Hi everyone!
My lighting system is the Lightberry HD/4K version with Apa102 leds. Last week I needed to reinstall my Raspberry 3B+. (And I'm a complete noob at Linux.) Decided I might as well try Hyperion NG instead of the old version while I'm at it. So... After installing Raspberry OS (Buster), I downloaded/installed Hyperion 2.0.0-alpha.9 from Github.
Pi boots up. Leds show red, green, blue. But no rainbow swirl. I can reach the (very convenient !!) web UI.
However the leds won't light. Trying to turn them on with the hyperionremote app on my phone, the app shows "No server connection" and "web socket" errors. I'm thinking everything must be running as I can see green lights for component status on the web UI.
Can somebody please help me diagnose? I've added the log text from the web UI. Maybe that can help?
Thanks in advance!!
2021-01-15T15:31:28.803Z [hyperiond DAEMON] (INFO) CEC handler created 2021-01-15T15:31:28.843Z [hyperiond EFFECTFILES] (INFO) 39 effects loaded from directory :/effects/ 2021-01-15T15:31:28.850Z [hyperiond EFFECTFILES] (INFO) 22 effect schemas loaded from directory :/effects/schema/ 2021-01-15T15:31:28.853Z [hyperiond EFFECTFILES] (INFO) 0 effects loaded from directory /home/pi/.hyperion/custom-effects 2021-01-15T15:31:28.906Z [hyperiond SETTINGSMGR] (DEBUG) (SettingsManager.cpp:109:SettingsManager()) Settings database initialized 2021-01-15T15:31:28.909Z [hyperiond BLACKBORDER] (DEBUG) (BlackBorderProcessor.cpp:64:handleSettingsUpdate()) Set mode to: default 2021-01-15T15:31:28.909Z [hyperiond COMPONENTREG] (DEBUG) (ComponentRegister.cpp:36:setNewComponentState()) Blackborder detector: enabled 2021-01-15T15:31:29.009Z [hyperiond DAEMON] (INFO) set screen capture device to 'dispmanx' 2021-01-15T15:31:29.009Z [hyperiond DISPMANXGRABBER] (DEBUG) (Grabber.cpp:30:setVideoMode()) Set videomode to 0 2021-01-15T15:31:29.011Z [hyperiond DISPMANXGRABBER] (INFO) Display opened with resolution: 1920x1080 2021-01-15T15:31:29.011Z [hyperiond DISPMANXGRABBER] (DEBUG) (Grabber.cpp:90:setWidthHeight()) Set new width: 80, height: 45 for capture 2021-01-15T15:31:29.012Z [hyperiond DAEMON] (INFO) DISPMANX frame grabber created 2021-01-15T15:31:29.013Z [hyperiond V4L2:AUTO] (DEBUG) (Grabber.cpp:30:setVideoMode()) Set videomode to 0 2021-01-15T15:31:29.015Z [hyperiond V4L2:AUTO] (INFO) Signal threshold set to: {12, 12, 12} 2021-01-15T15:31:29.015Z [hyperiond V4L2:AUTO] (INFO) Cropping image: width=0 height=0; crop: left=45 right=42 top=18 bottom=36 2021-01-15T15:31:29.016Z [hyperiond V4L2:AUTO] (INFO) CEC detection is now disabled 2021-01-15T15:31:29.016Z [hyperiond V4L2:AUTO] (INFO) Signal detection is now disabled 2021-01-15T15:31:29.017Z [hyperiond V4L2:AUTO] (INFO) Signal detection area set to: 0.250000,0.250000 x 0.750000,0.750000 2021-01-15T15:31:29.017Z [hyperiond DAEMON] (DEBUG) (hyperiond.cpp:630:handleSettingsUpdate()) V4L2 grabber created 2021-01-15T15:31:29.035Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:28:JsonServer()) Created instance 2021-01-15T15:31:29.058Z [hyperiond JSONSERVER] (INFO) Started on port 19444 2021-01-15T15:31:29.022Z [hyperiond COMPONENTREG] (DEBUG) (ComponentRegister.cpp:36:setNewComponentState()) Smoothing: enabled 2021-01-15T15:31:29.022Z [hyperiond SMOOTHING] (DEBUG) (LinearColorSmoothing.cpp:701:selectConfig()) cfg [0]: Type: linear - Time: 200 ms, outputRate 25.000000 Hz, interpolationRate: 25.000000 Hz, timer: 25 ms, Dithering: 0, Decay: 1.000000 -> HalfTime: 100.000000 ms 2021-01-15T15:31:29.023Z [hyperiond LEDDEVICE] (INFO) Start LedDevice 'adalight'. 2021-01-15T15:31:29.024Z [hyperiond LEDDEVICE] (DEBUG) (LedDevice.cpp:148:init()) deviceConfig: [{"colorOrder":"bgr","currentLedCount":108,"delayAfterConnect":0,"hardwareLedCount":108,"latchTime":30,"lightberry_apa102_mode":true,"output":"auto","rate":115200,"rewriteTime":1000,"type":"adalight"}] 2021-01-15T15:31:29.024Z [hyperiond LEDDEVICE] (DEBUG) (LedDevice.cpp:407:setLatchTime()) LatchTime updated to 30ms 2021-01-15T15:31:29.024Z [hyperiond LEDDEVICE] (DEBUG) (LedDevice.cpp:428:setRewriteTime()) Refresh interval = 1000ms 2021-01-15T15:31:29.024Z [hyperiond LEDDEVICE] (DEBUG) (LedDevice.cpp:434:setRewriteTime()) RewriteTime updated to 1000ms 2021-01-15T15:31:29.025Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:36:init()) DeviceType : adalight 2021-01-15T15:31:29.025Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:37:init()) LedCount : 108 2021-01-15T15:31:29.025Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:38:init()) ColorOrder : bgr 2021-01-15T15:31:29.025Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:39:init()) RefreshTime : 1000 2021-01-15T15:31:29.025Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:40:init()) LatchTime : 30 2021-01-15T15:31:29.025Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:52:init()) deviceName : auto 2021-01-15T15:31:29.026Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:53:init()) AutoDevice : 1 2021-01-15T15:31:29.026Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:54:init()) baudRate_Hz : 115200 2021-01-15T15:31:29.026Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:55:init()) delayAfCon ms: 0 2021-01-15T15:31:29.027Z [hyperiond LEDDEVICE] (DEBUG) (LedDeviceAdalight.cpp:44:init()) Adalight driver with activated LightBerry APA102 mode 2021-01-15T15:31:29.027Z [hyperiond LEDDEVICE] (DEBUG) (LedDeviceAdalight.cpp:59:init()) Adalight header for 108 leds: Ada 0x00 0x6c 0x39 2021-01-15T15:31:29.035Z [hyperiond SMOOTHING] (DEBUG) (LinearColorSmoothing.cpp:701:selectConfig()) Previous line repeats 1 times 2021-01-15T15:31:29.035Z [hyperiond FLATBUFCONN] (INFO) Connecting to Hyperion: 127.0.0.1:19401 2021-01-15T15:31:29.060Z [hyperiond EFFECTENGINE] (INFO) Run effect "Rainbow swirl fast" on channel 0 2021-01-15T15:31:29.072Z [hyperiond FLATBUFSERVER] (INFO) Started on port 19400 2021-01-15T15:31:29.073Z [hyperiond PROTOSERVER] (INFO) Started on port 19445 2021-01-15T15:31:29.074Z [hyperiond WEBSERVER] (DEBUG) (WebServer.cpp:33:initServer()) Initialize Webserver 2021-01-15T15:31:29.081Z [hyperiond WEBSERVER] (DEBUG) (WebServer.cpp:33:initServer()) Initialize Webserver 2021-01-15T15:31:29.088Z [hyperiond LEDDEVICE] (INFO) found serial device: ttyAMA0 2021-01-15T15:31:29.089Z [hyperiond LEDDEVICE] (INFO) Opening UART: ttyAMA0 2021-01-15T15:31:29.089Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:141:tryOpen()) _rs232Port.open(QIODevice::ReadWrite): ttyAMA0, Baud rate [115200]bps 2021-01-15T15:31:29.146Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:146:tryOpen()) portName: ttyAMA0 2021-01-15T15:31:29.146Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:147:tryOpen()) systemLocation: /dev/ttyAMA0 2021-01-15T15:31:29.146Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:148:tryOpen()) description: 2021-01-15T15:31:29.147Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:149:tryOpen()) manufacturer: 2021-01-15T15:31:29.147Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:150:tryOpen()) productIdentifier: 0x0 2021-01-15T15:31:29.147Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:151:tryOpen()) vendorIdentifier: 0x0 2021-01-15T15:31:29.147Z [hyperiond LEDDEVICE] (DEBUG) (ProviderRs232.cpp:152:tryOpen()) serialNumber: 2021-01-15T15:31:29.209Z [hyperiond EFFECTENGINE] (DEBUG) (EffectEngine.cpp:183:runEffectScript()) Start the effect: name [Rainbow swirl fast], smoothCfg [2] 2021-01-15T15:31:29.209Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:161:registerInput()) Register new input 'System/EFFECT' with priority 0 as inactive 2021-01-15T15:31:29.209Z [hyperiond HYPERION] (INFO) Initial foreground effect 'Rainbow swirl fast' started 2021-01-15T15:31:29.210Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:161:registerInput()) Register new input 'System/V4L' with priority 240 as inactive 2021-01-15T15:31:29.211Z [hyperiond COMPONENTREG] (DEBUG) (ComponentRegister.cpp:36:setNewComponentState()) V4L capture device: enabled 2021-01-15T15:31:29.217Z [hyperiond V4L2:AUTO] (INFO) available V4L2 devices: /dev/video0 usbtv /dev/video14 bcm2835-isp-capture0 /dev/video15 bcm2835-isp-capture1 2021-01-15T15:31:29.218Z [hyperiond V4L2:AUTO] (INFO) search for usable video devices 2021-01-15T15:31:29.220Z [hyperiond V4L2:AUTO] (INFO) test v4l device: /dev/video0 2021-01-15T15:31:29.221Z [hyperiond V4L2:AUTO] (DEBUG) (V4L2Grabber.cpp:696:init_device()) Set resolution to width=720 height=480 2021-01-15T15:31:29.221Z [hyperiond V4L2:AUTO] (DEBUG) (V4L2Grabber.cpp:735:init_device()) Pixel format=YUYV 2021-01-15T15:31:29.226Z [hyperiond V4L2:AUTO] (INFO) found usable v4l2 device: /dev/video0 (usbtv) 2021-01-15T15:31:29.271Z [hyperiond V4L2:AUTO] (INFO) Started 2021-01-15T15:31:29.272Z [hyperiond V4L2:auto] (DEBUG) (GrabberWrapper.cpp:48:start()) Grabber start() 2021-01-15T15:31:29.211Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:161:registerInput()) Register new input 'System/GRABBER' with priority 250 as inactive 2021-01-15T15:31:29.211Z [hyperiond COMPONENTREG] (DEBUG) (ComponentRegister.cpp:36:setNewComponentState()) Framegrabber: enabled 2021-01-15T15:31:29.273Z [hyperiond Dispmanx] (DEBUG) (GrabberWrapper.cpp:48:start()) Grabber start() 2021-01-15T15:31:29.212Z [hyperiond BOBLIGHT] (DEBUG) (BoblightServer.cpp:28:BoblightServer()) Instance created 2021-01-15T15:31:29.274Z [hyperiond HYPERION] (INFO) Hyperion instance 'TV woonkamer' has been started 2021-01-15T15:31:29.212Z [hyperiond COMPONENTREG] (DEBUG) (ComponentRegister.cpp:36:setNewComponentState()) LED device: enabled 2021-01-15T15:31:29.334Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:251:setInputImage()) Priority 240 is now active 2021-01-15T15:31:29.335Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:351:setCurrentTime()) Set visible priority to 0 2021-01-15T15:31:29.335Z [hyperiond SMOOTHING] (DEBUG) (LinearColorSmoothing.cpp:701:selectConfig()) cfg [2]: Type: linear - Time: 200 ms, outputRate 25.000000 Hz, interpolationRate: 25.000000 Hz, timer: 40 ms, Dithering: 0, Decay: 1.000000 -> HalfTime: 100.000000 ms 2021-01-15T15:31:29.335Z [hyperiond HYPERION] (DEBUG) (Hyperion.cpp:559:handlePriorityChangedLedDevice()) priority[0], previousPriority[255] 2021-01-15T15:31:29.335Z [hyperiond HYPERION] (DEBUG) (Hyperion.cpp:569:handlePriorityChangedLedDevice()) new source available -> switch LED-Device on 2021-01-15T15:31:29.351Z [hyperiond WEBSERVER] (DEBUG) (WebServer.cpp:91:handleSettingsUpdate()) Apply Webserver settings 2021-01-15T15:31:29.351Z [hyperiond WEBSERVER] (DEBUG) (WebServer.cpp:91:handleSettingsUpdate()) Apply Webserver settings 2021-01-15T15:31:29.352Z [hyperiond WEBSERVER] (DEBUG) (WebServer.cpp:109:handleSettingsUpdate()) Set document root to: :/webconfig 2021-01-15T15:31:29.352Z [hyperiond WEBSERVER] (DEBUG) (WebServer.cpp:109:handleSettingsUpdate()) Set document root to: :/webconfig 2021-01-15T15:31:29.352Z [hyperiond WEBSERVER] (INFO) Started on port 8090 name 'Hyperion Webserver' 2021-01-15T15:31:29.380Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:251:setInputImage()) Priority 250 is now active 2021-01-15T15:31:29.402Z [hyperiond WEBSERVER] (DEBUG) (WebServer.cpp:175:handleSettingsUpdate()) Setup SSL certificate 2021-01-15T15:31:29.403Z [hyperiond WEBSERVER] (DEBUG) (WebServer.cpp:191:handleSettingsUpdate()) Setup private SSL key 2021-01-15T15:31:29.404Z [hyperiond WEBSERVER] (INFO) Started on port 8092 name 'Hyperion Webserver' 2021-01-15T15:31:29.497Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:251:setInputImage()) Priority 0 is now active 2021-01-15T15:31:32.486Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:315:setCurrentTime()) Timeout clear for priority 0 2021-01-15T15:31:32.487Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:351:setCurrentTime()) Set visible priority to 0 2021-01-15T15:31:32.487Z [hyperiond SMOOTHING] (DEBUG) (LinearColorSmoothing.cpp:701:selectConfig()) cfg [0]: Type: linear - Time: 200 ms, outputRate 25.000000 Hz, interpolationRate: 25.000000 Hz, timer: 25 ms, Dithering: 0, Decay: 1.000000 -> HalfTime: 100.000000 ms 2021-01-15T15:31:32.487Z [hyperiond HYPERION] (DEBUG) (Hyperion.cpp:559:handlePriorityChangedLedDevice()) priority[0], previousPriority[0] 2021-01-15T15:31:32.736Z [hyperiond HYPERION] (DEBUG) (PriorityMuxer.cpp:351:setCurrentTime()) Set visible priority to 240 2021-01-15T15:31:32.738Z [hyperiond HYPERION] (DEBUG) (Hyperion.cpp:559:handlePriorityChangedLedDevice()) priority[240], previousPriority[0] 2021-01-15T15:32:06.040Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:105:newConnection()) New connection from: ::ffff:192.168.1.10 2021-01-15T15:32:06.041Z [hyperiond JSONCLIENTCONNECTION] (DEBUG) (JsonAPI.cpp:81:handleInstanceSwitch()) Client '::ffff:192.168.1.27' switch to Hyperion instance 0 2021-01-15T15:32:06.041Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 0, Column: 1 2021-01-15T15:32:06.043Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Previous line repeats 10 times 2021-01-15T15:32:06.043Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 1, Column: 0 2021-01-15T15:32:06.062Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:121:closedConnection()) Connection closed 2021-01-15T15:32:07.186Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:105:newConnection()) New connection from: ::ffff:192.168.1.10 2021-01-15T15:32:07.189Z [hyperiond JSONCLIENTCONNECTION] (DEBUG) (JsonAPI.cpp:81:handleInstanceSwitch()) Client '::ffff:192.168.1.27' switch to Hyperion instance 0 2021-01-15T15:32:07.190Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 0, Column: 1 2021-01-15T15:32:07.192Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Previous line repeats 10 times 2021-01-15T15:32:07.192Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 1, Column: 0 2021-01-15T15:32:07.198Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:121:closedConnection()) Connection closed 2021-01-15T15:32:07.239Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:105:newConnection()) New connection from: ::ffff:192.168.1.10 2021-01-15T15:32:07.240Z [hyperiond JSONCLIENTCONNECTION] (DEBUG) (JsonAPI.cpp:81:handleInstanceSwitch()) Client '::ffff:192.168.1.27' switch to Hyperion instance 0 2021-01-15T15:32:07.241Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 0, Column: 1 2021-01-15T15:32:07.242Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Previous line repeats 10 times 2021-01-15T15:32:07.242Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 1, Column: 0 2021-01-15T15:32:07.250Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:121:closedConnection()) Connection closed 2021-01-15T15:32:07.255Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:105:newConnection()) New connection from: ::ffff:192.168.1.10 2021-01-15T15:32:07.255Z [hyperiond JSONCLIENTCONNECTION] (DEBUG) (JsonAPI.cpp:81:handleInstanceSwitch()) Client '::ffff:192.168.1.27' switch to Hyperion instance 0 2021-01-15T15:32:07.256Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 0, Column: 1 2021-01-15T15:32:07.258Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Previous line repeats 10 times 2021-01-15T15:32:07.258Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 1, Column: 0 2021-01-15T15:32:07.268Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:121:closedConnection()) Connection closed 2021-01-15T15:32:07.297Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:105:newConnection()) New connection from: ::ffff:192.168.1.10 2021-01-15T15:32:07.298Z [hyperiond JSONCLIENTCONNECTION] (DEBUG) (JsonAPI.cpp:81:handleInstanceSwitch()) Client '::ffff:192.168.1.27' switch to Hyperion instance 0 2021-01-15T15:32:07.302Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 0, Column: 1 2021-01-15T15:32:07.305Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Previous line repeats 10 times 2021-01-15T15:32:07.305Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 1, Column: 0 2021-01-15T15:32:07.321Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:121:closedConnection()) Connection closed 2021-01-15T15:32:07.323Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:105:newConnection()) New connection from: ::ffff:192.168.1.10 2021-01-15T15:32:07.324Z [hyperiond JSONCLIENTCONNECTION] (DEBUG) (JsonAPI.cpp:81:handleInstanceSwitch()) Client '::ffff:192.168.1.27' switch to Hyperion instance 0 2021-01-15T15:32:07.325Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 0, Column: 1 2021-01-15T15:32:07.326Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Previous line repeats 10 times 2021-01-15T15:32:07.326Z [hyperiond JSONCLIENTCONNECTION] (ERROR) Failed to parse json data from JsonRpc@::ffff:192.168.1.27: Error: illegal value at Line: 1, Column: 0 2021-01-15T15:32:07.334Z [hyperiond JSONSERVER] (DEBUG) (JsonServer.cpp:121:closedConnection()) Connection closed 2021-01-15T15:34:12.267Z [hyperiond WEBSOCKET] (DEBUG) (WebSocketClient.cpp:30:WebSocketClient()) New connection from ::ffff:192.168.1.11 2021-01-15T15:34:12.268Z [hyperiond WEBSOCKET] (DEBUG) (JsonAPI.cpp:81:handleInstanceSwitch()) Client '::ffff:192.168.1.11' switch to Hyperion instance 0 2021-01-15T15:34:19.788Z [hyperiond WEBSOCKET] (DEBUG) (JsonAPI.cpp:1054:handleLoggingCommand()) log streaming activated for client ::ffff:192.168.1.11