MMM-MQTT = Blank Screen

vinnie1

I hope someone can help, or give me pointers on what to do next, other than to give up.

What I am trying to do is use mqtt to send data to my MagicMirror from another computer to be displayed.

I am using the ottopaulsen/MMM-MQTT module.

The MagicMirror computer is also running Mosquitto broker which I have tested and is working fine., data being recieved and read correctly.

I have what I guess are errors from the following command.

from pm2 logs

/home/pi2/.pm2/logs/mm-error.log last 15 lines:
0|mm       |     '/home/pi2/MagicMirror/modules/MMM-MQTT/node_modules/mqtt/lib/topic-alias-send.js',
0|mm       |     '/home/pi2/MagicMirror/modules/MMM-MQTT/node_modules/mqtt/lib/client.js',
0|mm       |     '/home/pi2/MagicMirror/modules/MMM-MQTT/node_modules/mqtt/mqtt.js',
0|mm       |     '/home/pi2/MagicMirror/modules/MMM-MQTT/mqtt_helper.js',
0|mm       |     '/home/pi2/MagicMirror/modules/MMM-MQTT/node_helper.js',
0|mm       |     '/home/pi2/MagicMirror/js/app.js',
0|mm       |     '/home/pi2/MagicMirror/js/electron.js',
0|mm       |     '/home/pi2/MagicMirror/node_modules/electron/dist/resources/default_app.asar/main.js',
0|mm       |     undefined
0|mm       |   ]
0|mm       | }
0|mm       | [02.02.2023 13:35.38.989] [ERROR] MagicMirror² will not quit, but it might be a good idea to check why this happened. Maybe no internet connection?
0|mm       | [02.02.2023 13:35.38.990] [ERROR] If you think this really is an issue, please open an issue on GitHub: https://github.com/MichMich/MagicMirror/issues
0|mm       | [2288:0202/133544.021643:ERROR:viz_main_impl.cc(186)] Exiting GPU process due to errors during initialization
0|mm       | [2319:0202/133544.414082:ERROR:gpu_memory_buffer_support_x11.cc(44)] dri3 extension not supported.

I have no idea how to proceed from here.

When I start MagicMirror, I just end up with a completely blank screen, no modules, nothing working.

I have run

npm run config:check

That seems ok.

Please Help!

sdetweil

@vinnie1 could you provide more of the pm2 logs?
–lines=50

vinnie1

@sdetweil

Thanks for taking the time to have a look.

This is everything from the command pm2 logs

pi2@MagicMirror:~/MagicMirror/config $ pm2 logs
[TAILING] Tailing last 15 lines for [all] processes (change the value with --lines option)
/home/pi2/.pm2/pm2.log last 15 lines:
PM2        | 2023-02-06T07:38:59: PM2 log: pid=693 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-06T07:38:59: PM2 log: pid=693 msg=failed to kill - retrying in 100ms

PM2        | 2023-02-06T07:39:00: PM2 log: pid=693 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-06T07:39:00: PM2 log: pid=693 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-06T07:39:00: PM2 log: pid=693 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-06T07:39:00: PM2 log: pid=693 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-06T07:39:00: PM2 log: pid=693 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-06T07:39:00: PM2 log: pid=693 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-06T07:39:00: PM2 log: pid=693 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-06T07:39:00: PM2 log: pid=693 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-06T07:39:00: PM2 log: Process with pid 693 still alive after 1600ms, sending it SIGKILL now...
PM2        | 2023-02-06T07:39:03: PM2 log: App [mm:0] exited with code [0] via signal [SIGKILL]
PM2        | 2023-02-06T07:39:03: PM2 log: pid=693 msg=process killed
PM2        | 2023-02-06T07:40:15: PM2 log: App [mm:0] starting in -fork mode-
PM2        | 2023-02-06T07:40:15: PM2 log: App [mm:0] online

