-- Logs begin at Mon 2024-08-26 16:56:08 UTC, end at Tue 2024-09-03 10:28:38 UTC. -- Sep 03 10:27:00 streambox raatdaemon[1501]: [0005231] [t1966077024] 1921.348 TRACE [transport/volumio] got update status Sep 03 10:27:01 streambox raatdaemon[1501]: [0005232] [t1966077024] 1922.352 TRACE [transport/volumio] got update status Sep 03 10:27:02 streambox raatdaemon[1501]: [0005233] [t1966077024] 1923.354 TRACE [transport/volumio] got update status Sep 03 10:27:02 streambox raatdaemon[1501]: [0005234] [t1992959072] 1923.505 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:27:02 streambox raatdaemon[1501]: [0005235] [t1992959072] 1923.505 TRACE [discovery] stopping Sep 03 10:27:02 streambox raatdaemon[1501]: [0005236] [t1992959072] 1923.505 TRACE closing multicast Sep 03 10:27:02 streambox raatdaemon[1501]: [0005237] [t1992959072] 1923.505 TRACE [discovery] closing unicast send socket Sep 03 10:27:02 streambox raatdaemon[1501]: [0005238] [t1992959072] 1923.505 TRACE [discovery] closing unicast recv socket Sep 03 10:27:02 streambox raatdaemon[1501]: [0005239] [t1992959072] 1923.505 TRACE [discovery] starting Sep 03 10:27:02 streambox raatdaemon[1501]: [0005240] [t1992959072] 1923.505 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:02 streambox raatdaemon[1501]: [0005241] [t1992959072] 1923.505 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:55921 Sep 03 10:27:02 streambox raatdaemon[1501]: [0005242] [t1992959072] 1923.505 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:02 streambox raatdaemon[1501]: [0005243] [t1992959072] 1923.505 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:60809 Sep 03 10:27:02 streambox raatdaemon[1501]: [0005244] [t1992959072] 1923.505 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:27:02 streambox raatdaemon[1501]: [0005245] [t1992959072] 1923.506 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=D26E9DDB-DEE9-7BA0-CD6C-0008723D6979, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:27:03 streambox raatdaemon[1501]: [0005246] [t1966077024] 1924.356 TRACE [transport/volumio] got update status Sep 03 10:27:04 streambox raatdaemon[1501]: [0005247] [t1966077024] 1925.359 TRACE [transport/volumio] got update status Sep 03 10:27:05 streambox raatdaemon[1501]: [0005248] [t1966077024] 1926.362 TRACE [transport/volumio] got update status Sep 03 10:27:06 streambox raatdaemon[1501]: [0005249] [t1966077024] 1927.365 TRACE [transport/volumio] got update status Sep 03 10:27:07 streambox raatdaemon[1501]: [0005250] [t1966077024] 1928.368 TRACE [transport/volumio] got update status Sep 03 10:27:08 streambox raatdaemon[1501]: [0005251] [t1966077024] 1929.373 TRACE [transport/volumio] got update status Sep 03 10:27:09 streambox raatdaemon[1501]: [0005252] [t1992959072] 1930.287 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:27:09 streambox raatdaemon[1501]: [0005253] [t1992959072] 1930.287 TRACE [discovery] stopping Sep 03 10:27:09 streambox raatdaemon[1501]: [0005254] [t1992959072] 1930.287 TRACE closing multicast Sep 03 10:27:09 streambox raatdaemon[1501]: [0005255] [t1992959072] 1930.287 TRACE [discovery] closing unicast send socket Sep 03 10:27:09 streambox raatdaemon[1501]: [0005256] [t1992959072] 1930.287 TRACE [discovery] closing unicast recv socket Sep 03 10:27:09 streambox raatdaemon[1501]: [0005257] [t1992959072] 1930.287 TRACE [discovery] starting Sep 03 10:27:09 streambox raatdaemon[1501]: [0005258] [t1992959072] 1930.287 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:09 streambox raatdaemon[1501]: [0005259] [t1992959072] 1930.288 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:45283 Sep 03 10:27:09 streambox raatdaemon[1501]: [0005260] [t1992959072] 1930.288 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:09 streambox raatdaemon[1501]: [0005261] [t1992959072] 1930.288 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:56185 Sep 03 10:27:09 streambox raatdaemon[1501]: [0005262] [t1992959072] 1930.288 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:27:09 streambox raatdaemon[1501]: [0005263] [t1992959072] 1930.288 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=BD9DFCEF-65C6-092E-75C4-90C2008106A3, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:27:09 streambox raatdaemon[1501]: [0005264] [t1966077024] 1930.378 TRACE [transport/volumio] got update status Sep 03 10:27:09 streambox raatdaemon[1501]: [0005265] [t1966077024] 1931.019 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1431] {"request":"end_stream"} Sep 03 10:27:09 streambox raatdaemon[1501]: [0005266] [t1966077024] 1931.019 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1191] {"status":"Ended"} Sep 03 10:27:09 streambox raatdaemon[1501]: [0005267] [t1966077024] 1931.019 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1431] {"status":"Success"} Sep 03 10:27:09 streambox raatdaemon[1501]: [0005268] [t1966077024] 1931.019 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1432] {"request":"request_source"} Sep 03 10:27:09 streambox raatdaemon[1501]: [0005269] [t1966077024] 1931.020 TRACE [source_selection/volumio] requesting source Sep 03 10:27:09 streambox raatdaemon[1501]: [81B blob data] Sep 03 10:27:09 streambox raatdaemon[1501]: [0005271] [t1996320768] 1931.021 TRACE [source_selection/volumio] source acquired Sep 03 10:27:09 streambox raatdaemon[1501]: [0005272] [t1966077024] 1931.021 TRACE [lua@0x7631d75c] [192.168.0.150:55082] source selection state changed: selected Sep 03 10:27:09 streambox volumio[1204]: info: [VRCP] already in control, acknowledged Sep 03 10:27:09 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:09 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:09 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:09 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:09 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:09 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:09 streambox raatdaemon[1501]: [0005273] [t1966077024] 1931.022 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [5] {"controls":{"source":{"value":"selected","info":{"is_standby_supported":false}},"volume":{"min":0.0,"info":{"config":{"device":"hw:5","mute":false,"value":100,"type":"alsa","mode":"number"}},"mute":false,"value":100,"step":1.0,"type":"number","max":100.0}},"status":"Success","action":"Changed"} Sep 03 10:27:09 streambox raatdaemon[1501]: [0005274] [t1966077024] 1931.022 TRACE [transport/volumio] got update artwork Sep 03 10:27:09 streambox raatdaemon[1501]: [0005275] [t1966077024] 1931.022 TRACE - MD5 for artwork: dc077e35986aced241b4bcaf8f9a6a1d Sep 03 10:27:09 streambox raatdaemon[1501]: [0005276] [t1966077024] 1931.022 TRACE - Full path for artwork: /tmp/raat/albumart/dc077e35986aced241b4bcaf8f9a6a1d.jpg Sep 03 10:27:09 streambox raatdaemon[1501]: [0005277] [t1966077024] 1931.023 TRACE [transport/volumio] got update status Sep 03 10:27:09 streambox raatdaemon[1501]: [0005278] [t1966077024] 1931.024 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1432] {"status":"Success"} Sep 03 10:27:09 streambox sudo[23322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:09 streambox sudo[23322]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:09 streambox sudo[23322]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:09 streambox raatdaemon[1501]: [0005279] [t1966077024] 1931.082 TRACE [transport/volumio] got update status Sep 03 10:27:09 streambox raatdaemon[1501]: [0005280] [t1966077024] 1931.089 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1434] {"request":"update_artwork","mimetype":"image/jpeg"} Sep 03 10:27:09 streambox raatdaemon[1501]: [0005281] [t1966077024] 1931.089 TRACE [transport/volumio] got update artwork Sep 03 10:27:09 streambox raatdaemon[1501]: [0005282] [t1966077024] 1931.089 TRACE - MD5 for artwork: dc077e35986aced241b4bcaf8f9a6a1d Sep 03 10:27:09 streambox raatdaemon[1501]: [0005283] [t1966077024] 1931.089 TRACE - Full path for artwork: /tmp/raat/albumart/dc077e35986aced241b4bcaf8f9a6a1d.jpg Sep 03 10:27:09 streambox raatdaemon[1501]: [0005284] [t1966077024] 1931.089 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1434] {"status":"Success"} Sep 03 10:27:09 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:09 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:10 streambox raatdaemon[1501]: [0005285] [t1966077024] 1931.123 TRACE [transport/volumio] got update status Sep 03 10:27:10 streambox volumio[1204]: [VRCP] RAAT stopped playing Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:10 streambox sudo[23331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:10 streambox raatdaemon[1501]: [0005286] [t1966077024] 1931.136 TRACE [transport/volumio] got update status Sep 03 10:27:10 streambox sudo[23331]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:10 streambox sudo[23331]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:10 streambox volumio[1204]: info: CorePlayQueue::getTrack 0 Sep 03 10:27:10 streambox volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Sep 03 10:27:10 streambox sudo[23337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:10 streambox sudo[23337]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:10 streambox sudo[23337]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:10 streambox raatdaemon[1501]: [0005287] [t1966077024] 1931.366 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1437] {"request":"update_artwork","mimetype":"image/jpeg"} Sep 03 10:27:10 streambox raatdaemon[1501]: [0005288] [t1966077024] 1931.367 TRACE [transport/volumio] got update artwork Sep 03 10:27:10 streambox raatdaemon[1501]: [0005289] [t1966077024] 1931.368 TRACE - MD5 for artwork: 70c69054a9a4a3cb60a7d1d5072d6701 Sep 03 10:27:10 streambox raatdaemon[1501]: [0005290] [t1966077024] 1931.368 TRACE - Full path for artwork: /tmp/raat/albumart/70c69054a9a4a3cb60a7d1d5072d6701.jpg Sep 03 10:27:10 streambox raatdaemon[1501]: [0005291] [t1966077024] 1931.368 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1437] {"status":"Success"} Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:10 streambox volumio[1204]: info: CorePlayQueue::getTrack 0 Sep 03 10:27:10 streambox volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Sep 03 10:27:10 streambox sudo[23359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:10 streambox sudo[23359]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:10 streambox sudo[23359]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:10 streambox raatdaemon[1501]: [0005292] [t1966077024] 1931.472 TRACE [transport/volumio] got update status Sep 03 10:27:10 streambox raatdaemon[1501]: [0005293] [t1966077024] 1931.473 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1439] {"request":"teardown"} Sep 03 10:27:10 streambox raatdaemon[1501]: [0005294] [t1966077024] 1931.473 TRACE [alsa] teardown Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:10 streambox raatdaemon[1501]: [0005295] [t1966077024] 1931.489 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1189] {"status":"Teardown"} Sep 03 10:27:10 streambox raatdaemon[1501]: [0005296] [t1966077024] 1931.489 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1440] {"request":"setup","format":{"sample_type":"pcm","sample_rate":192000,"bits_per_sample":24,"channels":2,"sample_subtype":"none"}} Sep 03 10:27:10 streambox raatdaemon[1501]: [0005297] [t1966077024] 1931.489 TRACE alsa output setup: format is pcm 192000/24/2 Sep 03 10:27:10 streambox raatdaemon[1501]: [0005298] [t1966077024] 1931.489 TRACE opening [hw:5,0] 192000/24/2 Sep 03 10:27:10 streambox raatdaemon[1501]: [0005299] [t1966077024] 1931.490 TRACE prefer larger samples = 0 Sep 03 10:27:10 streambox raatdaemon[1501]: [0005300] [t1966077024] 1931.490 TRACE [ALSA] [hw:5,0] using hw pcmformat S32_LE bitspersample 24 Sep 03 10:27:10 streambox raatdaemon[1501]: [0005301] [t1966077024] 1931.490 TRACE [ALSA] [hw:5,0] Requesting 2 periods Sep 03 10:27:10 streambox raatdaemon[1501]: [0005302] [t1966077024] 1931.490 TRACE [ALSA] [hw:5,0] Requesting 7680 frames/buffer (3840 frames/period) Sep 03 10:27:10 streambox raatdaemon[1501]: [0005303] [t1966077024] 1931.490 TRACE [ALSA] [hw:5,0] Initialized with 2 periods with 3840 frames/period and 7680 frames/buffer Sep 03 10:27:10 streambox volumio[1204]: info: CorePlayQueue::getTrack 0 Sep 03 10:27:10 streambox volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Sep 03 10:27:10 streambox sudo[23368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:10 streambox raatdaemon[1501]: [0005304] [t1955591264] 1931.512 WARNING sched_setscheduler failed: Operation not permitted Sep 03 10:27:10 streambox raatdaemon[1501]: [0005305] [t1955591264] 1931.512 TRACE samples per buf 3840 ns per buf 20000000 samplerate 192000 Sep 03 10:27:10 streambox raatdaemon[1501]: [0005306] [t1955591264] 1931.512 TRACE 3840 samples per buf, 23040 bytes per buf, 30720 bytes per hwbuf Sep 03 10:27:10 streambox raatdaemon[1501]: [0005307] [t1955591264] 1931.512 TRACE [output/alsa] [hw:5,0] waiting for device to be ready Sep 03 10:27:10 streambox raatdaemon[1501]: [0005308] [t1955591264] 1931.512 TRACE [output/alsa] [hw:5,0] device is ready Sep 03 10:27:10 streambox raatdaemon[1501]: [0005309] [t1966077024] 1931.514 TRACE [transport/volumio] got update status Sep 03 10:27:10 streambox raatdaemon[1501]: [0005310] [t1966077024] 1931.514 TRACE [lua@0x7631d75c] [192.168.0.150:55082] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Sep 03 10:27:10 streambox raatdaemon[1501]: [0005311] [t1966077024] 1931.515 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1440] {"message":{"signal_path":[{"method":"usb","type":"output","quality":"lossless"}]},"status":"OutputMessage"} Sep 03 10:27:10 streambox sudo[23368]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:10 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:10 streambox sudo[23368]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:10 streambox sudo[23376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:10 streambox raatdaemon[1501]: [0005312] [t1966077024] 1931.555 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1440] {"audio_port_tcp":37115,"status":"Success","clock_port":36786,"audio_port":0} Sep 03 10:27:10 streambox sudo[23376]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:10 streambox sudo[23376]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:10 streambox raatdaemon[1501]: [0005313] [t1966077024] 1931.613 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1442] {"request":"stream","stream_id":25582208,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2} Sep 03 10:27:10 streambox raatdaemon[1501]: [0005314] [t1966077024] 1931.613 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1442] {"status":"Buffering"} Sep 03 10:27:11 streambox raatdaemon[1501]: [0005315] [t1966077024] 1932.431 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1442] {"status":"Ready"} Sep 03 10:27:11 streambox raatdaemon[1501]: [0005316] [t1966077024] 1932.488 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1443] {"request":"start","min_offset":60880208,"stream_sample":0} Sep 03 10:27:11 streambox raatdaemon[1501]: [0005317] [t1966077024] 1932.488 TRACE [lua@0x7631d75c] [192.168.0.150:55082] Doing ASAP start based on current device clock (min_offset=60880208) Sep 03 10:27:11 streambox raatdaemon[1501]: [0005318] [t1966077024] 1932.488 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1442] {"status":"Playing"} Sep 03 10:27:11 streambox raatdaemon[1501]: [0005319] [t1966077024] 1932.488 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1443] {"time":706758089167,"status":"Success"} Sep 03 10:27:11 streambox raatdaemon[1501]: [0005320] [t1955591264] 1932.495 TRACE starting playback: now (706744843750ns) + ns_per_buf(20000000ns) = 706764843750ns > 706758089167ns streamsample=0 Sep 03 10:27:11 streambox raatdaemon[1501]: [0005321] [t1966077024] 1932.693 TRACE [transport/volumio] got update status Sep 03 10:27:12 streambox raatdaemon[1501]: [0005322] [t1966077024] 1933.677 TRACE [transport/volumio] got update status Sep 03 10:27:13 streambox raatdaemon[1501]: [0005323] [t1966077024] 1934.663 TRACE [transport/volumio] got update status Sep 03 10:27:14 streambox raatdaemon[1501]: [0005324] [t1966077024] 1935.664 TRACE [transport/volumio] got update status Sep 03 10:27:15 streambox raatdaemon[1501]: [0005325] [t1966077024] 1936.510 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1448] {"request":"end_stream"} Sep 03 10:27:15 streambox raatdaemon[1501]: [0005326] [t1966077024] 1936.510 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1442] {"status":"Ended"} Sep 03 10:27:15 streambox raatdaemon[1501]: [0005327] [t1966077024] 1936.510 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1448] {"status":"Success"} Sep 03 10:27:15 streambox raatdaemon[1501]: [0005328] [t1966077024] 1936.511 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1449] {"request":"request_source"} Sep 03 10:27:15 streambox raatdaemon[1501]: [0005329] [t1966077024] 1936.511 TRACE [source_selection/volumio] requesting source Sep 03 10:27:15 streambox raatdaemon[1501]: [81B blob data] Sep 03 10:27:15 streambox raatdaemon[1501]: [0005331] [t1996320768] 1936.514 TRACE [source_selection/volumio] source acquired Sep 03 10:27:15 streambox raatdaemon[1501]: [0005332] [t1966077024] 1936.515 TRACE [lua@0x7631d75c] [192.168.0.150:55082] source selection state changed: selected Sep 03 10:27:15 streambox volumio[1204]: info: [VRCP] already in control, acknowledged Sep 03 10:27:15 streambox raatdaemon[1501]: [0005333] [t1966077024] 1936.515 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [5] {"controls":{"source":{"value":"selected","info":{"is_standby_supported":false}},"volume":{"min":0.0,"info":{"config":{"device":"hw:5","mute":false,"value":100,"type":"alsa","mode":"number"}},"mute":false,"value":100,"step":1.0,"type":"number","max":100.0}},"status":"Success","action":"Changed"} Sep 03 10:27:15 streambox raatdaemon[1501]: [0005334] [t1966077024] 1936.516 TRACE [transport/volumio] got update artwork Sep 03 10:27:15 streambox raatdaemon[1501]: [0005335] [t1966077024] 1936.517 TRACE - MD5 for artwork: 70c69054a9a4a3cb60a7d1d5072d6701 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005336] [t1966077024] 1936.517 TRACE - Full path for artwork: /tmp/raat/albumart/70c69054a9a4a3cb60a7d1d5072d6701.jpg Sep 03 10:27:15 streambox raatdaemon[1501]: [0005337] [t1966077024] 1936.518 TRACE [transport/volumio] got update status Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:15 streambox raatdaemon[1501]: [0005338] [t1966077024] 1936.519 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1449] {"status":"Success"} Sep 03 10:27:15 streambox sudo[23442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005339] [t1966077024] 1936.557 TRACE [transport/volumio] got update status Sep 03 10:27:15 streambox sudo[23442]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:15 streambox sudo[23442]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:15 streambox raatdaemon[1501]: [0005340] [t1966077024] 1936.587 TRACE [transport/volumio] got update status Sep 03 10:27:15 streambox volumio[1204]: [VRCP] RAAT stopped playing Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:15 streambox raatdaemon[1501]: [0005341] [t1966077024] 1936.589 TRACE [transport/volumio] got update status Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:15 streambox volumio[1204]: info: CorePlayQueue::getTrack 0 Sep 03 10:27:15 streambox volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Sep 03 10:27:15 streambox sudo[23451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:15 streambox sudo[23451]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:15 streambox sudo[23451]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:15 streambox raatdaemon[1501]: [0005342] [t1966077024] 1936.780 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1453] {"request":"update_artwork","mimetype":"image/jpeg"} Sep 03 10:27:15 streambox raatdaemon[1501]: [0005343] [t1966077024] 1936.780 TRACE [transport/volumio] got update artwork Sep 03 10:27:15 streambox raatdaemon[1501]: [0005344] [t1966077024] 1936.781 TRACE - MD5 for artwork: 5bf33ddfc0cd3fe3e3960d1689a0aa93 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005345] [t1966077024] 1936.781 TRACE - Full path for artwork: /tmp/raat/albumart/5bf33ddfc0cd3fe3e3960d1689a0aa93.jpg Sep 03 10:27:15 streambox raatdaemon[1501]: [0005346] [t1966077024] 1936.781 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1453] {"status":"Success"} Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:15 streambox volumio[1204]: info: CorePlayQueue::getTrack 0 Sep 03 10:27:15 streambox volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Sep 03 10:27:15 streambox sudo[23461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:15 streambox sudo[23461]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:15 streambox sudo[23461]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:15 streambox raatdaemon[1501]: [0005347] [t1992959072] 1937.056 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:27:15 streambox raatdaemon[1501]: [0005348] [t1992959072] 1937.056 TRACE [discovery] stopping Sep 03 10:27:15 streambox raatdaemon[1501]: [0005349] [t1992959072] 1937.056 TRACE closing multicast Sep 03 10:27:15 streambox raatdaemon[1501]: [0005350] [t1992959072] 1937.056 TRACE [discovery] closing unicast send socket Sep 03 10:27:15 streambox raatdaemon[1501]: [0005351] [t1992959072] 1937.057 TRACE [discovery] closing unicast recv socket Sep 03 10:27:15 streambox raatdaemon[1501]: [0005352] [t1992959072] 1937.057 TRACE [discovery] starting Sep 03 10:27:15 streambox raatdaemon[1501]: [0005353] [t1992959072] 1937.057 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005354] [t1992959072] 1937.057 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:52634 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005355] [t1992959072] 1937.057 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005356] [t1992959072] 1937.057 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:35663 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005357] [t1992959072] 1937.057 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005358] [t1992959072] 1937.057 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=BB292C24-DFBD-0ABA-C603-AE042CC30348, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:27:15 streambox raatdaemon[1501]: [0005359] [t1966077024] 1937.065 TRACE [transport/volumio] got update status Sep 03 10:27:15 streambox raatdaemon[1501]: [0005360] [t1966077024] 1937.066 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1455] {"request":"teardown"} Sep 03 10:27:15 streambox raatdaemon[1501]: [0005361] [t1966077024] 1937.066 TRACE [alsa] teardown Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:15 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:15 streambox raatdaemon[1501]: [0005362] [t1966077024] 1937.077 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1440] {"status":"Teardown"} Sep 03 10:27:15 streambox raatdaemon[1501]: [0005363] [t1966077024] 1937.077 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1456] {"request":"setup","format":{"sample_type":"pcm","sample_rate":96000,"bits_per_sample":24,"channels":2,"sample_subtype":"none"}} Sep 03 10:27:15 streambox raatdaemon[1501]: [0005364] [t1966077024] 1937.077 TRACE alsa output setup: format is pcm 96000/24/2 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005365] [t1966077024] 1937.077 TRACE opening [hw:5,0] 96000/24/2 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005366] [t1966077024] 1937.078 TRACE prefer larger samples = 0 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005367] [t1966077024] 1937.078 TRACE [ALSA] [hw:5,0] using hw pcmformat S32_LE bitspersample 24 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005368] [t1966077024] 1937.078 TRACE [ALSA] [hw:5,0] Requesting 2 periods Sep 03 10:27:15 streambox raatdaemon[1501]: [0005369] [t1966077024] 1937.078 TRACE [ALSA] [hw:5,0] Requesting 3840 frames/buffer (1920 frames/period) Sep 03 10:27:15 streambox raatdaemon[1501]: [0005370] [t1966077024] 1937.078 TRACE [ALSA] [hw:5,0] Initialized with 2 periods with 1920 frames/period and 3840 frames/buffer Sep 03 10:27:15 streambox volumio[1204]: info: CorePlayQueue::getTrack 0 Sep 03 10:27:15 streambox volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Sep 03 10:27:15 streambox raatdaemon[1501]: [0005371] [t1955591264] 1937.099 WARNING sched_setscheduler failed: Operation not permitted Sep 03 10:27:15 streambox raatdaemon[1501]: [0005372] [t1955591264] 1937.099 TRACE samples per buf 1920 ns per buf 20000000 samplerate 96000 Sep 03 10:27:15 streambox raatdaemon[1501]: [0005373] [t1955591264] 1937.100 TRACE 1920 samples per buf, 11520 bytes per buf, 15360 bytes per hwbuf Sep 03 10:27:16 streambox raatdaemon[1501]: [0005374] [t1955591264] 1937.100 TRACE [output/alsa] [hw:5,0] waiting for device to be ready Sep 03 10:27:16 streambox raatdaemon[1501]: [0005375] [t1955591264] 1937.100 TRACE [output/alsa] [hw:5,0] device is ready Sep 03 10:27:16 streambox raatdaemon[1501]: [0005376] [t1966077024] 1937.102 TRACE [transport/volumio] got update status Sep 03 10:27:16 streambox raatdaemon[1501]: [0005377] [t1966077024] 1937.102 TRACE [lua@0x7631d75c] [192.168.0.150:55082] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Sep 03 10:27:16 streambox raatdaemon[1501]: [0005378] [t1966077024] 1937.102 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1456] {"message":{"signal_path":[{"method":"usb","type":"output","quality":"lossless"}]},"status":"OutputMessage"} Sep 03 10:27:16 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:16 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:16 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:16 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:16 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:16 streambox sudo[23470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:16 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:16 streambox sudo[23470]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:16 streambox sudo[23470]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:16 streambox raatdaemon[1501]: [0005379] [t1966077024] 1937.143 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1456] {"audio_port_tcp":37115,"status":"Success","clock_port":36786,"audio_port":0} Sep 03 10:27:16 streambox sudo[23477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:16 streambox sudo[23477]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:16 streambox sudo[23477]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:16 streambox raatdaemon[1501]: [0005380] [t1966077024] 1937.189 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1458] {"request":"stream","stream_id":25582209,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2} Sep 03 10:27:16 streambox raatdaemon[1501]: [0005381] [t1966077024] 1937.189 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1458] {"status":"Buffering"} Sep 03 10:27:16 streambox raatdaemon[1501]: [0005382] [t1966077024] 1937.429 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1458] {"status":"Ready"} Sep 03 10:27:16 streambox raatdaemon[1501]: [0005383] [t1966077024] 1937.731 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1459] {"request":"start","min_offset":60760416,"stream_sample":0} Sep 03 10:27:16 streambox raatdaemon[1501]: [0005384] [t1966077024] 1937.731 TRACE [lua@0x7631d75c] [192.168.0.150:55082] Doing ASAP start based on current device clock (min_offset=60760416) Sep 03 10:27:16 streambox raatdaemon[1501]: [0005385] [t1966077024] 1937.731 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1458] {"status":"Playing"} Sep 03 10:27:16 streambox raatdaemon[1501]: [0005386] [t1966077024] 1937.732 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1459] {"time":1423358255260,"status":"Success"} Sep 03 10:27:16 streambox raatdaemon[1501]: [0005387] [t1955591264] 1937.743 TRACE starting playback: now (1423349687500ns) + ns_per_buf(20000000ns) = 1423369687500ns > 1423358255260ns streamsample=0 Sep 03 10:27:16 streambox raatdaemon[1501]: [0005388] [t1966077024] 1937.998 TRACE [transport/volumio] got update status Sep 03 10:27:17 streambox raatdaemon[1501]: [0005389] [t1966077024] 1938.802 TRACE [transport/volumio] got update status Sep 03 10:27:18 streambox raatdaemon[1501]: [0005390] [t1966077024] 1939.858 TRACE [transport/volumio] got update status Sep 03 10:27:19 streambox raatdaemon[1501]: [0005391] [t1966077024] 1940.811 TRACE [transport/volumio] got update status Sep 03 10:27:20 streambox raatdaemon[1501]: [0005392] [t1966077024] 1941.816 TRACE [transport/volumio] got update status Sep 03 10:27:21 streambox raatdaemon[1501]: [0005393] [t1966077024] 1942.819 TRACE [transport/volumio] got update status Sep 03 10:27:22 streambox raatdaemon[1501]: [0005394] [t1992959072] 1943.836 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:27:22 streambox raatdaemon[1501]: [0005395] [t1992959072] 1943.836 TRACE [discovery] stopping Sep 03 10:27:22 streambox raatdaemon[1501]: [0005396] [t1992959072] 1943.836 TRACE closing multicast Sep 03 10:27:22 streambox raatdaemon[1501]: [0005397] [t1992959072] 1943.836 TRACE [discovery] closing unicast send socket Sep 03 10:27:22 streambox raatdaemon[1501]: [0005398] [t1992959072] 1943.836 TRACE [discovery] closing unicast recv socket Sep 03 10:27:22 streambox raatdaemon[1501]: [0005399] [t1992959072] 1943.836 TRACE [discovery] starting Sep 03 10:27:22 streambox raatdaemon[1501]: [0005400] [t1992959072] 1943.837 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:22 streambox raatdaemon[1501]: [0005401] [t1992959072] 1943.837 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:53389 Sep 03 10:27:22 streambox raatdaemon[1501]: [0005402] [t1992959072] 1943.837 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:22 streambox raatdaemon[1501]: [0005403] [t1992959072] 1943.837 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:60704 Sep 03 10:27:22 streambox raatdaemon[1501]: [0005404] [t1992959072] 1943.837 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:27:22 streambox raatdaemon[1501]: [0005405] [t1992959072] 1943.837 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=FB7A5301-B20E-2373-6E90-688469EEAB58, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:27:22 streambox raatdaemon[1501]: [0005406] [t1966077024] 1943.863 TRACE [transport/volumio] got update status Sep 03 10:27:23 streambox raatdaemon[1501]: [0005407] [t1966077024] 1944.827 TRACE [transport/volumio] got update status Sep 03 10:27:24 streambox raatdaemon[1501]: [0005408] [t1966077024] 1945.858 TRACE [transport/volumio] got update status Sep 03 10:27:25 streambox raatdaemon[1501]: [0005409] [t1966077024] 1946.839 TRACE [transport/volumio] got update status Sep 03 10:27:26 streambox raatdaemon[1501]: [0005410] [t1966077024] 1947.838 TRACE [transport/volumio] got update status Sep 03 10:27:27 streambox raatdaemon[1501]: [0005411] [t1966077024] 1948.837 TRACE [transport/volumio] got update status Sep 03 10:27:28 streambox raatdaemon[1501]: [0005412] [t1966077024] 1949.840 TRACE [transport/volumio] got update status Sep 03 10:27:29 streambox raatdaemon[1501]: [0005413] [t1992959072] 1950.615 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:27:29 streambox raatdaemon[1501]: [0005414] [t1992959072] 1950.615 TRACE [discovery] stopping Sep 03 10:27:29 streambox raatdaemon[1501]: [0005415] [t1992959072] 1950.615 TRACE closing multicast Sep 03 10:27:29 streambox raatdaemon[1501]: [0005416] [t1992959072] 1950.616 TRACE [discovery] closing unicast send socket Sep 03 10:27:29 streambox raatdaemon[1501]: [0005417] [t1992959072] 1950.616 TRACE [discovery] closing unicast recv socket Sep 03 10:27:29 streambox raatdaemon[1501]: [0005418] [t1992959072] 1950.616 TRACE [discovery] starting Sep 03 10:27:29 streambox raatdaemon[1501]: [0005419] [t1992959072] 1950.616 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:29 streambox raatdaemon[1501]: [0005420] [t1992959072] 1950.616 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:41261 Sep 03 10:27:29 streambox raatdaemon[1501]: [0005421] [t1992959072] 1950.616 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:29 streambox raatdaemon[1501]: [0005422] [t1992959072] 1950.616 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:34389 Sep 03 10:27:29 streambox raatdaemon[1501]: [0005423] [t1992959072] 1950.617 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:27:29 streambox raatdaemon[1501]: [0005424] [t1992959072] 1950.617 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=D36E7B9E-1853-EB42-374F-D2759BC7DDC9, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:27:29 streambox raatdaemon[1501]: [0005425] [t1966077024] 1950.843 TRACE [transport/volumio] got update status Sep 03 10:27:30 streambox raatdaemon[1501]: [0005426] [t1966077024] 1951.861 TRACE [transport/volumio] got update status Sep 03 10:27:31 streambox raatdaemon[1501]: [0005427] [t1966077024] 1952.855 TRACE [transport/volumio] got update status Sep 03 10:27:32 streambox raatdaemon[1501]: [0005428] [t1966077024] 1953.888 TRACE [transport/volumio] got update status Sep 03 10:27:33 streambox raatdaemon[1501]: [0005429] [t1966077024] 1954.857 TRACE [transport/volumio] got update status Sep 03 10:27:34 streambox raatdaemon[1501]: [0005430] [t1966077024] 1955.862 TRACE [transport/volumio] got update status Sep 03 10:27:35 streambox raatdaemon[1501]: [0005431] [t1966077024] 1956.867 TRACE [transport/volumio] got update status Sep 03 10:27:36 streambox raatdaemon[1501]: [0005432] [t1992959072] 1957.385 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:27:36 streambox raatdaemon[1501]: [0005433] [t1992959072] 1957.385 TRACE [discovery] stopping Sep 03 10:27:36 streambox raatdaemon[1501]: [0005434] [t1992959072] 1957.385 TRACE closing multicast Sep 03 10:27:36 streambox raatdaemon[1501]: [0005435] [t1992959072] 1957.385 TRACE [discovery] closing unicast send socket Sep 03 10:27:36 streambox raatdaemon[1501]: [0005436] [t1992959072] 1957.386 TRACE [discovery] closing unicast recv socket Sep 03 10:27:36 streambox raatdaemon[1501]: [0005437] [t1992959072] 1957.386 TRACE [discovery] starting Sep 03 10:27:36 streambox raatdaemon[1501]: [0005438] [t1992959072] 1957.386 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:36 streambox raatdaemon[1501]: [0005439] [t1992959072] 1957.386 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:45888 Sep 03 10:27:36 streambox raatdaemon[1501]: [0005440] [t1992959072] 1957.386 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:36 streambox raatdaemon[1501]: [0005441] [t1992959072] 1957.386 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:34274 Sep 03 10:27:36 streambox raatdaemon[1501]: [0005442] [t1992959072] 1957.386 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:27:36 streambox raatdaemon[1501]: [0005443] [t1992959072] 1957.386 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=7B5CFD26-0DA9-2B7E-EE86-04778C26721A, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:27:36 streambox raatdaemon[1501]: [0005444] [t1966077024] 1957.871 TRACE [transport/volumio] got update status Sep 03 10:27:36 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:36 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:36 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:36 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:36 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:36 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:36 streambox sudo[23715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:36 streambox sudo[23715]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:36 streambox sudo[23715]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:37 streambox raatdaemon[1501]: [0005445] [t1966077024] 1958.894 TRACE [transport/volumio] got update status Sep 03 10:27:38 streambox raatdaemon[1501]: [0005446] [t1966077024] 1959.876 TRACE [transport/volumio] got update status Sep 03 10:27:39 streambox raatdaemon[1501]: [0005447] [t1966077024] 1960.918 TRACE [transport/volumio] got update status Sep 03 10:27:40 streambox raatdaemon[1501]: [0005448] [t1966077024] 1961.899 TRACE [transport/volumio] got update status Sep 03 10:27:41 streambox raatdaemon[1501]: [0005449] [t1966077024] 1962.888 TRACE [transport/volumio] got update status Sep 03 10:27:42 streambox raatdaemon[1501]: [0005450] [t1966077024] 1963.892 TRACE [transport/volumio] got update status Sep 03 10:27:43 streambox raatdaemon[1501]: [0005451] [t1992959072] 1964.165 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:27:43 streambox raatdaemon[1501]: [0005452] [t1992959072] 1964.166 TRACE [discovery] stopping Sep 03 10:27:43 streambox raatdaemon[1501]: [0005453] [t1992959072] 1964.167 TRACE closing multicast Sep 03 10:27:43 streambox raatdaemon[1501]: [0005454] [t1992959072] 1964.168 TRACE [discovery] closing unicast send socket Sep 03 10:27:43 streambox raatdaemon[1501]: [0005455] [t1992959072] 1964.169 TRACE [discovery] closing unicast recv socket Sep 03 10:27:43 streambox raatdaemon[1501]: [0005456] [t1992959072] 1964.170 TRACE [discovery] starting Sep 03 10:27:43 streambox raatdaemon[1501]: [0005457] [t1992959072] 1964.171 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:43 streambox raatdaemon[1501]: [0005458] [t1992959072] 1964.172 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:44844 Sep 03 10:27:43 streambox raatdaemon[1501]: [0005459] [t1992959072] 1964.172 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:43 streambox raatdaemon[1501]: [0005460] [t1992959072] 1964.174 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:52125 Sep 03 10:27:43 streambox raatdaemon[1501]: [0005461] [t1992959072] 1964.174 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:27:43 streambox raatdaemon[1501]: [0005462] [t1992959072] 1964.175 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=91D44340-8312-3411-9EAE-FFDC559F2056, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:27:43 streambox raatdaemon[1501]: [0005463] [t1966077024] 1964.896 TRACE [transport/volumio] got update status Sep 03 10:27:44 streambox raatdaemon[1501]: [0005464] [t1966077024] 1965.900 TRACE [transport/volumio] got update status Sep 03 10:27:45 streambox raatdaemon[1501]: [0005465] [t1966077024] 1966.909 TRACE [transport/volumio] got update status Sep 03 10:27:46 streambox raatdaemon[1501]: [0005466] [t1966077024] 1967.910 TRACE [transport/volumio] got update status Sep 03 10:27:47 streambox raatdaemon[1501]: [0005467] [t1966077024] 1968.934 TRACE [transport/volumio] got update status Sep 03 10:27:48 streambox raatdaemon[1501]: [0005468] [t1966077024] 1969.915 TRACE [transport/volumio] got update status Sep 03 10:27:49 streambox raatdaemon[1501]: [0005469] [t1966077024] 1970.918 TRACE [transport/volumio] got update status Sep 03 10:27:49 streambox raatdaemon[1501]: [0005470] [t1992959072] 1970.945 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:27:49 streambox raatdaemon[1501]: [0005471] [t1992959072] 1970.946 TRACE [discovery] stopping Sep 03 10:27:49 streambox raatdaemon[1501]: [0005472] [t1992959072] 1970.946 TRACE closing multicast Sep 03 10:27:49 streambox raatdaemon[1501]: [0005473] [t1992959072] 1970.946 TRACE [discovery] closing unicast send socket Sep 03 10:27:49 streambox raatdaemon[1501]: [0005474] [t1992959072] 1970.946 TRACE [discovery] closing unicast recv socket Sep 03 10:27:49 streambox raatdaemon[1501]: [0005475] [t1992959072] 1970.946 TRACE [discovery] starting Sep 03 10:27:49 streambox raatdaemon[1501]: [0005476] [t1992959072] 1970.947 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:49 streambox raatdaemon[1501]: [0005477] [t1992959072] 1970.947 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:40147 Sep 03 10:27:49 streambox raatdaemon[1501]: [0005478] [t1992959072] 1970.947 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:49 streambox raatdaemon[1501]: [0005479] [t1992959072] 1970.947 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:56897 Sep 03 10:27:49 streambox raatdaemon[1501]: [0005480] [t1992959072] 1970.947 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:27:49 streambox raatdaemon[1501]: [0005481] [t1992959072] 1970.947 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=2895B641-EF88-8FB3-B12A-1B30E99F326A, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:27:50 streambox raatdaemon[1501]: [0005482] [t1966077024] 1971.924 TRACE [transport/volumio] got update status Sep 03 10:27:51 streambox raatdaemon[1501]: [0005483] [t1966077024] 1972.927 TRACE [transport/volumio] got update status Sep 03 10:27:52 streambox raatdaemon[1501]: [0005484] [t1966077024] 1973.951 TRACE [transport/volumio] got update status Sep 03 10:27:53 streambox raatdaemon[1501]: [0005485] [t1966077024] 1974.940 TRACE [transport/volumio] got update status Sep 03 10:27:54 streambox raatdaemon[1501]: [0005486] [t1966077024] 1975.941 TRACE [transport/volumio] got update status Sep 03 10:27:55 streambox raatdaemon[1501]: [0005487] [t1966077024] 1976.944 TRACE [transport/volumio] got update status Sep 03 10:27:56 streambox raatdaemon[1501]: [0005488] [t1992959072] 1977.736 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:27:56 streambox raatdaemon[1501]: [0005489] [t1992959072] 1977.736 TRACE [discovery] stopping Sep 03 10:27:56 streambox raatdaemon[1501]: [0005490] [t1992959072] 1977.736 TRACE closing multicast Sep 03 10:27:56 streambox raatdaemon[1501]: [0005491] [t1992959072] 1977.736 TRACE [discovery] closing unicast send socket Sep 03 10:27:56 streambox raatdaemon[1501]: [0005492] [t1992959072] 1977.736 TRACE [discovery] closing unicast recv socket Sep 03 10:27:56 streambox raatdaemon[1501]: [0005493] [t1992959072] 1977.736 TRACE [discovery] starting Sep 03 10:27:56 streambox raatdaemon[1501]: [0005494] [t1992959072] 1977.737 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:56 streambox raatdaemon[1501]: [0005495] [t1992959072] 1977.737 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:54677 Sep 03 10:27:56 streambox raatdaemon[1501]: [0005496] [t1992959072] 1977.737 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:27:56 streambox raatdaemon[1501]: [0005497] [t1992959072] 1977.737 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:48538 Sep 03 10:27:56 streambox raatdaemon[1501]: [0005498] [t1992959072] 1977.737 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:27:56 streambox raatdaemon[1501]: [0005499] [t1992959072] 1977.737 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=5BAA335D-E30C-C6F7-3CD7-154FC8140E4A, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:27:56 streambox raatdaemon[1501]: [0005500] [t1966077024] 1977.970 TRACE [transport/volumio] got update status Sep 03 10:27:56 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:27:56 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:27:56 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:27:56 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:27:56 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:27:56 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:27:56 streambox sudo[23938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:27:56 streambox sudo[23938]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:27:56 streambox sudo[23938]: pam_unix(sudo:session): session closed for user root Sep 03 10:27:57 streambox raatdaemon[1501]: [0005501] [t1966077024] 1978.963 TRACE [transport/volumio] got update status Sep 03 10:27:58 streambox raatdaemon[1501]: [0005502] [t1966077024] 1979.966 TRACE [transport/volumio] got update status Sep 03 10:27:59 streambox raatdaemon[1501]: [0005503] [t1966077024] 1980.960 TRACE [transport/volumio] got update status Sep 03 10:28:00 streambox raatdaemon[1501]: [0005504] [t1966077024] 1981.965 TRACE [transport/volumio] got update status Sep 03 10:28:01 streambox raatdaemon[1501]: [0005505] [t1966077024] 1982.967 TRACE [transport/volumio] got update status Sep 03 10:28:02 streambox raatdaemon[1501]: [0005506] [t1966077024] 1983.970 TRACE [transport/volumio] got update status Sep 03 10:28:03 streambox raatdaemon[1501]: [0005507] [t1992959072] 1984.516 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:28:03 streambox raatdaemon[1501]: [0005508] [t1992959072] 1984.516 TRACE [discovery] stopping Sep 03 10:28:03 streambox raatdaemon[1501]: [0005509] [t1992959072] 1984.516 TRACE closing multicast Sep 03 10:28:03 streambox raatdaemon[1501]: [0005510] [t1992959072] 1984.517 TRACE [discovery] closing unicast send socket Sep 03 10:28:03 streambox raatdaemon[1501]: [0005511] [t1992959072] 1984.517 TRACE [discovery] closing unicast recv socket Sep 03 10:28:03 streambox raatdaemon[1501]: [0005512] [t1992959072] 1984.517 TRACE [discovery] starting Sep 03 10:28:03 streambox raatdaemon[1501]: [0005513] [t1992959072] 1984.517 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:03 streambox raatdaemon[1501]: [0005514] [t1992959072] 1984.517 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:43624 Sep 03 10:28:03 streambox raatdaemon[1501]: [0005515] [t1992959072] 1984.517 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:03 streambox raatdaemon[1501]: [0005516] [t1992959072] 1984.517 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:47789 Sep 03 10:28:03 streambox raatdaemon[1501]: [0005517] [t1992959072] 1984.517 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:28:03 streambox raatdaemon[1501]: [0005518] [t1992959072] 1984.517 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=069FF02E-1F8C-192E-F299-A465023015F8, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:28:03 streambox raatdaemon[1501]: [0005519] [t1966077024] 1985.000 TRACE [transport/volumio] got update status Sep 03 10:28:04 streambox raatdaemon[1501]: [0005520] [t1966077024] 1985.984 TRACE [transport/volumio] got update status Sep 03 10:28:05 streambox raatdaemon[1501]: [0005521] [t1966077024] 1986.984 TRACE [transport/volumio] got update status Sep 03 10:28:06 streambox raatdaemon[1501]: [0005522] [t1966077024] 1987.992 TRACE [transport/volumio] got update status Sep 03 10:28:07 streambox raatdaemon[1501]: [0005523] [t1966077024] 1988.998 TRACE [transport/volumio] got update status Sep 03 10:28:08 streambox raatdaemon[1501]: [0005524] [t1966077024] 1989.801 TRACE [transport/volumio] got update status Sep 03 10:28:09 streambox raatdaemon[1501]: [0005525] [t1966077024] 1990.807 TRACE [transport/volumio] got update status Sep 03 10:28:10 streambox raatdaemon[1501]: [0005526] [t1992959072] 1991.286 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:28:10 streambox raatdaemon[1501]: [0005527] [t1992959072] 1991.286 TRACE [discovery] stopping Sep 03 10:28:10 streambox raatdaemon[1501]: [0005528] [t1992959072] 1991.286 TRACE closing multicast Sep 03 10:28:10 streambox raatdaemon[1501]: [0005529] [t1992959072] 1991.287 TRACE [discovery] closing unicast send socket Sep 03 10:28:10 streambox raatdaemon[1501]: [0005530] [t1992959072] 1991.287 TRACE [discovery] closing unicast recv socket Sep 03 10:28:10 streambox raatdaemon[1501]: [0005531] [t1992959072] 1991.287 TRACE [discovery] starting Sep 03 10:28:10 streambox raatdaemon[1501]: [0005532] [t1992959072] 1991.287 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:10 streambox raatdaemon[1501]: [0005533] [t1992959072] 1991.287 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:51603 Sep 03 10:28:10 streambox raatdaemon[1501]: [0005534] [t1992959072] 1991.287 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:10 streambox raatdaemon[1501]: [0005535] [t1992959072] 1991.287 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:47289 Sep 03 10:28:10 streambox raatdaemon[1501]: [0005536] [t1992959072] 1991.287 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:28:10 streambox raatdaemon[1501]: [0005537] [t1992959072] 1991.288 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=8C9FC501-DBF2-E7BE-0C2B-8CD9C6E41B7B, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:28:10 streambox raatdaemon[1501]: [0005538] [t1966077024] 1991.813 TRACE [transport/volumio] got update status Sep 03 10:28:11 streambox raatdaemon[1501]: [0005539] [t1966077024] 1992.817 TRACE [transport/volumio] got update status Sep 03 10:28:12 streambox raatdaemon[1501]: [0005540] [t1966077024] 1993.821 TRACE [transport/volumio] got update status Sep 03 10:28:13 streambox raatdaemon[1501]: [0005541] [t1966077024] 1994.844 TRACE [transport/volumio] got update status Sep 03 10:28:14 streambox raatdaemon[1501]: [0005542] [t1966077024] 1995.826 TRACE [transport/volumio] got update status Sep 03 10:28:15 streambox raatdaemon[1501]: [0005543] [t1966077024] 1996.836 TRACE [transport/volumio] got update status Sep 03 10:28:16 streambox raatdaemon[1501]: [0005544] [t1966077024] 1997.833 TRACE [transport/volumio] got update status Sep 03 10:28:16 streambox raatdaemon[1501]: [0005545] [t1992959072] 1998.055 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:28:16 streambox raatdaemon[1501]: [0005546] [t1992959072] 1998.055 TRACE [discovery] stopping Sep 03 10:28:16 streambox raatdaemon[1501]: [0005547] [t1992959072] 1998.055 TRACE closing multicast Sep 03 10:28:16 streambox raatdaemon[1501]: [0005548] [t1992959072] 1998.055 TRACE [discovery] closing unicast send socket Sep 03 10:28:16 streambox raatdaemon[1501]: [0005549] [t1992959072] 1998.055 TRACE [discovery] closing unicast recv socket Sep 03 10:28:16 streambox raatdaemon[1501]: [0005550] [t1992959072] 1998.055 TRACE [discovery] starting Sep 03 10:28:16 streambox raatdaemon[1501]: [0005551] [t1992959072] 1998.056 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:16 streambox raatdaemon[1501]: [0005552] [t1992959072] 1998.056 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:38153 Sep 03 10:28:16 streambox raatdaemon[1501]: [0005553] [t1992959072] 1998.056 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:16 streambox raatdaemon[1501]: [0005554] [t1992959072] 1998.056 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:54116 Sep 03 10:28:16 streambox raatdaemon[1501]: [0005555] [t1992959072] 1998.056 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:28:16 streambox raatdaemon[1501]: [0005556] [t1992959072] 1998.056 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=06D509EF-21AA-9159-8649-203BE36DAE0F, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:28:17 streambox raatdaemon[1501]: [0005557] [t1966077024] 1998.839 TRACE [transport/volumio] got update status Sep 03 10:28:17 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:28:17 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:28:17 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:28:17 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:28:17 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:28:17 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:28:17 streambox sudo[24169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:28:17 streambox sudo[24169]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:28:17 streambox sudo[24169]: pam_unix(sudo:session): session closed for user root Sep 03 10:28:18 streambox raatdaemon[1501]: [0005558] [t1966077024] 1999.853 TRACE [transport/volumio] got update status Sep 03 10:28:19 streambox raatdaemon[1501]: [0005559] [t1966077024] 2000.849 TRACE [transport/volumio] got update status Sep 03 10:28:20 streambox raatdaemon[1501]: [0005560] [t1966077024] 2001.877 TRACE [transport/volumio] got update status Sep 03 10:28:21 streambox raatdaemon[1501]: [0005561] [t1966077024] 2002.861 TRACE [transport/volumio] got update status Sep 03 10:28:22 streambox raatdaemon[1501]: [0005562] [t1966077024] 2003.865 TRACE [transport/volumio] got update status Sep 03 10:28:23 streambox raatdaemon[1501]: [0005563] [t1992959072] 2004.826 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:28:23 streambox raatdaemon[1501]: [0005564] [t1992959072] 2004.826 TRACE [discovery] stopping Sep 03 10:28:23 streambox raatdaemon[1501]: [0005565] [t1992959072] 2004.826 TRACE closing multicast Sep 03 10:28:23 streambox raatdaemon[1501]: [0005566] [t1992959072] 2004.826 TRACE [discovery] closing unicast send socket Sep 03 10:28:23 streambox raatdaemon[1501]: [0005567] [t1992959072] 2004.826 TRACE [discovery] closing unicast recv socket Sep 03 10:28:23 streambox raatdaemon[1501]: [0005568] [t1992959072] 2004.826 TRACE [discovery] starting Sep 03 10:28:23 streambox raatdaemon[1501]: [0005569] [t1992959072] 2004.827 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:23 streambox raatdaemon[1501]: [0005570] [t1992959072] 2004.827 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:49509 Sep 03 10:28:23 streambox raatdaemon[1501]: [0005571] [t1992959072] 2004.827 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:23 streambox raatdaemon[1501]: [0005572] [t1992959072] 2004.827 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:55088 Sep 03 10:28:23 streambox raatdaemon[1501]: [0005573] [t1992959072] 2004.827 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:28:23 streambox raatdaemon[1501]: [0005574] [t1992959072] 2004.827 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=E6ED0E70-91EF-622E-0623-F3CDE4670F27, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:28:23 streambox raatdaemon[1501]: [0005575] [t1966077024] 2004.874 TRACE [transport/volumio] got update status Sep 03 10:28:24 streambox raatdaemon[1501]: [0005576] [t1966077024] 2005.872 TRACE [transport/volumio] got update status Sep 03 10:28:25 streambox raatdaemon[1501]: [0005577] [t1966077024] 2006.875 TRACE [transport/volumio] got update status Sep 03 10:28:26 streambox raatdaemon[1501]: [0005578] [t1966077024] 2007.878 TRACE [transport/volumio] got update status Sep 03 10:28:27 streambox raatdaemon[1501]: [0005579] [t1966077024] 2008.884 TRACE [transport/volumio] got update status Sep 03 10:28:28 streambox raatdaemon[1501]: [0005580] [t1966077024] 2009.890 TRACE [transport/volumio] got update status Sep 03 10:28:29 streambox raatdaemon[1501]: [0005581] [t1966077024] 2010.905 TRACE [transport/volumio] got update status Sep 03 10:28:30 streambox raatdaemon[1501]: [0005582] [t1992959072] 2011.596 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:28:30 streambox raatdaemon[1501]: [0005583] [t1992959072] 2011.596 TRACE [discovery] stopping Sep 03 10:28:30 streambox raatdaemon[1501]: [0005584] [t1992959072] 2011.596 TRACE closing multicast Sep 03 10:28:30 streambox raatdaemon[1501]: [0005585] [t1992959072] 2011.596 TRACE [discovery] closing unicast send socket Sep 03 10:28:30 streambox raatdaemon[1501]: [0005586] [t1992959072] 2011.596 TRACE [discovery] closing unicast recv socket Sep 03 10:28:30 streambox raatdaemon[1501]: [0005587] [t1992959072] 2011.596 TRACE [discovery] starting Sep 03 10:28:30 streambox raatdaemon[1501]: [0005588] [t1992959072] 2011.597 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:30 streambox raatdaemon[1501]: [0005589] [t1992959072] 2011.597 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:35763 Sep 03 10:28:30 streambox raatdaemon[1501]: [0005590] [t1992959072] 2011.597 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:30 streambox raatdaemon[1501]: [0005591] [t1992959072] 2011.597 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:38659 Sep 03 10:28:30 streambox raatdaemon[1501]: [0005592] [t1992959072] 2011.597 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:28:30 streambox raatdaemon[1501]: [0005593] [t1992959072] 2011.597 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=D104D583-DD36-8DA3-CEAD-D870D4C33B0E, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:28:30 streambox raatdaemon[1501]: [0005594] [t1966077024] 2011.895 TRACE [transport/volumio] got update status Sep 03 10:28:31 streambox raatdaemon[1501]: [0005595] [t1966077024] 2012.898 TRACE [transport/volumio] got update status Sep 03 10:28:32 streambox raatdaemon[1501]: [0005596] [t1966077024] 2013.907 TRACE [transport/volumio] got update status Sep 03 10:28:33 streambox raatdaemon[1501]: [0005597] [t1966077024] 2014.910 TRACE [transport/volumio] got update status Sep 03 10:28:34 streambox raatdaemon[1501]: [0005598] [t1966077024] 2015.910 TRACE [transport/volumio] got update status Sep 03 10:28:35 streambox raatdaemon[1501]: [0005599] [t1966077024] 2016.916 TRACE [transport/volumio] got update status Sep 03 10:28:36 streambox raatdaemon[1501]: [0005600] [t1966077024] 2017.927 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1540] {"request":"end_stream"} Sep 03 10:28:36 streambox raatdaemon[1501]: [0005601] [t1966077024] 2017.927 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1458] {"status":"Ended"} Sep 03 10:28:36 streambox raatdaemon[1501]: [0005602] [t1966077024] 2017.927 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1540] {"status":"Success"} Sep 03 10:28:36 streambox raatdaemon[1501]: [0005603] [t1966077024] 2017.927 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1541] {"request":"request_source"} Sep 03 10:28:36 streambox raatdaemon[1501]: [0005604] [t1966077024] 2017.927 TRACE [source_selection/volumio] requesting source Sep 03 10:28:36 streambox raatdaemon[1501]: [81B blob data] Sep 03 10:28:36 streambox raatdaemon[1501]: [0005606] [t1996320768] 2017.929 TRACE [source_selection/volumio] source acquired Sep 03 10:28:36 streambox raatdaemon[1501]: [0005607] [t1966077024] 2017.929 TRACE [lua@0x7631d75c] [192.168.0.150:55082] source selection state changed: selected Sep 03 10:28:36 streambox raatdaemon[1501]: [0005608] [t1966077024] 2017.930 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [5] {"controls":{"source":{"value":"selected","info":{"is_standby_supported":false}},"volume":{"min":0.0,"info":{"config":{"device":"hw:5","mute":false,"value":100,"type":"alsa","mode":"number"}},"mute":false,"value":100,"step":1.0,"type":"number","max":100.0}},"status":"Success","action":"Changed"} Sep 03 10:28:36 streambox raatdaemon[1501]: [0005609] [t1966077024] 2017.930 TRACE [transport/volumio] got update artwork Sep 03 10:28:36 streambox raatdaemon[1501]: [0005610] [t1966077024] 2017.931 TRACE - MD5 for artwork: 5bf33ddfc0cd3fe3e3960d1689a0aa93 Sep 03 10:28:36 streambox raatdaemon[1501]: [0005611] [t1966077024] 2017.931 TRACE - Full path for artwork: /tmp/raat/albumart/5bf33ddfc0cd3fe3e3960d1689a0aa93.jpg Sep 03 10:28:36 streambox volumio[1204]: info: [VRCP] already in control, acknowledged Sep 03 10:28:36 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:28:36 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:28:36 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:28:36 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:28:36 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:28:36 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:28:36 streambox raatdaemon[1501]: [0005612] [t1966077024] 2017.932 TRACE [transport/volumio] got update status Sep 03 10:28:36 streambox raatdaemon[1501]: [0005613] [t1966077024] 2017.933 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1541] {"status":"Success"} Sep 03 10:28:36 streambox sudo[24389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:28:36 streambox sudo[24389]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:28:36 streambox sudo[24389]: pam_unix(sudo:session): session closed for user root Sep 03 10:28:36 streambox raatdaemon[1501]: [0005614] [t1966077024] 2017.987 TRACE [transport/volumio] got update status Sep 03 10:28:36 streambox raatdaemon[1501]: [0005615] [t1966077024] 2018.030 TRACE [transport/volumio] got update status Sep 03 10:28:36 streambox volumio[1204]: [VRCP] RAAT stopped playing Sep 03 10:28:36 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:28:36 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:28:36 streambox raatdaemon[1501]: [0005616] [t1966077024] 2018.032 TRACE [transport/volumio] got update status Sep 03 10:28:36 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:28:36 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:28:36 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:28:36 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:28:36 streambox volumio[1204]: info: CorePlayQueue::getTrack 0 Sep 03 10:28:36 streambox volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Sep 03 10:28:36 streambox sudo[24398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:28:36 streambox sudo[24398]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:28:36 streambox sudo[24398]: pam_unix(sudo:session): session closed for user root Sep 03 10:28:37 streambox raatdaemon[1501]: [0005617] [t1966077024] 2018.349 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1545] {"request":"update_artwork","mimetype":"image/jpeg"} Sep 03 10:28:37 streambox raatdaemon[1501]: [0005618] [t1966077024] 2018.349 TRACE [transport/volumio] got update artwork Sep 03 10:28:37 streambox raatdaemon[1501]: [0005619] [t1966077024] 2018.350 TRACE - MD5 for artwork: be83d0d924dff9e518561447afada65e Sep 03 10:28:37 streambox raatdaemon[1501]: [0005620] [t1966077024] 2018.350 TRACE - Full path for artwork: /tmp/raat/albumart/be83d0d924dff9e518561447afada65e.jpg Sep 03 10:28:37 streambox raatdaemon[1501]: [0005621] [t1966077024] 2018.351 TRACE [lua@0x7631d75c] [192.168.0.150:55082] SENT [1545] {"status":"Success"} Sep 03 10:28:37 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:28:37 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:28:37 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:28:37 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:28:37 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:28:37 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:28:37 streambox raatdaemon[1501]: [0005622] [t1992959072] 2018.365 TRACE Network Status Changed. Refreshing Discovery Sep 03 10:28:37 streambox raatdaemon[1501]: [0005623] [t1992959072] 2018.365 TRACE [discovery] stopping Sep 03 10:28:37 streambox raatdaemon[1501]: [0005624] [t1992959072] 2018.365 TRACE closing multicast Sep 03 10:28:37 streambox raatdaemon[1501]: [0005625] [t1992959072] 2018.366 TRACE [discovery] closing unicast send socket Sep 03 10:28:37 streambox raatdaemon[1501]: [0005626] [t1992959072] 2018.366 TRACE [discovery] closing unicast recv socket Sep 03 10:28:37 streambox raatdaemon[1501]: [0005627] [t1992959072] 2018.366 TRACE [discovery] starting Sep 03 10:28:37 streambox raatdaemon[1501]: [0005628] [t1992959072] 2018.366 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:37 streambox raatdaemon[1501]: [0005629] [t1992959072] 2018.366 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:46431 Sep 03 10:28:37 streambox raatdaemon[1501]: [0005630] [t1992959072] 2018.366 INFO [discovery] [iface:192.168.0.197] multicast recv socket is bound to 0.0.0.0:9003 Sep 03 10:28:37 streambox raatdaemon[1501]: [0005631] [t1992959072] 2018.366 INFO [discovery] [iface:192.168.0.197] multicast send socket is bound to 0.0.0.0:36197 Sep 03 10:28:37 streambox raatdaemon[1501]: [0005632] [t1992959072] 2018.366 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Sep 03 10:28:37 streambox raatdaemon[1501]: [0005633] [t1992959072] 2018.366 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=377E68D0-5465-38CD-C7CD-36FFC9A08227, Data={ "unique_id": "0000000050d0ca2b", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "auto_name": "streambox", "vendor": "Pro-Ject Audio Systems", "model": "Stream Box S2 ultra", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "33413", } ] Sep 03 10:28:37 streambox volumio[1204]: info: CorePlayQueue::getTrack 0 Sep 03 10:28:37 streambox volumio[1204]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Sep 03 10:28:37 streambox sudo[24410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:28:37 streambox sudo[24410]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:28:37 streambox sudo[24410]: pam_unix(sudo:session): session closed for user root Sep 03 10:28:37 streambox raatdaemon[1501]: [0005634] [t1966077024] 2018.738 TRACE [transport/volumio] got update status Sep 03 10:28:37 streambox raatdaemon[1501]: [0005635] [t1966077024] 2018.738 TRACE [lua@0x7631d75c] [192.168.0.150:55082] GOT [1547] {"request":"teardown"} Sep 03 10:28:37 streambox raatdaemon[1501]: [0005636] [t1966077024] 2018.738 TRACE [alsa] teardown Sep 03 10:28:37 streambox volumio[1204]: info: CoreCommandRouter::servicePushState Sep 03 10:28:37 streambox volumio[1204]: info: CoreStateMachine::syncState Sep 03 10:28:37 streambox volumio[1204]: info: CoreStateMachine::pushState Sep 03 10:28:37 streambox volumio[1204]: info: CoreStateMachine::getState Sep 03 10:28:37 streambox volumio[1204]: info: CoreCommandRouter::volumioPushState Sep 03 10:28:37 streambox volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 10:28:37 streambox systemd-journal[24430]: Runtime journal is using 12.0M (max allowed 30.0M, trying to leave 65.6M free of 425.2M available → current limit 30.0M). Sep 03 10:28:37 streambox kernel: Unable to handle kernel NULL pointer dereference at virtual address 00000222 Sep 03 10:28:37 streambox kernel: pgd = aee78000 Sep 03 10:28:37 streambox kernel: [00000222] *pgd=00000000 Sep 03 10:28:37 streambox kernel: Internal error: Oops: 5 [#1] SMP ARM Sep 03 10:28:37 streambox kernel: Modules linked in: bnep xt_REDIRECT nf_nat_redirect xt_tcpudp iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack ip_tables x_tables nfsd 8723bu(O) snd_usb_audio btusb(O) snd_hwdep btrtl(O) snd_usbmidi_lib cfg80211 snd_seq_midi snd_seq_midi_event btintel(O) btbcm(O) snd_rawmidi bluetooth snd_pcm snd_seq snd_seq_device snd_timer snd evdev rfkill bcm2835_gpiomem uio_pdrv_genirq uio fixed i2c_dev fuse ipv6 squashfs overlay Sep 03 10:28:37 streambox kernel: CPU: 1 PID: 197 Comm: systemd-journal Tainted: G O 4.9.65-v7+ #1 Sep 03 10:28:37 streambox kernel: Hardware name: BCM2835 Sep 03 10:28:37 streambox kernel: task: aee4ac40 task.stack: aea88000 Sep 03 10:28:37 streambox kernel: PC is at skb_release_data+0x64/0x128 Sep 03 10:28:37 streambox kernel: LR is at skb_release_all+0x30/0x34 Sep 03 10:28:37 streambox kernel: pc : [<805fff80>] lr : [<80600074>] psr: 60000013 sp : aea89d78 ip : aea89d98 fp : aea89d94 Sep 03 10:28:37 streambox kernel: r10: afe55e00 r9 : 00000000 r8 : 00000000 Sep 03 10:28:37 streambox kernel: r7 : 00000222 r6 : aeac40c0 r5 : aeac40c0 r4 : aeac40c0 Sep 03 10:28:37 streambox kernel: r3 : 00000000 r2 : 00001601 r1 : 00000000 r0 : aeac40c0 Sep 03 10:28:37 streambox kernel: Flags: nZCv IRQs on FIQs on Mode SVC_32 ISA ARM Segment user Sep 03 10:28:37 streambox kernel: Control: 10c5383d Table: 2ee7806a DAC: 00000055 Sep 03 10:28:37 streambox kernel: Process systemd-journal (pid: 197, stack limit = 0xaea88210) Sep 03 10:28:37 streambox kernel: Stack: (0xaea89d78 to 0xaea8a000) Sep 03 10:28:37 streambox kernel: 9d60: aeac40c0 aeac40c0 Sep 03 10:28:37 streambox kernel: 9d80: aea89e5c 0000078f aea89dac aea89d98 80600074 805fff28 00000001 aeac40c0 Sep 03 10:28:37 streambox kernel: 9da0: aea89dc4 aea89db0 806001f8 80600050 aeac40c0 00000071 aea89e54 aea89dc8 Sep 03 10:28:37 streambox kernel: 9dc0: 806c2264 806001cc 00000000 00000000 afe55f44 00000001 00000000 00000001 Sep 03 10:28:37 streambox kernel: 9de0: afe5600c 00000000 afe5604c 8f4ef500 00000071 00000001 00000071 afe55e84 Sep 03 10:28:37 streambox kernel: 9e00: aea0a540 00000000 000005dd 000003e8 000003e8 8c60c690 aea89e48 8028727c Sep 03 10:28:37 streambox kernel: 9e20: 66d6e4d5 25fcc48b aea89e6c 00000000 aea89f00 aea89ee8 aea89f78 00000800 Sep 03 10:28:37 streambox kernel: 9e40: 00b1e3a1 00000000 aea89e84 aea89e58 806c2410 806c1b10 806bdb84 806be394 Sep 03 10:28:37 streambox kernel: 9e60: 8f4ef500 aea89e88 00000000 00000800 00000040 00000000 aea89ed4 aea89e88 Sep 03 10:28:37 streambox kernel: 9e80: 805f71e0 806c23d4 00000000 00000000 00000000 00000071 0000078f aea89ee0 Sep 03 10:28:37 streambox kernel: 9ea0: 00000001 805c88c0 00000000 00000000 00000040 aea89f00 aea4f7ac 00000000 Sep 03 10:28:37 streambox kernel: 9ec0: 00000000 8c4db6c0 aea89f44 aea89ed8 80268b64 805f7150 00000800 0000001c Sep 03 10:28:37 streambox kernel: 9ee0: 00b1e3a1 00000800 00000000 00000000 00000800 aea89ee0 00000001 805c88c0 Sep 03 10:28:37 streambox kernel: 9f00: 8c4db6c0 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Sep 03 10:28:37 streambox kernel: 9f20: 8017ea84 00000800 8c4db6c0 00b1e3a1 aea89f78 00000800 aea89f74 aea89f48 Sep 03 10:28:37 streambox kernel: 9f40: 8026996c 80268aa0 000001e6 80289034 aea89f74 00000000 00000000 8c4db6c0 Sep 03 10:28:37 streambox kernel: 9f60: 8c4db6c0 00000800 aea89fa4 aea89f78 8026ab28 802698e0 00000000 00000000 Sep 03 10:28:37 streambox kernel: 9f80: 00b1e378 76f15f10 7e801748 00000003 80107fa4 aea88000 00000000 aea89fa8 Sep 03 10:28:37 streambox kernel: 9fa0: 80107e20 8026aae8 00b1e378 76f15f10 00000018 00b1e3a1 00000800 00000011 Sep 03 10:28:37 streambox kernel: 9fc0: 00b1e378 76f15f10 7e801748 00000003 76f15f10 00b190a0 00b19088 7e801a1c Sep 03 10:28:37 streambox kernel: 9fe0: 00000000 7e801724 004e83f0 76ebec8c 60000010 00000018 00000000 00000000 Sep 03 10:28:37 streambox kernel: [<805fff80>] (skb_release_data) from [<80600074>] (skb_release_all+0x30/0x34) Sep 03 10:28:37 streambox kernel: [<80600074>] (skb_release_all) from [<806001f8>] (consume_skb+0x38/0xe4) Sep 03 10:28:37 streambox kernel: [<806001f8>] (consume_skb) from [<806c2264>] (unix_stream_read_generic+0x760/0x834) Sep 03 10:28:37 streambox kernel: [<806c2264>] (unix_stream_read_generic) from [<806c2410>] (unix_stream_recvmsg+0x48/0x54) Sep 03 10:28:37 streambox kernel: [<806c2410>] (unix_stream_recvmsg) from [<805f71e0>] (sock_read_iter+0x9c/0xd0) Sep 03 10:28:37 streambox kernel: [<805f71e0>] (sock_read_iter) from [<80268b64>] (__vfs_read+0xd0/0x120) Sep 03 10:28:37 streambox kernel: [<80268b64>] (__vfs_read) from [<8026996c>] (vfs_read+0x98/0x16c) Sep 03 10:28:37 streambox kernel: [<8026996c>] (vfs_read) from [<8026ab28>] (SyS_read+0x4c/0xa0) Sep 03 10:28:37 streambox kernel: [<8026ab28>] (SyS_read) from [<80107e20>] (ret_fast_syscall+0x0/0x1c) Sep 03 10:28:37 streambox kernel: Code: 1afffffa f57ff05b e3510000 189da8f0 (e5d73000) Sep 03 10:28:37 streambox kernel: ---[ end trace 2adf088ca73ef679 ]--- Sep 03 10:28:37 streambox systemd[1]: systemd-journald.service has no holdoff time, scheduling restart. Sep 03 10:28:37 streambox systemd[1]: Stopping Journal Service... Sep 03 10:28:37 streambox systemd[1]: Starting Journal Service... Sep 03 10:28:37 streambox systemd[1]: Started Journal Service. Sep 03 10:28:37 streambox systemd-journal[24430]: Journal started Sep 03 10:28:37 streambox sudo[24429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 10:28:37 streambox systemd[1]: systemd-journald.service: main process exited, code=killed, status=11/SEGV Sep 03 10:28:37 streambox systemd[1]: Unit systemd-journald.service entered failed state. Sep 03 10:28:37 streambox systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Sep 03 10:28:37 streambox sudo[24429]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 10:28:37 streambox sudo[24429]: pam_unix(sudo:session): session closed for user root Sep 03 10:28:37 streambox systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Sep 03 10:28:38 streambox sudo[24453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-03 10:27 Sep 03 10:28:38 streambox sudo[24453]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="799336af25678656067f01b19a4048fba5591473" VOLUMIO_FE_VERSION="1044fb4648482758fb73a632ab8b178932421a9f" VOLUMIO_BE_VERSION="4c882986be7d34754aec510d8c0d17f2498fb048" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="projectsbus2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 12 12:59:41 CEST 2018" VOLUMIO_VERSION="1.001" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Pro-Ject Audio Systems" VOLUMIO_MODEL="Stream Box S2 ultra" VOLUMIO_VENDOR_MODEL="Pro-Ject Stream Box S2 ultra" VOLUMIO_HASH="74da29dd369f08fdbf453485f634c701"