-- Logs begin at Tue 2024-11-26 13:00:55 -03, end at Tue 2024-11-26 15:09:20 -03. -- Nov 26 15:09:12 mamuschkamitre ntpd[948]: Soliciting pool server 190.103.0.13 Nov 26 15:09:12 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 26 15:09:12 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Nov 26 15:09:13 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 Nov 26 15:09:14 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 31 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 32 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 35 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 36 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 37 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 39 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 43 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 44 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 45 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 46 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 47 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 48 Nov 26 15:09:15 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 50 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 51 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 52 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 53 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 54 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 55 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 56 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 57 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 58 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 59 Nov 26 15:09:16 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:16-03:00" level=debug msg="handling transfer player command from 3067c5c9286ea02f2d914ccfd6d6c9a3af6d528c" Nov 26 15:09:16 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:16-03:00" level=debug msg="resolved context of track" uri="spotify:playlist:52MZWFpiWwK16F0OyGXTGZ" Nov 26 15:09:16 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:16-03:00" level=trace msg="fetched new page 0 with 65 items (list: 65)" uri="spotify:playlist:52MZWFpiWwK16F0OyGXTGZ" Nov 26 15:09:16 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:16-03:00" level=debug msg="loading track (paused: true, position: 198162ms)" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 60 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 61 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 62 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 63 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 64 Nov 26 15:09:16 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 65 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 66 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 67 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 68 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 69 Nov 26 15:09:17 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:17-03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 26 15:09:17 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:17-03:00" level=trace msg="emitting websocket event: will_play" Nov 26 15:09:17 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:17-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 70 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 71 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 72 Nov 26 15:09:17 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:17-03:00" level=debug msg="new websocket client" Nov 26 15:09:17 mamuschkamitre volumio[966]: info: Connection to go-librespot Websocket established Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 73 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 74 Nov 26 15:09:17 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:17-03:00" level=debug msg="selected format OGG_VORBIS_320 (c68e1bac4b5e7c3fa98d33f780bf8ff83a95050e)" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:17 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:17-03:00" level=debug msg="requested aes key for file c68e1bac4b5e7c3fa98d33f780bf8ff83a95050e, gid: 6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:17 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:17-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1343" Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 75 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 76 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 77 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 78 Nov 26 15:09:17 mamuschkamitre volumio-time-update[492]: volumio-time-update-util: Date not found in response Nov 26 15:09:17 mamuschkamitre volumio-time-update[492]: volumio-time-update-util: Retrying in 5 seconds... Nov 26 15:09:17 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:17-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1119" Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 79 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 80 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 81 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 82 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 83 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 84 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 85 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 86 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 87 Nov 26 15:09:17 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 88 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 89 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 90 Nov 26 15:09:18 mamuschkamitre volumio[966]: info: Discovery: A device disappeared from network Nov 26 15:09:18 mamuschkamitre volumio[966]: info: Discovery: Device mamuschkamitre disappeared from network Nov 26 15:09:18 mamuschkamitre volumio[966]: info: Discovery: A device disappeared from network Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 91 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 92 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 93 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 94 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 95 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 96 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 97 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 98 Nov 26 15:09:18 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:18-03:00" level=debug msg="fetched first chunk of 19, total size is 9659236 bytes" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 99 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 100 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 101 Nov 26 15:09:18 mamuschkamitre volumio[966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 102 Nov 26 15:09:18 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:18-03:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:18 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:18-03:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="fetched chunk 13/18, size: 524288" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=trace msg="seek to 198162ms (diff: 121ms, samples: 8738944, bytes: 7116006)" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 10068 us, period size = 444 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 49 frames" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="created new output device" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=info msg="loaded track \"All I Ask\" (paused: true, position: 198162ms, duration: 271800ms, prefetched: false)" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="fetched chunk 14/18, size: 524288" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="fetched chunk 16/18, size: 524288" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="fetched chunk 15/18, size: 524288" uri="spotify:track:6DEMMeWXfmFAXgDUMMzeg6" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=trace msg="emitting websocket event: metadata" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=trace msg="emitting websocket event: active" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="sending successful reply for dealer request" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Nov 26 15:09:19 mamuschkamitre volumio[966]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6DEMMeWXfmFAXgDUMMzeg6","name":"All I Ask","artist_names":["Adele"],"album_name":"25","album_cover_url":"https://i.scdn.co/image/ab67616d00001e027b9888dc365b8f957c63df14","position":198162,"duration":271800,"release_date":"year:2015 month:11 day:20","track_number":10,"disc_number":1}} Nov 26 15:09:19 mamuschkamitre volumio[966]: SPOTIFY: received: {"type":"active","data":null} Nov 26 15:09:19 mamuschkamitre volumio[966]: info: Aligning Spotify Volume to Volumio Volume Nov 26 15:09:19 mamuschkamitre volumio[966]: info: CoreCommandRouter::volumioGetState Nov 26 15:09:19 mamuschkamitre volumio[966]: info: CorePlayQueue::getTrack 0 Nov 26 15:09:19 mamuschkamitre volumio[966]: info: Setting Spotify Volume from Volumio: 80 Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 26 15:09:19 mamuschkamitre go-librespot[9609]: time="2024-11-26T15:09:19-03:00" level=trace msg="emitting websocket event: paused" Nov 26 15:09:19 mamuschkamitre volumio[966]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6DEMMeWXfmFAXgDUMMzeg6","play_origin":"playlist/ondemand"}} Nov 26 15:09:19 mamuschkamitre volumio[966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 15:09:19 mamuschkamitre volumio[966]: TypeError: Cannot read property 'service' of undefined Nov 26 15:09:19 mamuschkamitre volumio[966]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Nov 26 15:09:19 mamuschkamitre volumio[966]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:271:18) Nov 26 15:09:19 mamuschkamitre volumio[966]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Nov 26 15:09:19 mamuschkamitre volumio[966]: at WebSocket.emit (events.js:315:20) Nov 26 15:09:19 mamuschkamitre volumio[966]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Nov 26 15:09:19 mamuschkamitre volumio[966]: at Receiver.emit (events.js:315:20) Nov 26 15:09:19 mamuschkamitre volumio[966]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Nov 26 15:09:19 mamuschkamitre volumio[966]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Nov 26 15:09:19 mamuschkamitre volumio[966]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Nov 26 15:09:19 mamuschkamitre volumio[966]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Nov 26 15:09:19 mamuschkamitre volumio[966]: at writeOrBuffer (internal/streams/writable.js:358:12) Nov 26 15:09:19 mamuschkamitre volumio[966]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Nov 26 15:09:19 mamuschkamitre volumio[966]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Nov 26 15:09:19 mamuschkamitre volumio[966]: at Socket.emit (events.js:315:20) Nov 26 15:09:19 mamuschkamitre volumio[966]: at addChunk (internal/streams/readable.js:309:12) Nov 26 15:09:19 mamuschkamitre volumio[966]: at readableAddChunk (internal/streams/readable.js:284:9) Nov 26 15:09:19 mamuschkamitre volumio[966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 15:09:20 mamuschkamitre sudo[9678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 15:08 Nov 26 15:09:20 mamuschkamitre sudo[9678]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"