/home/pi2/.pm2/logs/mm-error.log last 15 lines:
0|mm       | [1318:0204/093618.954184:ERROR:gpu_memory_buffer_support_x11.cc(44)] dri3 extension not supported.
0|mm       | [1281:0204/093619.055108:ERROR:command_buffer_proxy_impl.cc(126)] ContextResult::kTransientFailure: Failed to send GpuControl.CreateCommandBuffer.
0|mm       | [1318:0205/050009.577343:ERROR:connection.cc(46)] X connection error received.
0|mm       | [1318:0205/050009.577492:ERROR:connection.cc(46)] X connection error received.
0|mm       | [1268:0205/050132.845932:ERROR:viz_main_impl.cc(186)] Exiting GPU process due to errors during initialization
0|mm       | [1321:0205/050133.251938:ERROR:gpu_memory_buffer_support_x11.cc(44)] dri3 extension not supported.
0|mm       | [1277:0205/050133.344683:ERROR:command_buffer_proxy_impl.cc(126)] ContextResult::kTransientFailure: Failed to send GpuControl.CreateCommandBuffer.
0|mm       | [1321:0206/050014.605843:ERROR:connection.cc(46)] X connection error received.
0|mm       | [1321:0206/050014.605850:ERROR:connection.cc(46)] X connection error received.
0|mm       | [1270:0206/050128.218984:ERROR:viz_main_impl.cc(186)] Exiting GPU process due to errors during initialization
0|mm       | [1323:0206/050128.620777:ERROR:gpu_memory_buffer_support_x11.cc(44)] dri3 extension not supported.
0|mm       | [1277:0206/050128.732856:ERROR:command_buffer_proxy_impl.cc(126)] ContextResult::kTransientFailure: Failed to send GpuControl.CreateCommandBuffer.
0|mm       | [2762:0206/074034.819745:ERROR:viz_main_impl.cc(186)] Exiting GPU process due to errors during initialization
0|mm       | [2805:0206/074035.474647:ERROR:gpu_memory_buffer_support_x11.cc(44)] dri3 extension not supported.
0|mm       | [2776:0206/074035.538693:ERROR:command_buffer_proxy_impl.cc(126)] ContextResult::kTransientFailure: Failed to send GpuControl.CreateCommandBuffer.

/home/pi2/.pm2/logs/mm-out.log last 15 lines:
0|mm       | [06.02.2023 07:40.28.058] [LOG]   Server started ...
0|mm       | [06.02.2023 07:40.28.063] [LOG]   Connecting socket for: updatenotification
0|mm       | [06.02.2023 07:40.28.065] [LOG]   Starting module helper: updatenotification
0|mm       | [06.02.2023 07:40.28.067] [LOG]   Connecting socket for: calendar
0|mm       | [06.02.2023 07:40.28.068] [LOG]   Starting node helper for: calendar
0|mm       | [06.02.2023 07:40.28.070] [LOG]   Connecting socket for: MMM-ImageSlideshow
0|mm       | [06.02.2023 07:40.28.072] [LOG]   Connecting socket for: MMM-MQTT
0|mm       | [06.02.2023 07:40.28.074] [LOG]   Connecting socket for: MMM-Jast
0|mm       | [06.02.2023 07:40.28.075] [LOG]   MMM-Jast helper method started...
0|mm       | [06.02.2023 07:40.28.077] [LOG]   Connecting socket for: newsfeed
0|mm       | [06.02.2023 07:40.28.078] [LOG]   Starting node helper for: newsfeed
0|mm       | [06.02.2023 07:40.28.080] [LOG]   Sockets connected & modules started ...
0|mm       | [06.02.2023 07:40.28.262] [LOG]   Launching application.
0|mm       | [06.02.2023 07:40.39.239] [LOG]   Create new calendarfetcher for url: http://www.calendarlabs.com/ical-calendar/ics/35/Australia_Holidays.ics - Interval: 300000
0|mm       | [06.02.2023 07:40.41.159] [INFO]  Calendar-Fetcher: Broadcasting 18 events.

