-- Logs begin at Thu 2024-08-15 14:14:40 UTC, end at Fri 2024-08-23 08:43:25 UTC. -- Aug 23 08:42:00 stream-box-s2-ultra raatdaemon[1522]: [0005175] [t1967854688] 1538.864 TRACE [transport/volumio] got update status Aug 23 08:42:01 stream-box-s2-ultra raatdaemon[1522]: [0005176] [t1967854688] 1539.867 TRACE [transport/volumio] got update status Aug 23 08:42:02 stream-box-s2-ultra raatdaemon[1522]: [0005177] [t1967854688] 1540.883 TRACE [transport/volumio] got update status Aug 23 08:42:03 stream-box-s2-ultra raatdaemon[1522]: [0005178] [t1967854688] 1541.878 TRACE [transport/volumio] got update status Aug 23 08:42:04 stream-box-s2-ultra raatdaemon[1522]: [0005179] [t1967854688] 1542.883 TRACE [transport/volumio] got update status Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005180] [t1992660064] 1543.864 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005181] [t1992660064] 1543.864 TRACE [discovery] stopping Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005182] [t1992660064] 1543.864 TRACE closing multicast Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005183] [t1992660064] 1543.865 TRACE [discovery] closing unicast send socket Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005184] [t1992660064] 1543.865 TRACE [discovery] closing unicast recv socket Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005185] [t1992660064] 1543.865 TRACE [discovery] starting Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005186] [t1992660064] 1543.865 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005187] [t1992660064] 1543.865 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:55333 Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005188] [t1992660064] 1543.865 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005189] [t1992660064] 1543.865 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:40983 Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005190] [t1992660064] 1543.865 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005191] [t1992660064] 1543.865 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=8F1851FE-E188-8A91-DEE5-164F46C8A2A9, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:42:05 stream-box-s2-ultra raatdaemon[1522]: [0005192] [t1967854688] 1543.886 TRACE [transport/volumio] got update status Aug 23 08:42:06 stream-box-s2-ultra raatdaemon[1522]: [0005193] [t1967854688] 1544.891 TRACE [transport/volumio] got update status Aug 23 08:42:07 stream-box-s2-ultra raatdaemon[1522]: [0005194] [t1967854688] 1545.896 TRACE [transport/volumio] got update status Aug 23 08:42:08 stream-box-s2-ultra raatdaemon[1522]: [0005195] [t1967854688] 1546.902 TRACE [transport/volumio] got update status Aug 23 08:42:09 stream-box-s2-ultra raatdaemon[1522]: [0005196] [t1967854688] 1547.904 TRACE [transport/volumio] got update status Aug 23 08:42:10 stream-box-s2-ultra raatdaemon[1522]: [0005197] [t1967854688] 1548.909 TRACE [transport/volumio] got update status Aug 23 08:42:11 stream-box-s2-ultra raatdaemon[1522]: [0005198] [t1967854688] 1549.914 TRACE [transport/volumio] got update status Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005199] [t1992660064] 1550.634 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005200] [t1992660064] 1550.634 TRACE [discovery] stopping Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005201] [t1992660064] 1550.634 TRACE closing multicast Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005202] [t1992660064] 1550.635 TRACE [discovery] closing unicast send socket Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005203] [t1992660064] 1550.635 TRACE [discovery] closing unicast recv socket Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005204] [t1992660064] 1550.635 TRACE [discovery] starting Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005205] [t1992660064] 1550.635 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005206] [t1992660064] 1550.635 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:36720 Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005207] [t1992660064] 1550.635 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005208] [t1992660064] 1550.635 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:57898 Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005209] [t1992660064] 1550.635 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005210] [t1992660064] 1550.635 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=1DB285C8-8EBD-3F23-D66E-78CC3FC7F232, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:42:12 stream-box-s2-ultra raatdaemon[1522]: [0005211] [t1967854688] 1550.920 TRACE [transport/volumio] got update status Aug 23 08:42:13 stream-box-s2-ultra raatdaemon[1522]: [0005212] [t1967854688] 1551.923 TRACE [transport/volumio] got update status Aug 23 08:42:14 stream-box-s2-ultra raatdaemon[1522]: [0005213] [t1967854688] 1552.926 TRACE [transport/volumio] got update status Aug 23 08:42:15 stream-box-s2-ultra raatdaemon[1522]: [0005214] [t1967854688] 1553.932 TRACE [transport/volumio] got update status Aug 23 08:42:16 stream-box-s2-ultra raatdaemon[1522]: [0005215] [t1967854688] 1554.938 TRACE [transport/volumio] got update status Aug 23 08:42:17 stream-box-s2-ultra raatdaemon[1522]: [0005216] [t1967854688] 1555.952 TRACE [transport/volumio] got update status Aug 23 08:42:18 stream-box-s2-ultra raatdaemon[1522]: [0005217] [t1967854688] 1556.944 TRACE [transport/volumio] got update status Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005218] [t1992660064] 1557.415 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005219] [t1992660064] 1557.415 TRACE [discovery] stopping Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005220] [t1992660064] 1557.415 TRACE closing multicast Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005221] [t1992660064] 1557.416 TRACE [discovery] closing unicast send socket Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005222] [t1992660064] 1557.416 TRACE [discovery] closing unicast recv socket Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005223] [t1992660064] 1557.416 TRACE [discovery] starting Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005224] [t1992660064] 1557.417 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005225] [t1992660064] 1557.417 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:39847 Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005226] [t1992660064] 1557.417 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005227] [t1992660064] 1557.417 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:49377 Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005228] [t1992660064] 1557.417 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005229] [t1992660064] 1557.418 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=29946A98-07E6-3969-DB32-DAB4602C7EA0, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005230] [t1967854688] 1557.949 TRACE [transport/volumio] got update status Aug 23 08:42:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:42:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:42:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:42:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:42:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:42:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:42:19 stream-box-s2-ultra sudo[19583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:42:19 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005232] [t1996021760] 1557.983 TRACE [VRST] got volume notify Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005233] [t1996021760] 1557.983 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005235] [t1996021760] 1557.984 TRACE [VRST] got update signal path Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005236] [t1996021760] 1557.984 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005237] [t1996021760] 1557.985 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005238] [t1967854688] 1557.985 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:42:19 stream-box-s2-ultra raatdaemon[1522]: [0005239] [t1967854688] 1557.985 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [202] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:42:19 stream-box-s2-ultra sudo[19583]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:42:19 stream-box-s2-ultra sudo[19583]: pam_unix(sudo:session): session closed for user root Aug 23 08:42:20 stream-box-s2-ultra raatdaemon[1522]: [0005240] [t1967854688] 1558.953 TRACE [transport/volumio] got update status Aug 23 08:42:21 stream-box-s2-ultra raatdaemon[1522]: [0005241] [t1967854688] 1559.959 TRACE [transport/volumio] got update status Aug 23 08:42:22 stream-box-s2-ultra raatdaemon[1522]: [0005242] [t1967854688] 1560.968 TRACE [transport/volumio] got update status Aug 23 08:42:23 stream-box-s2-ultra raatdaemon[1522]: [0005243] [t1967854688] 1561.973 TRACE [transport/volumio] got update status Aug 23 08:42:24 stream-box-s2-ultra raatdaemon[1522]: [0005244] [t1967854688] 1562.977 TRACE [transport/volumio] got update status Aug 23 08:42:25 stream-box-s2-ultra raatdaemon[1522]: [0005245] [t1967854688] 1563.981 TRACE [transport/volumio] got update status Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005246] [t1992660064] 1564.185 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005247] [t1992660064] 1564.185 TRACE [discovery] stopping Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005248] [t1992660064] 1564.185 TRACE closing multicast Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005249] [t1992660064] 1564.185 TRACE [discovery] closing unicast send socket Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005250] [t1992660064] 1564.185 TRACE [discovery] closing unicast recv socket Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005251] [t1992660064] 1564.185 TRACE [discovery] starting Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005252] [t1992660064] 1564.186 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005253] [t1992660064] 1564.186 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:44694 Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005254] [t1992660064] 1564.186 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005255] [t1992660064] 1564.186 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:34444 Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005256] [t1992660064] 1564.186 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005257] [t1992660064] 1564.186 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=58E5F2C6-78FE-2894-C9DD-A2FE78AA78CF, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:42:26 stream-box-s2-ultra raatdaemon[1522]: [0005258] [t1967854688] 1564.993 TRACE [transport/volumio] got update status Aug 23 08:42:27 stream-box-s2-ultra raatdaemon[1522]: [0005259] [t1967854688] 1565.990 TRACE [transport/volumio] got update status Aug 23 08:42:28 stream-box-s2-ultra raatdaemon[1522]: [0005260] [t1967854688] 1566.995 TRACE [transport/volumio] got update status Aug 23 08:42:29 stream-box-s2-ultra raatdaemon[1522]: [0005261] [t1967854688] 1567.998 TRACE [transport/volumio] got update status Aug 23 08:42:30 stream-box-s2-ultra raatdaemon[1522]: [0005262] [t1967854688] 1569.007 TRACE [transport/volumio] got update status Aug 23 08:42:31 stream-box-s2-ultra raatdaemon[1522]: [0005263] [t1967854688] 1570.008 TRACE [transport/volumio] got update status Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005264] [t1992660064] 1570.965 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005265] [t1992660064] 1570.966 TRACE [discovery] stopping Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005266] [t1992660064] 1570.966 TRACE closing multicast Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005267] [t1992660064] 1570.966 TRACE [discovery] closing unicast send socket Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005268] [t1992660064] 1570.966 TRACE [discovery] closing unicast recv socket Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005269] [t1992660064] 1570.966 TRACE [discovery] starting Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005270] [t1992660064] 1570.966 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005271] [t1992660064] 1570.966 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:45427 Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005272] [t1992660064] 1570.966 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005273] [t1992660064] 1570.966 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:57303 Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005274] [t1992660064] 1570.967 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005275] [t1992660064] 1570.967 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=FA560A02-5A45-A9E7-F635-9DEF1DA0668B, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:42:32 stream-box-s2-ultra raatdaemon[1522]: [0005276] [t1967854688] 1571.010 TRACE [transport/volumio] got update status Aug 23 08:42:33 stream-box-s2-ultra raatdaemon[1522]: [0005277] [t1967854688] 1572.015 TRACE [transport/volumio] got update status Aug 23 08:42:34 stream-box-s2-ultra raatdaemon[1522]: [0005278] [t1967854688] 1573.018 TRACE [transport/volumio] got update status Aug 23 08:42:35 stream-box-s2-ultra raatdaemon[1522]: [0005279] [t1967854688] 1574.032 TRACE [transport/volumio] got update status Aug 23 08:42:36 stream-box-s2-ultra raatdaemon[1522]: [0005280] [t1967854688] 1575.026 TRACE [transport/volumio] got update status Aug 23 08:42:37 stream-box-s2-ultra raatdaemon[1522]: [0005281] [t1967854688] 1576.036 TRACE [transport/volumio] got update status Aug 23 08:42:38 stream-box-s2-ultra raatdaemon[1522]: [0005282] [t1967854688] 1577.050 TRACE [transport/volumio] got update status Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005283] [t1992660064] 1577.735 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005284] [t1992660064] 1577.737 TRACE [discovery] stopping Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005285] [t1992660064] 1577.737 TRACE closing multicast Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005286] [t1992660064] 1577.738 TRACE [discovery] closing unicast send socket Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005287] [t1992660064] 1577.739 TRACE [discovery] closing unicast recv socket Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005288] [t1992660064] 1577.740 TRACE [discovery] starting Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005289] [t1992660064] 1577.741 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005290] [t1992660064] 1577.742 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:54056 Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005291] [t1992660064] 1577.743 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005292] [t1992660064] 1577.743 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:43400 Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005293] [t1992660064] 1577.743 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005294] [t1992660064] 1577.743 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=D4CE7818-6DEE-60A1-8721-BAD46841D427, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005295] [t1967854688] 1578.043 TRACE [transport/volumio] got update status Aug 23 08:42:39 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:42:39 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:42:39 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:42:39 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:42:39 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:42:39 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005297] [t1996021760] 1578.077 TRACE [VRST] got volume notify Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005298] [t1996021760] 1578.077 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:42:39 stream-box-s2-ultra sudo[19813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:42:39 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005300] [t1996021760] 1578.078 TRACE [VRST] got update signal path Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005301] [t1996021760] 1578.078 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005302] [t1996021760] 1578.078 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005303] [t1967854688] 1578.078 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:42:39 stream-box-s2-ultra raatdaemon[1522]: [0005304] [t1967854688] 1578.078 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [202] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:42:39 stream-box-s2-ultra sudo[19813]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:42:39 stream-box-s2-ultra sudo[19813]: pam_unix(sudo:session): session closed for user root Aug 23 08:42:40 stream-box-s2-ultra raatdaemon[1522]: [0005305] [t1967854688] 1579.048 TRACE [transport/volumio] got update status Aug 23 08:42:41 stream-box-s2-ultra raatdaemon[1522]: [0005306] [t1967854688] 1579.852 TRACE [transport/volumio] got update status Aug 23 08:42:42 stream-box-s2-ultra raatdaemon[1522]: [0005307] [t1967854688] 1580.855 TRACE [transport/volumio] got update status Aug 23 08:42:43 stream-box-s2-ultra raatdaemon[1522]: [0005308] [t1967854688] 1581.859 TRACE [transport/volumio] got update status Aug 23 08:42:44 stream-box-s2-ultra raatdaemon[1522]: [0005309] [t1967854688] 1582.863 TRACE [transport/volumio] got update status Aug 23 08:42:45 stream-box-s2-ultra raatdaemon[1522]: [0005310] [t1967854688] 1583.879 TRACE [transport/volumio] got update status Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005311] [t1992660064] 1584.506 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005312] [t1992660064] 1584.506 TRACE [discovery] stopping Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005313] [t1992660064] 1584.506 TRACE closing multicast Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005314] [t1992660064] 1584.506 TRACE [discovery] closing unicast send socket Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005315] [t1992660064] 1584.506 TRACE [discovery] closing unicast recv socket Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005316] [t1992660064] 1584.506 TRACE [discovery] starting Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005317] [t1992660064] 1584.507 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005318] [t1992660064] 1584.507 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:53521 Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005319] [t1992660064] 1584.508 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005320] [t1992660064] 1584.508 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:50179 Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005321] [t1992660064] 1584.508 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005322] [t1992660064] 1584.508 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=391703DD-5CE0-0AC8-582F-C0CB2DE40A36, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:42:46 stream-box-s2-ultra raatdaemon[1522]: [0005323] [t1967854688] 1584.884 TRACE [transport/volumio] got update status Aug 23 08:42:47 stream-box-s2-ultra raatdaemon[1522]: [0005324] [t1967854688] 1585.889 TRACE [transport/volumio] got update status Aug 23 08:42:48 stream-box-s2-ultra raatdaemon[1522]: [0005325] [t1967854688] 1586.893 TRACE [transport/volumio] got update status Aug 23 08:42:49 stream-box-s2-ultra raatdaemon[1522]: [0005326] [t1967854688] 1587.898 TRACE [transport/volumio] got update status Aug 23 08:42:50 stream-box-s2-ultra raatdaemon[1522]: [0005327] [t1967854688] 1588.903 TRACE [transport/volumio] got update status Aug 23 08:42:51 stream-box-s2-ultra raatdaemon[1522]: [0005328] [t1967854688] 1589.908 TRACE [transport/volumio] got update status Aug 23 08:42:52 stream-box-s2-ultra raatdaemon[1522]: [0005329] [t1967854688] 1590.932 TRACE [transport/volumio] got update status Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005330] [t1992660064] 1591.285 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005331] [t1992660064] 1591.285 TRACE [discovery] stopping Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005332] [t1992660064] 1591.285 TRACE closing multicast Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005333] [t1992660064] 1591.285 TRACE [discovery] closing unicast send socket Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005334] [t1992660064] 1591.285 TRACE [discovery] closing unicast recv socket Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005335] [t1992660064] 1591.285 TRACE [discovery] starting Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005336] [t1992660064] 1591.286 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005337] [t1992660064] 1591.286 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:42745 Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005338] [t1992660064] 1591.286 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005339] [t1992660064] 1591.286 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:53689 Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005340] [t1992660064] 1591.286 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005341] [t1992660064] 1591.286 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=4E27901F-0305-5F02-FF6A-20ABA640E1C9, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:42:53 stream-box-s2-ultra raatdaemon[1522]: [0005342] [t1967854688] 1591.936 TRACE [transport/volumio] got update status Aug 23 08:42:54 stream-box-s2-ultra raatdaemon[1522]: [0005343] [t1967854688] 1592.942 TRACE [transport/volumio] got update status Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005344] [t1967854688] 1593.380 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1198] {"request":"stop"} Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005345] [t1967854688] 1593.381 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [955] {"status":"Stopped"} Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005346] [t1967854688] 1593.381 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1198] {"status":"Success"} Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005347] [t1967854688] 1593.383 TRACE [transport/volumio] got update status Aug 23 08:42:55 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:42:55 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:42:55 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:42:55 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:42:55 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:42:55 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005349] [t1996021760] 1593.420 TRACE [VRST] got volume notify Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005350] [t1996021760] 1593.420 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:42:55 stream-box-s2-ultra sudo[19982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:42:55 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005352] [t1996021760] 1593.421 TRACE [VRST] got update signal path Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005353] [t1996021760] 1593.421 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005354] [t1996021760] 1593.421 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005355] [t1967854688] 1593.421 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:42:55 stream-box-s2-ultra raatdaemon[1522]: [0005356] [t1967854688] 1593.422 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [202] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:42:55 stream-box-s2-ultra sudo[19982]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:42:55 stream-box-s2-ultra sudo[19982]: pam_unix(sudo:session): session closed for user root Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005357] [t1992660064] 1598.075 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005358] [t1992660064] 1598.075 TRACE [discovery] stopping Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005359] [t1992660064] 1598.075 TRACE closing multicast Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005360] [t1992660064] 1598.075 TRACE [discovery] closing unicast send socket Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005361] [t1992660064] 1598.076 TRACE [discovery] closing unicast recv socket Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005362] [t1992660064] 1598.076 TRACE [discovery] starting Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005363] [t1992660064] 1598.076 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005364] [t1992660064] 1598.076 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:55899 Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005365] [t1992660064] 1598.076 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005366] [t1992660064] 1598.076 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:56260 Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005367] [t1992660064] 1598.076 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:42:59 stream-box-s2-ultra raatdaemon[1522]: [0005368] [t1992660064] 1598.076 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=8F559820-BA00-AB6A-F831-233D10F45014, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:43:00 stream-box-s2-ultra raatdaemon[1522]: [0005369] [t1967854688] 1598.387 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1200] {"request":"end_stream"} Aug 23 08:43:00 stream-box-s2-ultra raatdaemon[1522]: [0005370] [t1967854688] 1598.387 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [955] {"status":"Ended"} Aug 23 08:43:00 stream-box-s2-ultra raatdaemon[1522]: [0005371] [t1967854688] 1598.388 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1200] {"status":"Success"} Aug 23 08:43:00 stream-box-s2-ultra raatdaemon[1522]: [0005372] [t1967854688] 1598.390 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1201] {"request":"teardown"} Aug 23 08:43:00 stream-box-s2-ultra raatdaemon[1522]: [0005373] [t1967854688] 1598.390 TRACE [alsa] teardown Aug 23 08:43:00 stream-box-s2-ultra raatdaemon[1522]: [0005374] [t1967854688] 1598.400 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [202] {"status":"Teardown"} Aug 23 08:43:00 stream-box-s2-ultra raatdaemon[1522]: [0005375] [t1967854688] 1598.430 TRACE [transport/volumio] got update status Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005376] [t1967854688] 1602.007 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1203] {"request":"request_source"} Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005377] [t1967854688] 1602.007 TRACE [source_selection/volumio] requesting source Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: [VRCP] already in control, acknowledged Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [81B blob data] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005379] [t1996021760] 1602.009 TRACE [source_selection/volumio] source acquired Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005380] [t1967854688] 1602.009 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] source selection state changed: selected Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005381] [t1967854688] 1602.009 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [5] {"status":"Success","controls":{"volume":{"mute":false,"max":100.0,"type":"number","step":1.0,"info":{"config":{"mute":false,"type":"alsa","mode":"number","value":100,"device":"hw:5"}},"value":64,"min":0.0},"source":{"value":"selected","info":{"is_standby_supported":false}}},"action":"Changed"} Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005382] [t1967854688] 1602.010 TRACE [transport/volumio] got update artwork Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005383] [t1967854688] 1602.010 TRACE - MD5 for artwork: 699b7efd6d716f304c15cbc2625c849f Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005384] [t1967854688] 1602.011 TRACE - Full path for artwork: /tmp/raat/albumart/699b7efd6d716f304c15cbc2625c849f.jpg Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005385] [t1967854688] 1602.012 TRACE [transport/volumio] got update status Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005386] [t1967854688] 1602.016 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1203] {"status":"Success"} Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005388] [t1996021760] 1602.042 TRACE [VRST] got volume notify Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005389] [t1996021760] 1602.042 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:03 stream-box-s2-ultra sudo[20087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005391] [t1996021760] 1602.043 TRACE [VRST] got update signal path Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005392] [t1996021760] 1602.043 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005393] [t1996021760] 1602.043 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005394] [t1967854688] 1602.044 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:03 stream-box-s2-ultra sudo[20087]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:03 stream-box-s2-ultra sudo[20087]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005395] [t1967854688] 1602.076 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1204] {"request":"request_source"} Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: [VRCP] already in control, acknowledged Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005396] [t1967854688] 1602.076 TRACE [source_selection/volumio] requesting source Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [81B blob data] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005398] [t1996021760] 1602.077 TRACE [source_selection/volumio] source acquired Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005399] [t1967854688] 1602.077 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] source selection state changed: selected Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005400] [t1967854688] 1602.078 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [5] {"status":"Success","controls":{"volume":{"mute":false,"max":100.0,"type":"number","step":1.0,"info":{"config":{"mute":false,"type":"alsa","mode":"number","value":100,"device":"hw:5"}},"value":64,"min":0.0},"source":{"value":"selected","info":{"is_standby_supported":false}}},"action":"Changed"} Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005401] [t1967854688] 1602.078 TRACE [transport/volumio] got update artwork Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005402] [t1967854688] 1602.079 TRACE - MD5 for artwork: 699b7efd6d716f304c15cbc2625c849f Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005403] [t1967854688] 1602.079 TRACE - Full path for artwork: /tmp/raat/albumart/699b7efd6d716f304c15cbc2625c849f.jpg Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005404] [t1967854688] 1602.080 TRACE [transport/volumio] got update status Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005405] [t1967854688] 1602.080 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1204] {"status":"Success"} Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005407] [t1996021760] 1602.110 TRACE [VRST] got volume notify Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005408] [t1996021760] 1602.110 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:03 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005410] [t1996021760] 1602.110 TRACE [VRST] got update signal path Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005411] [t1996021760] 1602.110 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005412] [t1996021760] 1602.111 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:03 stream-box-s2-ultra raatdaemon[1522]: [0005413] [t1967854688] 1602.111 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:03 stream-box-s2-ultra sudo[20097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:04 stream-box-s2-ultra sudo[20097]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:04 stream-box-s2-ultra sudo[20097]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [0005414] [t1967854688] 1602.392 TRACE [transport/volumio] got update status Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: [VRCP] RAAT stopped playing Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [0005415] [t1967854688] 1602.395 TRACE [transport/volumio] got update status Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: info: CorePlayQueue::getTrack 0 Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [0005417] [t1996021760] 1602.425 TRACE [VRST] got volume notify Aug 23 08:43:04 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [0005418] [t1996021760] 1602.425 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [0005420] [t1996021760] 1602.426 TRACE [VRST] got update signal path Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [0005421] [t1996021760] 1602.426 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [0005422] [t1996021760] 1602.426 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:04 stream-box-s2-ultra raatdaemon[1522]: [0005423] [t1967854688] 1602.426 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:04 stream-box-s2-ultra sudo[20108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:04 stream-box-s2-ultra sudo[20108]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:04 stream-box-s2-ultra sudo[20108]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005424] [t1967854688] 1603.125 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1207] {"request":"update_artwork","mimetype":"image/jpeg"} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005425] [t1967854688] 1603.125 TRACE [transport/volumio] got update artwork Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005426] [t1967854688] 1603.126 TRACE - MD5 for artwork: da29c3e2ff544a9c6b7f0deeff7dae09 Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005427] [t1967854688] 1603.126 TRACE - Full path for artwork: /tmp/raat/albumart/da29c3e2ff544a9c6b7f0deeff7dae09.jpg Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005428] [t1967854688] 1603.126 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1207] {"status":"Success"} Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CorePlayQueue::getTrack 0 Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005430] [t1996021760] 1603.156 TRACE [VRST] got volume notify Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005431] [t1996021760] 1603.156 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:05 stream-box-s2-ultra sudo[20128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005433] [t1996021760] 1603.157 TRACE [VRST] got update signal path Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005434] [t1996021760] 1603.157 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005435] [t1996021760] 1603.157 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005436] [t1967854688] 1603.157 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:05 stream-box-s2-ultra sudo[20128]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:05 stream-box-s2-ultra sudo[20128]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005437] [t1967854688] 1603.688 TRACE [transport/volumio] got update status Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005438] [t1967854688] 1603.689 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1209] {"request":"setup","format":{"sample_type":"pcm","sample_rate":88200,"bits_per_sample":24,"channels":2,"sample_subtype":"mqa_core","mqa_original_sample_rate":44100}} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005439] [t1967854688] 1603.689 TRACE alsa output setup: format is pcm 88200/24/2 Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005440] [t1967854688] 1603.689 TRACE opening [hw:5,0] 88200/24/2 Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005441] [t1967854688] 1603.689 TRACE [ALSA] [hw:5,0] Audio content is MQA_CORE, ORFS=44100 Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005442] [t1967854688] 1603.690 TRACE prefer larger samples = 0 Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005443] [t1967854688] 1603.690 TRACE [ALSA] [hw:5,0] using hw pcmformat S32_LE bitspersample 24 Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005444] [t1967854688] 1603.690 TRACE [ALSA] [hw:5,0] Requesting 2 periods Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005445] [t1967854688] 1603.690 TRACE [ALSA] [hw:5,0] Requesting 3528 frames/buffer (1764 frames/period) Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005446] [t1967854688] 1603.690 TRACE [ALSA] [hw:5,0] Initialized with 2 periods with 1764 frames/period and 3528 frames/buffer Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005447] [t1955591264] 1603.692 WARNING sched_setscheduler failed: Operation not permitted Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005448] [t1955591264] 1603.693 TRACE samples per buf 1764 ns per buf 20000000 samplerate 88200 Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005449] [t1955591264] 1603.693 TRACE 1764 samples per buf, 10584 bytes per buf, 14112 bytes per hwbuf Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005450] [t1955591264] 1603.693 TRACE [output/alsa] [hw:5,0] waiting for device to be ready Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005451] [t1955591264] 1603.693 TRACE [output/alsa] [hw:5,0] device is ready Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005452] [t1967854688] 1603.693 TRACE [transport/volumio] got update status Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005453] [t1967854688] 1603.693 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005454] [t1967854688] 1603.694 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005456] [t1996021760] 1603.723 TRACE [VRST] got volume notify Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005457] [t1996021760] 1603.723 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:05 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005459] [t1996021760] 1603.724 TRACE [VRST] got update signal path Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005460] [t1996021760] 1603.724 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005461] [t1996021760] 1603.724 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005462] [t1967854688] 1603.724 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005463] [t1967854688] 1603.725 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:05 stream-box-s2-ultra sudo[20139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005464] [t1967854688] 1603.734 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"Success","audio_port_tcp":44505,"clock_port":47318,"audio_port":0} Aug 23 08:43:05 stream-box-s2-ultra sudo[20139]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:05 stream-box-s2-ultra sudo[20139]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005465] [t1967854688] 1603.763 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1211] {"request":"stream","stream_id":119121519,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005466] [t1967854688] 1603.763 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1211] {"status":"Buffering"} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005467] [t1967854688] 1603.925 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1211] {"status":"Ready"} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005468] [t1967854688] 1604.064 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1212] {"request":"start","min_offset":60884353,"stream_sample":0} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005469] [t1967854688] 1604.065 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] Doing ASAP start based on current device clock (min_offset=60884353) Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005470] [t1967854688] 1604.065 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1211] {"status":"Playing"} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005471] [t1967854688] 1604.067 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1212] {"status":"Success","time":988530671162} Aug 23 08:43:05 stream-box-s2-ultra raatdaemon[1522]: [0005472] [t1955591264] 1604.074 TRACE starting playback: now (988520000000ns) + ns_per_buf(20000000ns) = 988540000000ns > 988530671162ns streamsample=0 Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005473] [t1967854688] 1604.174 TRACE [transport/volumio] got update status Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005474] [t1992660064] 1604.855 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005475] [t1992660064] 1604.856 TRACE [discovery] stopping Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005476] [t1992660064] 1604.856 TRACE closing multicast Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005477] [t1992660064] 1604.856 TRACE [discovery] closing unicast send socket Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005478] [t1992660064] 1604.856 TRACE [discovery] closing unicast recv socket Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005479] [t1992660064] 1604.856 TRACE [discovery] starting Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005480] [t1992660064] 1604.856 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005481] [t1992660064] 1604.856 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:38570 Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005482] [t1992660064] 1604.856 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005483] [t1992660064] 1604.856 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:41594 Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005484] [t1992660064] 1604.857 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:43:06 stream-box-s2-ultra raatdaemon[1522]: [0005485] [t1992660064] 1604.857 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=814D007E-CF15-A2D4-C590-22349511A74E, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:43:07 stream-box-s2-ultra raatdaemon[1522]: [0005486] [t1967854688] 1605.180 TRACE [transport/volumio] got update status Aug 23 08:43:08 stream-box-s2-ultra raatdaemon[1522]: [0005487] [t1967854688] 1606.183 TRACE [transport/volumio] got update status Aug 23 08:43:09 stream-box-s2-ultra raatdaemon[1522]: [0005488] [t1967854688] 1607.190 TRACE [transport/volumio] got update status Aug 23 08:43:10 stream-box-s2-ultra raatdaemon[1522]: [0005489] [t1967854688] 1608.189 TRACE [transport/volumio] got update status Aug 23 08:43:11 stream-box-s2-ultra raatdaemon[1522]: [0005490] [t1967854688] 1609.194 TRACE [transport/volumio] got update status Aug 23 08:43:12 stream-box-s2-ultra raatdaemon[1522]: [0005491] [t1967854688] 1610.199 TRACE [transport/volumio] got update status Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005492] [t1967854688] 1611.202 TRACE [transport/volumio] got update status Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005493] [t1992660064] 1611.645 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005494] [t1992660064] 1611.646 TRACE [discovery] stopping Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005495] [t1992660064] 1611.647 TRACE closing multicast Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005496] [t1992660064] 1611.648 TRACE [discovery] closing unicast send socket Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005497] [t1992660064] 1611.649 TRACE [discovery] closing unicast recv socket Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005498] [t1992660064] 1611.649 TRACE [discovery] starting Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005499] [t1992660064] 1611.650 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005500] [t1992660064] 1611.650 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:48967 Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005501] [t1992660064] 1611.650 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005502] [t1992660064] 1611.650 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:42323 Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005503] [t1992660064] 1611.650 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:43:13 stream-box-s2-ultra raatdaemon[1522]: [0005504] [t1992660064] 1611.650 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=54C776A6-4F83-0419-B383-4EAD79EBF01A, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:43:14 stream-box-s2-ultra raatdaemon[1522]: [0005505] [t1967854688] 1612.208 TRACE [transport/volumio] got update status Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005506] [t1967854688] 1613.210 TRACE [transport/volumio] got update status Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005507] [t1967854688] 1613.557 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1223] {"request":"end_stream"} Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005508] [t1967854688] 1613.557 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1211] {"status":"Ended"} Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005509] [t1967854688] 1613.557 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1223] {"status":"Success"} Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005510] [t1967854688] 1613.559 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1224] {"request":"request_source"} Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005511] [t1967854688] 1613.559 TRACE [source_selection/volumio] requesting source Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [81B blob data] Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005513] [t1996021760] 1613.560 TRACE [source_selection/volumio] source acquired Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: [VRCP] already in control, acknowledged Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005514] [t1967854688] 1613.561 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] source selection state changed: selected Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005515] [t1967854688] 1613.562 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [5] {"status":"Success","controls":{"volume":{"mute":false,"max":100.0,"type":"number","step":1.0,"info":{"config":{"mute":false,"type":"alsa","mode":"number","value":100,"device":"hw:5"}},"value":64,"min":0.0},"source":{"value":"selected","info":{"is_standby_supported":false}}},"action":"Changed"} Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005516] [t1967854688] 1613.562 TRACE [transport/volumio] got update artwork Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005517] [t1967854688] 1613.563 TRACE - MD5 for artwork: da29c3e2ff544a9c6b7f0deeff7dae09 Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005518] [t1967854688] 1613.563 TRACE - Full path for artwork: /tmp/raat/albumart/da29c3e2ff544a9c6b7f0deeff7dae09.jpg Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005519] [t1967854688] 1613.570 TRACE [transport/volumio] got update status Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005520] [t1967854688] 1613.570 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1224] {"status":"Success"} Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005522] [t1996021760] 1613.597 TRACE [VRST] got volume notify Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005523] [t1996021760] 1613.597 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:15 stream-box-s2-ultra sudo[20258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005525] [t1996021760] 1613.598 TRACE [VRST] got update signal path Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005526] [t1996021760] 1613.598 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005527] [t1996021760] 1613.598 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005528] [t1967854688] 1613.598 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005529] [t1967854688] 1613.599 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005530] [t1967854688] 1613.602 TRACE [transport/volumio] got update status Aug 23 08:43:15 stream-box-s2-ultra sudo[20258]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:15 stream-box-s2-ultra sudo[20258]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005531] [t1967854688] 1613.744 TRACE [transport/volumio] got update status Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: [VRCP] RAAT stopped playing Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005532] [t1967854688] 1613.747 TRACE [transport/volumio] got update status Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: CorePlayQueue::getTrack 0 Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Aug 23 08:43:15 stream-box-s2-ultra sudo[20268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:15 stream-box-s2-ultra sudo[20268]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:15 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005534] [t1996021760] 1613.791 TRACE [VRST] got volume notify Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005535] [t1996021760] 1613.791 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005537] [t1996021760] 1613.792 TRACE [VRST] got update signal path Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005538] [t1996021760] 1613.792 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005539] [t1996021760] 1613.792 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005540] [t1967854688] 1613.792 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:15 stream-box-s2-ultra raatdaemon[1522]: [0005541] [t1967854688] 1613.793 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:15 stream-box-s2-ultra sudo[20268]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005542] [t1967854688] 1614.206 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1228] {"request":"update_artwork","mimetype":"image/jpeg"} Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005543] [t1967854688] 1614.206 TRACE [transport/volumio] got update artwork Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005544] [t1967854688] 1614.207 TRACE - MD5 for artwork: 4b0c21ab4a013eb59f37340b26c0ad52 Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005545] [t1967854688] 1614.207 TRACE - Full path for artwork: /tmp/raat/albumart/4b0c21ab4a013eb59f37340b26c0ad52.jpg Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005546] [t1967854688] 1614.207 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1228] {"status":"Success"} Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CorePlayQueue::getTrack 0 Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Aug 23 08:43:16 stream-box-s2-ultra sudo[20279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005548] [t1996021760] 1614.244 TRACE [VRST] got volume notify Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005549] [t1996021760] 1614.244 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005551] [t1996021760] 1614.245 TRACE [VRST] got update signal path Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005552] [t1996021760] 1614.245 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005553] [t1996021760] 1614.245 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005554] [t1967854688] 1614.245 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005555] [t1967854688] 1614.245 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:16 stream-box-s2-ultra sudo[20279]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:16 stream-box-s2-ultra sudo[20279]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005556] [t1967854688] 1614.376 TRACE [transport/volumio] got update status Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005557] [t1967854688] 1614.379 TRACE [transport/volumio] got update status Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005558] [t1967854688] 1614.388 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1231] {"request":"stream","stream_id":119121520,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2} Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005559] [t1967854688] 1614.389 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1231] {"status":"Buffering"} Aug 23 08:43:16 stream-box-s2-ultra sudo[20289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005561] [t1996021760] 1614.428 TRACE [VRST] got volume notify Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005562] [t1996021760] 1614.428 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:16 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005564] [t1996021760] 1614.429 TRACE [VRST] got update signal path Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005565] [t1996021760] 1614.429 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005566] [t1996021760] 1614.429 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005567] [t1967854688] 1614.430 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005568] [t1967854688] 1614.430 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:16 stream-box-s2-ultra sudo[20289]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:16 stream-box-s2-ultra sudo[20289]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005569] [t1967854688] 1614.541 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1231] {"status":"Ready"} Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005570] [t1967854688] 1614.679 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1232] {"request":"start","min_offset":60895691,"stream_sample":0} Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005571] [t1967854688] 1614.679 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] Doing ASAP start based on current device clock (min_offset=60895691) Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005572] [t1967854688] 1614.679 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1231] {"status":"Playing"} Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005573] [t1967854688] 1614.679 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1232] {"status":"Success","time":999144466475} Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005574] [t1955591264] 1614.694 TRACE starting playback: now (999140000000ns) + ns_per_buf(20000000ns) = 999160000000ns > 999144466475ns streamsample=0 Aug 23 08:43:16 stream-box-s2-ultra raatdaemon[1522]: [0005575] [t1967854688] 1614.800 TRACE [transport/volumio] got update status Aug 23 08:43:17 stream-box-s2-ultra raatdaemon[1522]: [0005576] [t1967854688] 1615.804 TRACE [transport/volumio] got update status Aug 23 08:43:18 stream-box-s2-ultra raatdaemon[1522]: [0005577] [t1967854688] 1616.807 TRACE [transport/volumio] got update status Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005578] [t1967854688] 1617.321 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1236] {"request":"end_stream"} Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005579] [t1967854688] 1617.322 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1231] {"status":"Ended"} Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005580] [t1967854688] 1617.322 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1236] {"status":"Success"} Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005581] [t1967854688] 1617.322 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1237] {"request":"request_source"} Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005582] [t1967854688] 1617.322 TRACE [source_selection/volumio] requesting source Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: [VRCP] already in control, acknowledged Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [81B blob data] Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005584] [t1996021760] 1617.325 TRACE [source_selection/volumio] source acquired Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005585] [t1967854688] 1617.325 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] source selection state changed: selected Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005586] [t1967854688] 1617.326 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [5] {"status":"Success","controls":{"volume":{"mute":false,"max":100.0,"type":"number","step":1.0,"info":{"config":{"mute":false,"type":"alsa","mode":"number","value":100,"device":"hw:5"}},"value":64,"min":0.0},"source":{"value":"selected","info":{"is_standby_supported":false}}},"action":"Changed"} Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005587] [t1967854688] 1617.326 TRACE [transport/volumio] got update artwork Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005588] [t1967854688] 1617.327 TRACE - MD5 for artwork: 4b0c21ab4a013eb59f37340b26c0ad52 Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005589] [t1967854688] 1617.327 TRACE - Full path for artwork: /tmp/raat/albumart/4b0c21ab4a013eb59f37340b26c0ad52.jpg Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005590] [t1967854688] 1617.328 TRACE [transport/volumio] got update status Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005591] [t1967854688] 1617.328 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1237] {"status":"Success"} Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005593] [t1996021760] 1617.361 TRACE [VRST] got volume notify Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005594] [t1996021760] 1617.361 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005596] [t1996021760] 1617.362 TRACE [VRST] got update signal path Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005597] [t1996021760] 1617.362 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005598] [t1996021760] 1617.362 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005599] [t1967854688] 1617.362 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005600] [t1967854688] 1617.363 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005601] [t1967854688] 1617.365 TRACE [transport/volumio] got update status Aug 23 08:43:19 stream-box-s2-ultra sudo[20331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:19 stream-box-s2-ultra sudo[20331]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:19 stream-box-s2-ultra sudo[20331]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005602] [t1967854688] 1617.504 TRACE [transport/volumio] got update status Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: [VRCP] RAAT stopped playing Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005603] [t1967854688] 1617.507 TRACE [transport/volumio] got update status Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CorePlayQueue::getTrack 0 Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Aug 23 08:43:19 stream-box-s2-ultra sudo[20340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005605] [t1996021760] 1617.546 TRACE [VRST] got volume notify Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005606] [t1996021760] 1617.546 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005608] [t1996021760] 1617.546 TRACE [VRST] got update signal path Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005609] [t1996021760] 1617.546 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005610] [t1996021760] 1617.546 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005611] [t1967854688] 1617.546 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005612] [t1967854688] 1617.547 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:19 stream-box-s2-ultra sudo[20340]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:19 stream-box-s2-ultra sudo[20340]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005613] [t1967854688] 1618.103 TRACE [transport/volumio] got update status Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005614] [t1967854688] 1618.105 TRACE [transport/volumio] got update status Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:19 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005615] [t1967854688] 1618.117 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1243] {"request":"stream","stream_id":119121521,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2} Aug 23 08:43:19 stream-box-s2-ultra raatdaemon[1522]: [0005616] [t1967854688] 1618.117 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1243] {"status":"Buffering"} Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005618] [t1996021760] 1618.139 TRACE [VRST] got volume notify Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005619] [t1996021760] 1618.139 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:20 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005621] [t1996021760] 1618.141 TRACE [VRST] got update signal path Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005622] [t1996021760] 1618.141 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005623] [t1996021760] 1618.141 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005624] [t1967854688] 1618.141 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005625] [t1967854688] 1618.141 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:20 stream-box-s2-ultra sudo[20357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:20 stream-box-s2-ultra sudo[20357]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:20 stream-box-s2-ultra sudo[20357]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005626] [t1992660064] 1618.415 TRACE Network Status Changed. Refreshing Discovery Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005627] [t1992660064] 1618.415 TRACE [discovery] stopping Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005628] [t1992660064] 1618.415 TRACE closing multicast Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005629] [t1992660064] 1618.415 TRACE [discovery] closing unicast send socket Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005630] [t1992660064] 1618.415 TRACE [discovery] closing unicast recv socket Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005631] [t1992660064] 1618.415 TRACE [discovery] starting Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005632] [t1992660064] 1618.416 INFO [discovery] [iface:127.0.0.1] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005633] [t1992660064] 1618.416 INFO [discovery] [iface:127.0.0.1] multicast send socket is bound to 0.0.0.0:54292 Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005634] [t1992660064] 1618.416 INFO [discovery] [iface:192.168.0.39] multicast recv socket is bound to 0.0.0.0:9003 Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005635] [t1992660064] 1618.416 INFO [discovery] [iface:192.168.0.39] multicast send socket is bound to 0.0.0.0:32867 Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005636] [t1992660064] 1618.416 INFO [discovery] unicast socket is bound to 0.0.0.0:9003 Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005637] [t1992660064] 1618.416 TRACE [device] announcing DiscoveryMessage[type=QUERY, transactionid=E4BED778-7D66-3B19-CB5B-9449D686D587, Data={ "unique_id": "000000001a6087eb", "raat_version": "1.1.32", "version": "1.001", "protocol_version": "3", "config_url": "http://__SELF__", "model": "Stream Box S2 ultra", "vendor": "Pro-Ject Audio Systems", "vendor_model": "Pro-Ject Stream Box S2 ultra", "service_id": "5e2042ad-9bc5-4508-be92-ff68f19bdc93", "tcp_port": "38647", } ] Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005638] [t1967854688] 1618.467 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1243] {"status":"Ready"} Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005639] [t1967854688] 1618.604 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1244] {"request":"start","min_offset":60861678,"stream_sample":0} Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005640] [t1967854688] 1618.605 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] Doing ASAP start based on current device clock (min_offset=60861678) Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005641] [t1967854688] 1618.605 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1243] {"status":"Playing"} Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005642] [t1967854688] 1618.605 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1244] {"status":"Success","time":1003070014652} Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005643] [t1967854688] 1618.613 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1245] {"request":"update_artwork","mimetype":"image/jpeg"} Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005644] [t1967854688] 1618.613 TRACE [transport/volumio] got update artwork Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005645] [t1967854688] 1618.614 TRACE - MD5 for artwork: 958cec5d9dccdba82046b513e1bb2b0f Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005646] [t1967854688] 1618.614 TRACE - Full path for artwork: /tmp/raat/albumart/958cec5d9dccdba82046b513e1bb2b0f.jpg Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005647] [t1967854688] 1618.614 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1245] {"status":"Success"} Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005648] [t1955591264] 1618.615 TRACE starting playback: now (1003060000000ns) + ns_per_buf(20000000ns) = 1003080000000ns > 1003070014652ns streamsample=0 Aug 23 08:43:20 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:20 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:20 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:20 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:20 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:20 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005650] [t1996021760] 1618.648 TRACE [VRST] got volume notify Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005651] [t1996021760] 1618.648 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:20 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005653] [t1996021760] 1618.648 TRACE [VRST] got update signal path Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005654] [t1996021760] 1618.648 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005655] [t1996021760] 1618.648 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005656] [t1967854688] 1618.649 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005657] [t1967854688] 1618.649 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:20 stream-box-s2-ultra sudo[20369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:20 stream-box-s2-ultra sudo[20369]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:20 stream-box-s2-ultra sudo[20369]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:20 stream-box-s2-ultra raatdaemon[1522]: [0005658] [t1967854688] 1618.739 TRACE [transport/volumio] got update status Aug 23 08:43:21 stream-box-s2-ultra raatdaemon[1522]: [0005659] [t1967854688] 1619.742 TRACE [transport/volumio] got update status Aug 23 08:43:22 stream-box-s2-ultra raatdaemon[1522]: [0005660] [t1967854688] 1620.749 TRACE [transport/volumio] got update status Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005661] [t1967854688] 1621.750 TRACE [transport/volumio] got update status Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005662] [t1967854688] 1621.841 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1250] {"request":"end_stream"} Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005663] [t1967854688] 1621.841 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1243] {"status":"Ended"} Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005664] [t1967854688] 1621.842 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1250] {"status":"Success"} Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005665] [t1967854688] 1621.842 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1251] {"request":"request_source"} Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005666] [t1967854688] 1621.842 TRACE [source_selection/volumio] requesting source Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: [VRCP] already in control, acknowledged Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [81B blob data] Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005668] [t1996021760] 1621.843 TRACE [source_selection/volumio] source acquired Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005669] [t1967854688] 1621.843 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] source selection state changed: selected Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005670] [t1967854688] 1621.844 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [5] {"status":"Success","controls":{"volume":{"mute":false,"max":100.0,"type":"number","step":1.0,"info":{"config":{"mute":false,"type":"alsa","mode":"number","value":100,"device":"hw:5"}},"value":64,"min":0.0},"source":{"value":"selected","info":{"is_standby_supported":false}}},"action":"Changed"} Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005671] [t1967854688] 1621.844 TRACE [transport/volumio] got update artwork Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005672] [t1967854688] 1621.845 TRACE - MD5 for artwork: 958cec5d9dccdba82046b513e1bb2b0f Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005673] [t1967854688] 1621.845 TRACE - Full path for artwork: /tmp/raat/albumart/958cec5d9dccdba82046b513e1bb2b0f.jpg Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005674] [t1967854688] 1621.846 TRACE [transport/volumio] got update status Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005675] [t1967854688] 1621.846 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1251] {"status":"Success"} Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005677] [t1996021760] 1621.883 TRACE [VRST] got volume notify Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005678] [t1996021760] 1621.883 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005680] [t1996021760] 1621.884 TRACE [VRST] got update signal path Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005681] [t1996021760] 1621.884 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005682] [t1996021760] 1621.884 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005683] [t1967854688] 1621.885 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005684] [t1967854688] 1621.885 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005685] [t1967854688] 1621.887 TRACE [transport/volumio] got update status Aug 23 08:43:23 stream-box-s2-ultra sudo[20419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:23 stream-box-s2-ultra sudo[20419]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:23 stream-box-s2-ultra sudo[20419]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005686] [t1967854688] 1622.039 TRACE [transport/volumio] got update status Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005687] [t1967854688] 1622.042 TRACE [transport/volumio] got update status Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: [VRCP] RAAT stopped playing Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: CorePlayQueue::getTrack 0 Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current streaming_services Received raat Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005689] [t1996021760] 1622.076 TRACE [VRST] got volume notify Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005690] [t1996021760] 1622.076 TRACE [VRST] got volume notify: 64.000000 Aug 23 08:43:23 stream-box-s2-ultra volumio[1225]: info: Updating RAAT Signal Path Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [82B blob data] Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005692] [t1996021760] 1622.077 TRACE [VRST] got update signal path Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005693] [t1996021760] 1622.077 TRACE new signal path: [{"quality":"lossless","type":"output","method":"usb"}] Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005694] [t1996021760] 1622.077 TRACE new signal path after decoding: [{"quality": "lossless", "type": "output", "method": "usb"}] Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005695] [t1967854688] 1622.077 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] got output message {"signal_path": [{"quality": "lossless", "type": "output", "method": "usb"}]} Aug 23 08:43:23 stream-box-s2-ultra raatdaemon[1522]: [0005696] [t1967854688] 1622.077 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] SENT [1209] {"status":"OutputMessage","message":{"signal_path":[{"method":"usb","quality":"lossless","type":"output"}]}} Aug 23 08:43:23 stream-box-s2-ultra sudo[20428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:23 stream-box-s2-ultra sudo[20428]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:23 stream-box-s2-ultra sudo[20428]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:24 stream-box-s2-ultra raatdaemon[1522]: [0005697] [t1967854688] 1622.665 TRACE [transport/volumio] got update status Aug 23 08:43:24 stream-box-s2-ultra raatdaemon[1522]: [0005698] [t1967854688] 1622.665 TRACE [lua@0x7637c9b4] [192.168.0.14:51836] GOT [1256] {"request":"teardown"} Aug 23 08:43:24 stream-box-s2-ultra raatdaemon[1522]: [0005699] [t1967854688] 1622.665 TRACE [alsa] teardown Aug 23 08:43:24 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::servicePushState Aug 23 08:43:24 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::syncState Aug 23 08:43:24 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::pushState Aug 23 08:43:24 stream-box-s2-ultra volumio[1225]: info: CoreStateMachine::getState Aug 23 08:43:24 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::volumioPushState Aug 23 08:43:24 stream-box-s2-ultra volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:43:24 stream-box-s2-ultra systemd-journal[20441]: Runtime journal is using 12.0M (max allowed 30.0M, trying to leave 65.6M free of 425.2M available → current limit 30.0M). Aug 23 08:43:24 stream-box-s2-ultra kernel: Unable to handle kernel NULL pointer dereference at virtual address 00000023 Aug 23 08:43:24 stream-box-s2-ultra kernel: pgd = aeda8000 Aug 23 08:43:24 stream-box-s2-ultra kernel: [00000023] *pgd=00000000 Aug 23 08:43:24 stream-box-s2-ultra kernel: Internal error: Oops: 5 [#1] SMP ARM Aug 23 08:43:24 stream-box-s2-ultra 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 snd_hwdep btusb(O) snd_usbmidi_lib btrtl(O) snd_seq_midi snd_seq_midi_event btintel(O) btbcm(O) snd_rawmidi bluetooth snd_pcm cfg80211 snd_seq snd_seq_device snd_timer evdev snd rfkill bcm2835_gpiomem fixed uio_pdrv_genirq uio i2c_dev fuse ipv6 squashfs overlay Aug 23 08:43:24 stream-box-s2-ultra kernel: CPU: 1 PID: 197 Comm: systemd-journal Tainted: G O 4.9.65-v7+ #1 Aug 23 08:43:24 stream-box-s2-ultra kernel: Hardware name: BCM2835 Aug 23 08:43:24 stream-box-s2-ultra kernel: task: aeda0ec0 task.stack: b6b80000 Aug 23 08:43:24 stream-box-s2-ultra kernel: PC is at skb_release_data+0x64/0x128 Aug 23 08:43:24 stream-box-s2-ultra kernel: LR is at skb_release_all+0x30/0x34 Aug 23 08:43:24 stream-box-s2-ultra kernel: pc : [<805fff80>] lr : [<80600074>] psr: 60000013 sp : b6b81d78 ip : b6b81d98 fp : b6b81d94 Aug 23 08:43:24 stream-box-s2-ultra kernel: r10: aea96100 r9 : 00000000 r8 : 00000000 Aug 23 08:43:24 stream-box-s2-ultra kernel: r7 : 00000023 r6 : 8b6b9480 r5 : 8b6b9480 r4 : 8b6b9480 Aug 23 08:43:24 stream-box-s2-ultra kernel: r3 : 00000000 r2 : 00001601 r1 : 00000000 r0 : 8b6b9480 Aug 23 08:43:24 stream-box-s2-ultra kernel: Flags: nZCv IRQs on FIQs on Mode SVC_32 ISA ARM Segment user Aug 23 08:43:24 stream-box-s2-ultra kernel: Control: 10c5383d Table: 2eda806a DAC: 00000055 Aug 23 08:43:24 stream-box-s2-ultra kernel: Process systemd-journal (pid: 197, stack limit = 0xb6b80210) Aug 23 08:43:24 stream-box-s2-ultra kernel: Stack: (0xb6b81d78 to 0xb6b82000) Aug 23 08:43:24 stream-box-s2-ultra kernel: 1d60: 8b6b9480 8b6b9480 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1d80: b6b81e5c 00000790 b6b81dac b6b81d98 80600074 805fff28 00000001 8b6b9480 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1da0: b6b81dc4 b6b81db0 806001f8 80600050 8b6b9480 00000070 b6b81e54 b6b81dc8 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1dc0: 806c2264 806001cc 00000000 00000000 aea96244 00000001 00000000 00000001 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1de0: aea9630c 00000000 aea9634c 8e4d1340 00000070 00000001 00000070 aea96184 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1e00: aef29800 00000000 000005f2 000003e8 000003e8 b29b8cf0 b6b81e48 8028727c Aug 23 08:43:24 stream-box-s2-ultra kernel: 1e20: 66c84bac 20b9ea9a b6b81e6c 00000000 b6b81f00 b6b81ee8 b6b81f78 00000800 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1e40: 017d61b1 00000000 b6b81e84 b6b81e58 806c2410 806c1b10 806bdb84 806be394 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1e60: 8e4d1340 b6b81e88 00000000 00000800 00000040 00000000 b6b81ed4 b6b81e88 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1e80: 805f71e0 806c23d4 00000000 00000000 00000000 00000070 00000790 b6b81ee0 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1ea0: 00000001 805c88c0 00000000 00000000 00000040 b6b81f00 b69a29ac 00000000 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1ec0: 00000000 b235d9c0 b6b81f44 b6b81ed8 80268b64 805f7150 00000800 0000001c Aug 23 08:43:24 stream-box-s2-ultra kernel: 1ee0: 017d61b1 00000800 00000000 00000000 00000800 b6b81ee0 00000001 805c88c0 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1f00: b235d9c0 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1f20: 8017ea84 00000800 b235d9c0 017d61b1 b6b81f78 00000800 b6b81f74 b6b81f48 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1f40: 8026996c 80268aa0 00000188 80289034 b6b81f74 00000000 00000000 b235d9c0 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1f60: b235d9c0 00000800 b6b81fa4 b6b81f78 8026ab28 802698e0 00000000 00000000 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1f80: 017d6188 76fbbf10 7e828748 00000003 80107fa4 b6b80000 00000000 b6b81fa8 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1fa0: 80107e20 8026aae8 017d6188 76fbbf10 00000018 017d61b1 00000800 00000011 Aug 23 08:43:24 stream-box-s2-ultra kernel: 1fc0: 017d6188 76fbbf10 7e828748 00000003 76fbbf10 017ce0a0 017ce088 7e828a1c Aug 23 08:43:24 stream-box-s2-ultra kernel: 1fe0: 00000000 7e828724 004da3f0 76f64c8c 60000010 00000018 e89da830 e5923010 Aug 23 08:43:24 stream-box-s2-ultra kernel: [<805fff80>] (skb_release_data) from [<80600074>] (skb_release_all+0x30/0x34) Aug 23 08:43:24 stream-box-s2-ultra kernel: [<80600074>] (skb_release_all) from [<806001f8>] (consume_skb+0x38/0xe4) Aug 23 08:43:24 stream-box-s2-ultra kernel: [<806001f8>] (consume_skb) from [<806c2264>] (unix_stream_read_generic+0x760/0x834) Aug 23 08:43:24 stream-box-s2-ultra kernel: [<806c2264>] (unix_stream_read_generic) from [<806c2410>] (unix_stream_recvmsg+0x48/0x54) Aug 23 08:43:24 stream-box-s2-ultra kernel: [<806c2410>] (unix_stream_recvmsg) from [<805f71e0>] (sock_read_iter+0x9c/0xd0) Aug 23 08:43:24 stream-box-s2-ultra kernel: [<805f71e0>] (sock_read_iter) from [<80268b64>] (__vfs_read+0xd0/0x120) Aug 23 08:43:24 stream-box-s2-ultra kernel: [<80268b64>] (__vfs_read) from [<8026996c>] (vfs_read+0x98/0x16c) Aug 23 08:43:24 stream-box-s2-ultra kernel: [<8026996c>] (vfs_read) from [<8026ab28>] (SyS_read+0x4c/0xa0) Aug 23 08:43:24 stream-box-s2-ultra kernel: [<8026ab28>] (SyS_read) from [<80107e20>] (ret_fast_syscall+0x0/0x1c) Aug 23 08:43:24 stream-box-s2-ultra kernel: Code: 1afffffa f57ff05b e3510000 189da8f0 (e5d73000) Aug 23 08:43:24 stream-box-s2-ultra kernel: ---[ end trace edad2cf413e1db11 ]--- Aug 23 08:43:24 stream-box-s2-ultra systemd[1]: systemd-journald.service has no holdoff time, scheduling restart. Aug 23 08:43:24 stream-box-s2-ultra systemd[1]: Stopping Journal Service... Aug 23 08:43:24 stream-box-s2-ultra systemd[1]: Starting Journal Service... Aug 23 08:43:24 stream-box-s2-ultra systemd[1]: Started Journal Service. Aug 23 08:43:24 stream-box-s2-ultra systemd-journal[20441]: Journal started Aug 23 08:43:24 stream-box-s2-ultra systemd[1]: systemd-journald.service: main process exited, code=killed, status=11/SEGV Aug 23 08:43:24 stream-box-s2-ultra systemd[1]: Unit systemd-journald.service entered failed state. Aug 23 08:43:24 stream-box-s2-ultra systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Aug 23 08:43:24 stream-box-s2-ultra systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Aug 23 08:43:24 stream-box-s2-ultra sudo[20443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 08:43:24 stream-box-s2-ultra sudo[20443]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:43:24 stream-box-s2-ultra sudo[20443]: pam_unix(sudo:session): session closed for user root Aug 23 08:43:25 stream-box-s2-ultra sudo[20467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 08:42 Aug 23 08:43:25 stream-box-s2-ultra sudo[20467]: 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"