An error occurred while running command ….

hi,
Application and environment details:
Product: OroCommerce Version:1.5
Operating system : Ubuntu 16.04 Server (64 bits)
Web server name : Apache 2.4.7
Database server : MySQL 5.5.57
PHP version: 7.1

i have this error in log file :

Shell

[2017-12-10 21:38:06] console.ERROR: An error occurred while running command "--env=prod --no-debug 'oro:message-queue:consume'". Call to a member function getConnection() on null {"exit_code":0,"arguments":{"command":"oro:message-queue:consume"},"options":{"env":"prod","no-debug":true}} [] [2017-12-10 21:38:07] app.CRITICAL: Orphans were found and redelivered. consumerIds: "5a2d9b1d7f54f9.21472267" [] {"extension":"Oro\\Component\\MessageQueue\\Consumption\\Dbal\\Extension\\RedeliverOrphanMessagesDbalExtension"}

1 2

[2017-12-10 21:38:06] console.ERROR: An error occurred while running command "--env=prod --no-debug 'oro:message-queue:consume'". Call to a member function getConnection() on null {"exit_code":0,"arguments":{"command":"oro:message-queue:consume"},"options":{"env":"prod","no-debug":true}} [] [2017-12-10 21:38:07] app.CRITICAL: Orphans were found and redelivered. consumerIds: "5a2d9b1d7f54f9.21472267" [] {"extension":"Oro\\Component\\MessageQueue\\Consumption\\Dbal\\Extension\\RedeliverOrphanMessagesDbalExtension"}

thank you.

Topic

[msalmi.kaisParticipant]

hi,
any idea ?

[mmiasnikovParticipant]

Hello, Msalmi.kais.

As I can see from the error log, for some reason the DB connection was lost in one particular running process of ‘oro:message-queue:consumer’ and, as result, the process has exited.

As result, another running ‘oro:message-queue:consumer’ process found unfinished tasks (Orphans) – a legacy from exited colleague – and add these tasks again to the queue.

That is the situation, where we can see pros of architecture, that uses message queue – despite the problem with one process, all jobs, probably, had been done successfully.

If the issue with lost connection in ‘oro:message-queue:consume’ process will appear again – please, let us know in this thread – we will take a more detailed investigation.

Thank you.

[msalmi.kaisParticipant]

thank you

[Mauro ChojrinParticipant]

Hi:

I’m getting a lot of critical messages like this.

I haven’t changed anything to the server and I’ve been getting this alerts for more than an hour… just restarted supervisord but it looks just the same.

I checked my mysql and it’s up and running…

What I did found in the logs is a few of this:

Shell

2019-11-28T15:03:18.908790Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 4293ms. The settings might not be optimal. (flushed=6 and evicted=0, during the time.)

1	2019-11-28T15:03:18.908790Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 4293ms. The settings might not be optimal. (flushed=6 and evicted=0, during the time.)

I checked https://stackoverflow.com/questions/41134785/how-to-solve-mysql-warning-innodb-page-cleaner-1000ms-intended-loop-took-xxx and it says that it can be normal, but I never saw it happening before…

Any ideas will be greatly appreciated, thanks!

[Author]

Replies