And for more info here is the relevant section of my config.js

    {
                module: 'MMM-MQTT',
                        position: 'bottom_left',
                        header: 'MQTT',
                        config: {
                        logging: false,
                        useWildcards: false,
                        mqttServers: [
                                {
                                address: 'localhost',
                                port: '1883',
                                subscriptions: [
                                        {
                                        topic: 'sensor/data',
                                        label: 'Temp',
                                        broadcast: false,
                                        },
                                        {
                                        topic: 'MM/Accu/Lader2',
                                        label: 'Acculader 2',
                                        broadcast: false,
                                        },
                                        {
                                        topic: 'Energie/power_delivered',
                                        label: 'Verbruik',
                                        suffix: 'kWh',
                                        broadcast: false,
                                        }
                                ]
                                }
                                ],
                        }
                },

With or without the MQTT in config.js the logs look the same.
Maybe the error is in the config.js somewhere?

sdetweil

@vinnie1 still not enough

do

pm2 stop all
pm2 flush

pm2 start all

wait 30 seconds
ctrl-m to minimize mm
pm2 stop all

pm2 logs --lines=100

vinnie1

@sdetweil

ok, here is the output.

Just for information this is done over ssh.

pi2@MagicMirror:~/MagicMirror/config $ pm2 logs --lines=100
[TAILING] Tailing last 100 lines for [all] processes (change the value with --lines option)
/home/pi2/.pm2/pm2.log last 100 lines:
PM2        | 2023-02-09T10:48:02: PM2 log: App [mm:0] starting in -fork mode-
PM2        | 2023-02-09T10:48:02: PM2 log: App [mm:0] online
PM2        | 2023-02-09T10:50:12: PM2 log: Stopping app:mm id:0
PM2        | 2023-02-09T10:50:13: PM2 log: pid=1664 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-09T10:50:13: PM2 log: pid=1664 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-09T10:50:13: PM2 log: pid=1664 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-09T10:50:13: PM2 log: pid=1664 msg=failed to kill - retrying in 100ms
PM2        | 2023-02-09T10:50:13: PM2 log: App [mm:0] exited with code [0] via signal [SIGINT]
PM2        | 2023-02-09T10:50:13: PM2 log: pid=1664 msg=process killed

