Fatal error: Exception: PHP-FPM failed to start #870

ianrodrigues · 2021-02-25T03:15:50Z

ianrodrigues
Feb 25, 2021

I just built a simple demo app, using this command:

> docker build -t lamba-php-example .
Sending build context to Docker daemon  3.072kB
Step 1/5 : FROM bref/php-74-fpm
 ---> bbe3e727eeb7
Step 2/5 : COPY --from=composer /usr/bin/composer /usr/bin/composer
 ---> Using cache
 ---> 3248a2a297b7
Step 3/5 : RUN composer require bref/bref
 ---> Using cache
 ---> f7faa0d6bd70
Step 4/5 : ADD . $LAMBDA_TASK_ROOT
 ---> Using cache
 ---> c9e87343974d
Step 5/5 : CMD [ "index.php" ]
 ---> Using cache
 ---> dafd224a4527
Successfully built dafd224a4527
Successfully tagged lamba-php-example:latest

This is my Dockerfile:

FROM bref/php-74-fpm

COPY --from=composer /usr/bin/composer /usr/bin/composer

RUN composer require bref/bref

ADD . $LAMBDA_TASK_ROOT

CMD [ "index.php" ]

This is my index.php (I have only this file in my current directory):

<?php

echo "Hello World";

Then I start the container locally:

> docker run -p 9000:8080 lamba-php-example:latest
time="2021-02-25T03:10:52.774" level=info msg="exec '/var/runtime/bootstrap' (cwd=/var/task, handler=)"

After that, I try to run a curl command:

> curl "http://localhost:9000/2015-03-31/functions/function/invocations" -d '{}'

My container logs that:

> docker run -p 9000:8080 lamba-php-example:latest
time="2021-02-25T03:10:52.774" level=info msg="exec '/var/runtime/bootstrap' (cwd=/var/task, handler=)"
time="2021-02-25T03:11:05.992" level=info msg="extensionsDisabledByLayer(/opt/disable-extensions-jwigqn8j) -> stat /opt/disable-extensions-jwigqn8j: no such file or directory"
time="2021-02-25T03:11:05.992" level=warning msg="Cannot list external agents" error="open /opt/extensions: no such file or directory"
START RequestId: 91a55523-2eb6-44bb-89e2-8371083d44d9 Version: $LATEST
[25-Feb-2021 03:11:06] ALERT: [pool default] user has not been defined
[25-Feb-2021 03:11:06] ERROR: failed to post process the configuration
[25-Feb-2021 03:11:06] ERROR: FPM initialization failed
Error while starting PHP-FPM
Fatal error: Exception: PHP-FPM failed to start: 

[25-Feb-2021 03:11:06] ALERT: [pool default] user has not been defined
[25-Feb-2021 03:11:06] ERROR: failed to post process the configuration
[25-Feb-2021 03:11:06] ERROR: FPM initialization failed
 in /var/task/vendor/bref/bref/src/Event/Http/FpmHandler.php:168
Stack trace:
#0 /var/task/vendor/bref/bref/src/Event/Http/FpmHandler.php(88): Bref\Event\Http\FpmHandler->waitUntilReady()
#1 /var/runtime/bootstrap(37): Bref\Event\Http\FpmHandler->start()
#2 {main}time="2021-02-25T03:11:06.027" level=panic msg="ReplyStream not available"
2021/02/25 03:11:06 http: panic serving 127.0.0.1:51950: &{0xc00013c000 map[] 2021-02-25 03:11:06.027124049 +0000 UTC m=+13.253363948 panic <nil> ReplyStream not available <nil> <nil> }
goroutine 13 [running]:
net/http.(*conn).serve.func1(0xc00010ac80)
	/usr/local/go/src/net/http/server.go:1800 +0x139
panic(0x866640, 0xc00013c310)
	/usr/local/go/src/runtime/panic.go:975 +0x3e3
