php-fpm and mail services both fail consistenly

[blackdoginet]

See log file excerpts
logs.zip

2016-03-24 12:54:57,427 INFO supervisord started with pid 1
2016-03-24 12:54:58,430 INFO spawned: 'startup' with pid 11
2016-03-24 12:54:58,433 INFO spawned: 'mail' with pid 12
2016-03-24 12:54:58,436 INFO spawned: 'php5-fpm' with pid 13
2016-03-24 12:54:58,444 INFO spawned: 'nginx' with pid 14
2016-03-24 12:54:58,450 INFO exited: mail (exit status 0; not expected)
2016-03-24 12:54:58,464 INFO exited: startup (exit status 0; not expected)
2016-03-24 12:54:58,571 INFO exited: php5-fpm (exit status 0; not expected)
2016-03-24 12:54:59,575 INFO spawned: 'startup' with pid 45
2016-03-24 12:54:59,578 INFO spawned: 'mail' with pid 46
2016-03-24 12:54:59,581 INFO spawned: 'php5-fpm' with pid 47
2016-03-24 12:54:59,582 INFO success: nginx entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2016-03-24 12:54:59,593 INFO exited: mail (exit status 0; not expected)
2016-03-24 12:54:59,603 INFO exited: startup (exit status 0; not expected)
2016-03-24 12:54:59,692 INFO exited: php5-fpm (exit status 0; not expected)
2016-03-24 12:55:01,697 INFO spawned: 'startup' with pid 75
2016-03-24 12:55:01,699 INFO spawned: 'mail' with pid 76
2016-03-24 12:55:01,701 INFO spawned: 'php5-fpm' with pid 77
2016-03-24 12:55:01,712 INFO exited: mail (exit status 0; not expected)
2016-03-24 12:55:01,719 INFO exited: startup (exit status 0; not expected)
2016-03-24 12:55:01,807 INFO exited: php5-fpm (exit status 0; not expected)
2016-03-24 12:55:04,813 INFO spawned: 'startup' with pid 105
2016-03-24 12:55:04,817 INFO spawned: 'mail' with pid 106
2016-03-24 12:55:04,821 INFO spawned: 'php5-fpm' with pid 107
2016-03-24 12:55:04,830 INFO exited: mail (exit status 0; not expected)
2016-03-24 12:55:04,831 INFO gave up: mail entered FATAL state, too many start retries too quickly
2016-03-24 12:55:04,843 INFO exited: startup (exit status 0; not expected)
2016-03-24 12:55:04,928 INFO gave up: startup entered FATAL state, too many start retries too quickly
2016-03-24 12:55:04,928 INFO exited: php5-fpm (exit status 0; not expected)
2016-03-24 12:55:05,930 INFO gave up: php5-fpm entered FATAL state, too many start retries too quickly

above from supervisord.log

Configuring Nginx and PHP5-FPM with environment variables
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_ENV_MYSQL_PASSWORD}
Adding variable {HOSTNAME}
Adding variable {MYSQL_ENV_MYSQL_DATABASE}
Adding variable {MYSQL_1_PORT_3306_TCP}
Adding variable {MYSQL_ENV_MYSQL_ROOT_PASSWORD}
Adding variable {MYSQL_1_ENV_MYSQL_USER}
Adding variable {BASE_URL}
Adding variable {MYSQL_ENV_MARIADB_VERSION}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_ENV_MYSQL_ROOT_PASSWORD}
Adding variable {DRUPAL_ENVIRONMENT}
Adding variable {MYSQL_1_PORT_3306_TCP_PROTO}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_ENV_MARIADB_VERSION}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_PORT_3306_TCP_PROTO}
Adding variable {LC_ALL}
Adding variable {MYSQL_1_PORT_3306_TCP_PORT}
Adding variable {SMTP_PORT}
Adding variable {SMTP_USERNAME}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_ENV_MYSQL_USER}
Adding variable {MYSQL_PORT_3306_TCP_PORT}
Adding variable {SMTP_PASSWORD}
Adding variable {MYSQL_ENV_MARIADB_MAJOR}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_PORT_3306_TCP_ADDR}
Adding variable {MYSQL_PORT_3306_TCP}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_PORT_3306_TCP}
Adding variable {MYSQL_1_ENV_MYSQL_ROOT_PASSWORD}
Adding variable {PATH}
Adding variable {MYSQL_ENV_MYSQL_USER}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_NAME}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_PORT}
Adding variable {MYSQL_1_ENV_MARIADB_VERSION}
Adding variable {SUPERVISOR_GROUP_NAME}
Adding variable {PWD}
Adding variable {SMTP_FROMNAME}
Adding variable {LANG}
Adding variable {SMTP_HOST}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_PORT_3306_TCP_PORT}
Adding variable {MYSQL_ENV_MYSQL_PASSWORD}
Adding variable {MYSQL_1_ENV_MYSQL_DATABASE}
Adding variable {SUPERVISOR_ENABLED}
Adding variable {SHLVL}
Adding variable {HOME}
Adding variable {MYSQL_NAME}
Adding variable {MYSQL_PORT_3306_TCP_PROTO}
Adding variable {MYSQL_1_ENV_MARIADB_MAJOR}
Adding variable {MYSQL_PORT_3306_TCP_ADDR}
Adding variable {MYSQL_1_PORT}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_ENV_MARIADB_MAJOR}
Adding variable {SUPERVISOR_PROCESS_NAME}
Adding variable {DEBIAN_FRONTEND}
Adding variable {SUPERVISOR_SERVER_URL}
Adding variable {MYSQL_1_NAME}
Adding variable {MYSQL_1_PORT_3306_TCP_ADDR}
Adding variable {MYSQL_PORT}
Adding variable {DOCKERNGINXDRUPAL_MYSQL_1_ENV_MYSQL_DATABASE}
Adding variable {MYSQL_1_ENV_MYSQL_PASSWORD}
Adding variable {_}
Configuring /etc/hosts

From startup.log
Any ideas anyone ??

[luiselizondo]

Check your php and system logs, you might find something interesting there.

[luiselizondo]

I've been tracking this problem and apparently is because of this:

With newer versions, docker started adding something like this:

MYSQL_1_ENV_affinity:container==7c9320f488cb47242157e41a10476e0a59d4f14d849ce91914de09ca47379172

Which to be honest I don't know what is, but the problem is that when the startup script populates the /etc/php5/fpm/pool.d/env.conf file it doesn't parse those variables and they end up with an empty value, and then php5-fpm can't start.

[luiselizondo]

Issue #1 I think is related to this one.