/home/pi2/.pm2/logs/mm-out.log last 100 lines:
0|mm       | 
0|mm       | > magicmirror@2.22.0 start
0|mm       | > DISPLAY="${DISPLAY:=:0}" ./node_modules/.bin/electron js/electron.js
0|mm       | 
0|mm       | [09.02.2023 10:48.09.593] [LOG]   Starting MagicMirror: v2.22.0
0|mm       | [09.02.2023 10:48.09.609] [LOG]   Loading config ...
0|mm       | [09.02.2023 10:48.09.626] [LOG]   Loading module helpers ...
0|mm       | [09.02.2023 10:48.09.632] [LOG]   No helper found for module: alert.
0|mm       | [09.02.2023 10:48.09.669] [LOG]   Initializing new module helper ...
0|mm       | [09.02.2023 10:48.09.672] [LOG]   Module helper loaded: updatenotification
0|mm       | [09.02.2023 10:48.09.675] [LOG]   No helper found for module: clock.
0|mm       | [09.02.2023 10:48.10.394] [LOG]   Initializing new module helper ...
0|mm       | [09.02.2023 10:48.10.399] [LOG]   Module helper loaded: calendar
0|mm       | [09.02.2023 10:48.10.411] [LOG]   No helper found for module: weather.
0|mm       | [09.02.2023 10:48.10.420] [LOG]   Initializing new module helper ...
0|mm       | [09.02.2023 10:48.10.421] [LOG]   Module helper loaded: MMM-ImageSlideshow
0|mm       | [09.02.2023 10:48.10.895] [LOG]   Initializing new module helper ...
0|mm       | [09.02.2023 10:48.10.896] [LOG]   Module helper loaded: MMM-MQTT
0|mm       | [09.02.2023 10:48.12.346] [LOG]   Initializing new module helper ...
0|mm       | [09.02.2023 10:48.12.348] [LOG]   Module helper loaded: MMM-Jast
0|mm       | [09.02.2023 10:48.12.453] [LOG]   Initializing new module helper ...
0|mm       | [09.02.2023 10:48.12.455] [LOG]   Module helper loaded: newsfeed
0|mm       | [09.02.2023 10:48.12.456] [LOG]   All module helpers loaded.
0|mm       | [09.02.2023 10:48.12.758] [LOG]   Starting server on port 8080 ... 
0|mm       | [09.02.2023 10:48.13.766] [LOG]   Server started ...
0|mm       | [09.02.2023 10:48.13.768] [LOG]   Connecting socket for: updatenotification
0|mm       | [09.02.2023 10:48.13.770] [LOG]   Starting module helper: updatenotification
0|mm       | [09.02.2023 10:48.13.772] [LOG]   Connecting socket for: calendar
0|mm       | [09.02.2023 10:48.13.773] [LOG]   Starting node helper for: calendar
0|mm       | [09.02.2023 10:48.13.779] [LOG]   Connecting socket for: MMM-ImageSlideshow
0|mm       | [09.02.2023 10:48.13.781] [LOG]   Connecting socket for: MMM-MQTT
0|mm       | [09.02.2023 10:48.13.783] [LOG]   Connecting socket for: MMM-Jast
0|mm       | [09.02.2023 10:48.13.785] [LOG]   MMM-Jast helper method started...
0|mm       | [09.02.2023 10:48.13.787] [LOG]   Connecting socket for: newsfeed
0|mm       | [09.02.2023 10:48.13.789] [LOG]   Starting node helper for: newsfeed
0|mm       | [09.02.2023 10:48.13.791] [LOG]   Sockets connected & modules started ...
0|mm       | [09.02.2023 10:48.14.076] [LOG]   Launching application.
0|mm       | [09.02.2023 10:48.22.293] [LOG]   Create new calendarfetcher for url: http://www.calendarlabs.com/ical-calendar/ics/35/Australia_Holidays.ics - Interval: 300000
0|mm       | [09.02.2023 10:48.24.266] [INFO]  Calendar-Fetcher: Broadcasting 18 events.

/home/pi2/.pm2/logs/mm-error.log last 100 lines:
0|mm       | [1719:0209/104818.604536:ERROR:viz_main_impl.cc(186)] Exiting GPU process due to errors during initialization
0|mm       | [1761:0209/104819.018207:ERROR:gpu_memory_buffer_support_x11.cc(44)] dri3 extension not supported.
0|mm       | [1732:0209/104819.110729:ERROR:command_buffer_proxy_impl.cc(126)] ContextResult::kTransientFailure: Failed to send GpuControl.CreateCommandBuffer.
0|mm       | /home/pi2/MagicMirror/node_modules/electron/dist/electron exited with signal SIGINT

sdetweil

@vinnie1 see this for steps to turn off gpu

https://forum.magicmirror.builders/topic/16409/mmm-flights/16?_=1675905532490

vinnie1

@sdetweil

Still get the errors

/home/pi2/.pm2/logs/mm-error.log last 100 lines:
0|mm       | [2768:0211/074735.631766:ERROR:viz_main_impl.cc(186)] Exiting GPU process due to errors during initialization
0|mm       | [2811:0211/074736.362425:ERROR:gpu_memory_buffer_support_x11.cc(44)] dri3 extension not supported.
0|mm       | [2782:0211/074736.436519:ERROR:command_buffer_proxy_impl.cc(126)] ContextResult::kTransientFailure: Failed to send GpuControl.CreateCommandBuffer.

sdetweil

@vinnie1 did u add the
export ELECTRON_DISABLE_GPU=1

to the correct file

vinnie1

@sdetweil

yes

/MagicMirror/installers/mm.sh

I also have a mm.sh in my home directory (not sure why), I put it in there as well.

sdetweil

@vinnie1 to figure out which script needs updating use pm2 commands

pm2 status

will show you all the apps it is managing

for each, using the number or name do

pm2 info x

where x is the number or name
for example
pm2 info 0
or
pm2 info MagicMirror

it will tell you lots of things, but one is the name of the script used to launch the app