github.com/sirupsen/logrus.Entry.log(0xc00013c000, 0xc0000714d0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/go/pkg/mod/github.com/sirupsen/[email protected]/entry.go:259 +0x335
github.com/sirupsen/logrus.(*Entry).Log(0xc00013c0e0, 0xc000000000, 0xc00028f588, 0x1, 0x1)
	/go/pkg/mod/github.com/sirupsen/[email protected]/entry.go:287 +0xeb
github.com/sirupsen/logrus.(*Logger).Log(0xc00013c000, 0xc000000000, 0xc00028f588, 0x1, 0x1)
	/go/pkg/mod/github.com/sirupsen/[email protected]/logger.go:193 +0x7d
github.com/sirupsen/logrus.(*Logger).Panic(...)
	/go/pkg/mod/github.com/sirupsen/[email protected]/logger.go:234
github.com/sirupsen/logrus.Panic(...)
	/go/pkg/mod/github.com/sirupsen/[email protected]/exported.go:129
go.amzn.com/lambda/rapi/rendering.RenderInteropError(0x9097c0, 0xc0001621c0, 0xc00017e500, 0x902b60, 0xc0000591e0)
	/LambdaRuntimeLocal/lambda/rapi/rendering/rendering.go:292 +0x9a
go.amzn.com/lambda/rapi/handler.(*initErrorHandler).ServeHTTP(0xc00009c0a0, 0x9097c0, 0xc0001621c0, 0xc00017e500)
	/LambdaRuntimeLocal/lambda/rapi/handler/initerror.go:52 +0x519
net/http.HandlerFunc.ServeHTTP(0xc000094380, 0x9097c0, 0xc0001621c0, 0xc00017e500)
	/usr/local/go/src/net/http/server.go:2041 +0x44
github.com/go-chi/chi.(*Mux).routeHTTP(0xc0000a60c0, 0x9097c0, 0xc0001621c0, 0xc00017e500)
	/go/pkg/mod/github.com/go-chi/[email protected]+incompatible/mux.go:431 +0x278
net/http.HandlerFunc.ServeHTTP(0xc00009c030, 0x9097c0, 0xc0001621c0, 0xc00017e500)
	/usr/local/go/src/net/http/server.go:2041 +0x44
go.amzn.com/lambda/rapi/middleware.RuntimeReleaseMiddleware.func1.1(0x9097c0, 0xc0001621c0, 0xc00017e500)
	/LambdaRuntimeLocal/lambda/rapi/middleware/middleware.go:100 +0xea
net/http.HandlerFunc.ServeHTTP(0xc000094180, 0x9097c0, 0xc0001621c0, 0xc00017e500)
	/usr/local/go/src/net/http/server.go:2041 +0x44
go.amzn.com/lambda/rapi/middleware.AccessLogMiddleware.func1.1(0x9097c0, 0xc0001621c0, 0xc00017e500)
	/LambdaRuntimeLocal/lambda/rapi/middleware/middleware.go:77 +0x170
net/http.HandlerFunc.ServeHTTP(0xc0000941a0, 0x9097c0, 0xc0001621c0, 0xc00017e500)
	/usr/local/go/src/net/http/server.go:2041 +0x44
go.amzn.com/lambda/rapi/middleware.AppCtxMiddleware.func1.1(0x9097c0, 0xc0001621c0, 0xc00017e400)
	/LambdaRuntimeLocal/lambda/rapi/middleware/middleware.go:66 +0x77
net/http.HandlerFunc.ServeHTTP(0xc00009a2d0, 0x9097c0, 0xc0001621c0, 0xc00017e400)
	/usr/local/go/src/net/http/server.go:2041 +0x44
github.com/go-chi/chi.(*Mux).ServeHTTP(0xc0000a60c0, 0x9097c0, 0xc0001621c0, 0xc00017e400)
	/go/pkg/mod/github.com/go-chi/[email protected]+incompatible/mux.go:70 +0x513
github.com/go-chi/chi.(*Mux).Mount.func1(0x9097c0, 0xc0001621c0, 0xc00017e400)
	/go/pkg/mod/github.com/go-chi/[email protected]+incompatible/mux.go:298 +0x118
net/http.HandlerFunc.ServeHTTP(0xc0000943e0, 0x9097c0, 0xc0001621c0, 0xc00017e400)
	/usr/local/go/src/net/http/server.go:2041 +0x44
github.com/go-chi/chi.(*Mux).routeHTTP(0xc0000a6060, 0x9097c0, 0xc0001621c0, 0xc00017e400)
	/go/pkg/mod/github.com/go-chi/[email protected]+incompatible/mux.go:431 +0x278
net/http.HandlerFunc.ServeHTTP(0xc00009c0c0, 0x9097c0, 0xc0001621c0, 0xc00017e400)
	/usr/local/go/src/net/http/server.go:2041 +0x44
github.com/go-chi/chi.(*Mux).ServeHTTP(0xc0000a6060, 0x9097c0, 0xc0001621c0, 0xc00017e300)
	/go/pkg/mod/github.com/go-chi/[email protected]+incompatible/mux.go:86 +0x2b2
net/http.serverHandler.ServeHTTP(0xc0000ac000, 0x9097c0, 0xc0001621c0, 0xc00017e300)
	/usr/local/go/src/net/http/server.go:2836 +0xa3
net/http.(*conn).serve(0xc00010ac80, 0x90a800, 0xc000028700)
	/usr/local/go/src/net/http/server.go:1924 +0x86c
created by net/http.(*Server).Serve
	/usr/local/go/src/net/http/server.go:2962 +0x35c
PHP Fatal error:  Uncaught Exception: Error while calling the Lambda runtime API: Empty reply from server in /var/task/vendor/bref/bref/src/Runtime/LambdaRuntime.php:295
Stack trace:
#0 /var/task/vendor/bref/bref/src/Runtime/LambdaRuntime.php(257): Bref\Runtime\LambdaRuntime->postJson()
#1 /var/runtime/bootstrap(39): Bref\Runtime\LambdaRuntime->failInitialization()
#2 {main}
  thrown in /var/task/vendor/bref/bref/src/Runtime/LambdaRuntime.php on line 295

Fatal error: Uncaught Exception: Error while calling the Lambda runtime API: Empty reply from server in /var/task/vendor/bref/bref/src/Runtime/LambdaRuntime.php:295
Stack trace:
#0 /var/task/vendor/bref/bref/src/Runtime/LambdaRuntime.php(257): Bref\Runtime\LambdaRuntime->postJson()
#1 /var/runtime/bootstrap(39): Bref\Runtime\LambdaRuntime->failInitialization()
#2 {main}
  thrown in /var/task/vendor/bref/bref/src/Runtime/LambdaRuntime.php on line 295
time="2021-02-25T03:11:06.029" level=warning msg="First fatal error stored in appctx: Runtime.ExitError"
time="2021-02-25T03:11:06.029" level=warning msg="Process 14(bootstrap) exited: Runtime exited with error: exit status 255"
time="2021-02-25T03:11:06.029" level=error msg="Init failed" InvokeID= error="Runtime exited with error: exit status 255"
time="2021-02-25T03:11:06.029" level=warning msg="Failed to send default error response: ErrInvalidInvokeID"
time="2021-02-25T03:11:06.029" level=error msg="INIT DONE failed: Runtime.ExitError"
time="2021-02-25T03:11:06.029" level=warning msg="Reset initiated: ReserveFail"

Anyone have faced that issue?

ianrodrigues · 2021-02-25T03:17:32Z

ianrodrigues
Feb 25, 2021
Author

The first curl call returns nothing. If I give it another try, the second curl call returns that:

> curl "http://localhost:9000/2015-03-31/functions/function/invocations" -d '{}'

{"errorMessage":"Error while starting PHP-FPM PHP-FPM failed to start: \n\n[25-Feb-2021 03:14:41] ALERT: [pool default] user has not been defined\n[25-Feb-2021 03:14:41] ERROR: failed to post process the configuration\n[25-Feb-2021 03:14:41] ERROR: FPM initialization failed\n","errorType":"Exception","stackTrace":["#0 \/var\/task\/vendor\/bref\/bref\/src\/Event\/Http\/FpmHandler.php(88): Bref\\Event\\Http\\FpmHandler->waitUntilReady()","#1 \/var\/runtime\/bootstrap(37): Bref\\Event\\Http\\FpmHandler->start()","#2 {main}"]}

But third and subsequent calls return nothing.

0 replies

mnapoli · 2021-02-26T08:43:53Z

mnapoli
Feb 26, 2021
Maintainer

Just to be sure, could you make sure you're using all the latest versions (docker pull bref/php-74-fpm and composer update) ?

0 replies

ianrodrigues · 2021-02-26T11:36:55Z

ianrodrigues
Feb 26, 2021
Author

Both docker.io/bref/php-74-fpm:latest and bref/bref are up-to-date.

> docker pull bref/php-74-fpm
Using default tag: latest
latest: Pulling from bref/php-74-fpm
Digest: sha256:415a06ab3b3b1006838be79bbd88b28642e364f8c9bb48fd0f2f18e7248558ec
Status: Image is up to date for bref/php-74-fpm:latest
docker.io/bref/php-74-fpm:latest

- Locking bref/bref (1.2.0)

0 replies

mnapoli · 2021-02-26T15:49:49Z

mnapoli
Feb 26, 2021
Maintainer

I keep re-reading your post but I don't understand what you are trying to do. The Dockerfile is confusing to me, I'm not sure why you are running it locally and the port mapping makes no sense either. Also why are you trying to call the invocation API directly?

I do understand something is crashing in there, but I'd love to understand the context to help a bit better.

1 reply

ianrodrigues Feb 26, 2021
Author

Thanks for asking that, @mnapoli.

It is my first try running Container Images on Lambda and I just tried running it locally before sending it to AWS and, since it didn't work locally I didn't even try testing on Lambda.

I just tried there, and it worked. 🎉 I'm probably missing something to run it locally.

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Fatal error: Exception: PHP-FPM failed to start #870

{{title}}

Replies: 4 comments 1 reply

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

Select a reply

Fatal error: Exception: PHP-FPM failed to start #870

ianrodrigues Feb 25, 2021

Replies: 4 comments · 1 reply

ianrodrigues Feb 25, 2021 Author

mnapoli Feb 26, 2021 Maintainer

ianrodrigues Feb 26, 2021 Author

mnapoli Feb 26, 2021 Maintainer

ianrodrigues Feb 26, 2021 Author

ianrodrigues
Feb 25, 2021

Replies: 4 comments 1 reply

ianrodrigues
Feb 25, 2021
Author

mnapoli
Feb 26, 2021
Maintainer

ianrodrigues
Feb 26, 2021
Author

mnapoli
Feb 26, 2021
Maintainer

ianrodrigues Feb 26, 2021
Author