-- Logs begin at Thu 2024-10-10 21:31:17 CEST, end at Sat 2024-10-12 22:51:20 CEST. -- Oct 12 22:50:01 volumio volumio[951]: info: CoreCommandRouter::volumioGetState Oct 12 22:50:01 volumio volumio[951]: info: CorePlayQueue::getTrack 3 Oct 12 22:50:06 volumio volumio[951]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 22:50:06 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 12 22:50:06 volumio volumio[951]: Disk list : [] Oct 12 22:50:06 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 12 22:50:06 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 12 22:50:06 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 12 22:50:06 volumio volumio[951]: info: Received Get System Version Oct 12 22:50:06 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 22:50:28 volumio volumio[951]: info: CALLMETHOD: system_controller system setLanguageTimezone [object Object] Oct 12 22:50:28 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , setLanguageTimezone Oct 12 22:50:28 volumio volumio[951]: info: Setting timezone to Europe/Berlin Oct 12 22:50:28 volumio sudo[31412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 12 22:50:28 volumio sudo[31412]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 22:50:28 volumio sudo[31412]: pam_unix(sudo:session): session closed for user root Oct 12 22:50:28 volumio sudo[31416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Berlin /etc/localtime Oct 12 22:50:28 volumio sudo[31416]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 22:50:28 volumio sudo[31416]: pam_unix(sudo:session): session closed for user root Oct 12 22:50:28 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Oct 12 22:50:28 volumio systemd[1]: Starting Daily apt download activities... Oct 12 22:50:28 volumio sudo[31420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 12 22:50:28 volumio sudo[31420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 22:50:28 volumio sudo[31420]: pam_unix(sudo:session): session closed for user root Oct 12 22:50:28 volumio sudo[31427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Oct 12 22:50:28 volumio sudo[31427]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 22:50:28 volumio dbus-daemon[590]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=31430 comm="/usr/bin/timedatectl set-timezone Europe/Berlin ") Oct 12 22:50:28 volumio systemd[1]: Starting Time & Date Service... Oct 12 22:50:29 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 12 22:50:29 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 12 22:50:30 volumio systemd[1]: apt-daily.service: Succeeded. Oct 12 22:50:30 volumio systemd[1]: Started Daily apt download activities. Oct 12 22:50:53 volumio dbus-daemon[590]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 12 22:50:53 volumio sudo[31427]: pam_unix(sudo:session): session closed for user root Oct 12 22:50:53 volumio volumio[951]: Failed to set time zone: Connection timed out Oct 12 22:50:53 volumio volumio[951]: info: Could not set timezone, retrying Oct 12 22:50:53 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 12 22:50:53 volumio volumio[951]: info: Loading i18n strings for locale en Oct 12 22:50:53 volumio volumio[951]: Upnp client error: Error: This socket has been ended by the other party Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 12 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 13 Oct 12 22:50:53 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 14 Oct 12 22:50:53 volumio volumio[951]: Upnp client error: Error: This socket has been ended by the other party Oct 12 22:50:53 volumio volumio[951]: Upnp client error: Error: This socket has been ended by the other party Oct 12 22:50:53 volumio volumio[951]: Upnp client error: Error: This socket has been ended by the other party Oct 12 22:50:53 volumio volumio[951]: Upnp client error: Error: This socket has been ended by the other party Oct 12 22:50:53 volumio volumio[951]: Upnp client error: Error: This socket has been ended by the other party Oct 12 22:50:53 volumio volumio[951]: Upnp client error: Error: This socket has been ended by the other party Oct 12 22:50:53 volumio volumio[951]: Upnp client error: Error: This socket has been ended by the other party Oct 12 22:50:53 volumio volumio[951]: Upnp client error: Error: This socket has been ended by the other party Oct 12 22:50:53 volumio volumio[951]: Updating browse sources language Oct 12 22:50:53 volumio volumio[951]: Cannot find translation for source YouTube Music Oct 12 22:50:53 volumio volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 22:50:53 volumio volumio[951]: Cannot find translation for source YouTube Music Oct 12 22:50:54 volumio volumio[951]: info: Fetching Streaming Services browse cache Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 14 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 15 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 16 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 17 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 18 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 19 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 20 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 21 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 22 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.209 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 23 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 24 Oct 12 22:50:54 volumio volumio[951]: verbose: New Socket.io Connection to 192.168.2.101 from 192.168.2.35 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Engine version: 3 Transport: polling Total Clients: 25 Oct 12 22:50:54 volumio sudo[31562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Oct 12 22:50:54 volumio sudo[31562]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 22:50:54 volumio dbus-daemon[590]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.17' (uid=0 pid=31563 comm="/usr/bin/timedatectl set-timezone Europe/Berlin ") Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#22 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD IN Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#22 CDB: opcode=0x28 28 00 00 00 2c 37 00 00 09 00 Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#21 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD IN Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#21 CDB: opcode=0x28 28 00 00 00 2b 11 00 00 2f 00 Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#19 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD IN Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#19 CDB: opcode=0x28 28 00 00 00 2b ea 00 00 4c 00 Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#18 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD IN Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#18 CDB: opcode=0x28 28 00 00 00 2b a3 00 00 46 00 Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#17 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD IN Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#17 CDB: opcode=0x28 28 00 00 00 2b 53 00 00 4f 00 Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#16 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#16 CDB: opcode=0x28 28 00 00 00 2b 40 00 00 12 00 Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#20 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD IN Oct 12 22:50:59 volumio kernel: sd 0:0:0:0: [sda] tag#20 CDB: opcode=0x28 28 00 0d 15 62 80 00 02 00 00 Oct 12 22:51:02 volumio kernel: sd 0:0:0:0: [sda] tag#23 uas_eh_abort_handler 0 uas-tag 7 inflight: CMD IN Oct 12 22:51:02 volumio kernel: sd 0:0:0:0: [sda] tag#23 CDB: opcode=0x28 28 00 0d 15 64 80 00 02 00 00 Oct 12 22:51:02 volumio kernel: scsi host0: uas_eh_device_reset_handler start Oct 12 22:51:02 volumio kernel: usb 2-1: reset SuperSpeed USB device number 2 using xhci_hcd Oct 12 22:51:02 volumio kernel: scsi host0: uas_eh_device_reset_handler success Oct 12 22:51:18 volumio volumio-remote-updater[563]: [2024-10-12 22:51:18] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 12 22:51:19 volumio dbus-daemon[590]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 12 22:51:19 volumio sudo[31562]: pam_unix(sudo:session): session closed for user root Oct 12 22:51:19 volumio volumio[951]: Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 12 22:51:19 volumio volumio[951]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 22:51:19 volumio volumio[951]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Europe/Berlin' Oct 12 22:51:19 volumio volumio[951]: Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 12 22:51:19 volumio volumio[951]: at checkExecSyncError (child_process.js:616:11) Oct 12 22:51:19 volumio volumio[951]: at execSync (child_process.js:652:15) Oct 12 22:51:19 volumio volumio[951]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Oct 12 22:51:19 volumio volumio[951]: at listOnTimeout (internal/timers.js:554:17) Oct 12 22:51:19 volumio volumio[951]: at processTimers (internal/timers.js:497:7) { Oct 12 22:51:19 volumio volumio[951]: status: 1, Oct 12 22:51:19 volumio volumio[951]: signal: null, Oct 12 22:51:19 volumio volumio[951]: output: [ Oct 12 22:51:19 volumio volumio[951]: null, Oct 12 22:51:19 volumio volumio[951]: '', Oct 12 22:51:19 volumio volumio[951]: "Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms)\n" Oct 12 22:51:19 volumio volumio[951]: ], Oct 12 22:51:19 volumio volumio[951]: pid: 31561, Oct 12 22:51:19 volumio volumio[951]: stdout: '', Oct 12 22:51:19 volumio volumio[951]: stderr: "Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms)\n" Oct 12 22:51:19 volumio volumio[951]: } Oct 12 22:51:19 volumio volumio[951]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 22:51:20 volumio sudo[31574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-12 22:50 Oct 12 22:51:20 volumio sudo[31574]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"