-- Logs begin at Sun 2024-10-13 18:20:09 JST, end at Sun 2024-10-13 18:34:20 JST. -- Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12662, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12669, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12676, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12683, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12690, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12697, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12704, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12711, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12718, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12725, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12732, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12739, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12746, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12753, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12760, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12767, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12774, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12781, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12788, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12795, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12802, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12809, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12816, instance 1, run: true Oct 13 18:33:00 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12823, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12830, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12837, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12844, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12851, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12858, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12865, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12872, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12879, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12886, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12893, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12900, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12907, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12914, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12921, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12928, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12935, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12942, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12949, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12956, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Oct 13 18:33:01 volumio1 volumio[965]: info: CoreCommandRouter::Reload Ui Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12963, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12970, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12977, instance 1, run: true Oct 13 18:33:01 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12984, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12991, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 12998, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13005, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13012, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13019, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13026, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13033, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13040, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13047, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13054, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13061, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13068, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13075, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13082, instance 1, run: true Oct 13 18:33:02 volumio1 sudo[13090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 18:33:02 volumio1 sudo[13090]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:02 volumio1 sudo[13090]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:02 volumio1 sudo[13092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 18:33:02 volumio1 sudo[13092]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13094, instance 1, run: true Oct 13 18:33:02 volumio1 sudo[13092]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13102, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13109, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13116, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13123, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: verbose: New Socket.io Connection to 172.16.1.4 from 172.16.1.22 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13130, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13137, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13144, instance 1, run: true Oct 13 18:33:02 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13151, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13158, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 13 18:33:03 volumio1 volumio[965]: info: Received Get System Info Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:33:03 volumio1 volumio[965]: info: Discovery: Getting this device information Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::volumioGetState Oct 13 18:33:03 volumio1 volumio[965]: info: CorePlayQueue::getTrack 0 Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:33:03 volumio1 volumio-remote-updater[646]: No test mode Oct 13 18:33:03 volumio1 volumio-remote-updater[646]: No alpha test mode Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:33:03 volumio1 volumio[965]: info: Retrieving Cloud Streaming UI Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13169, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: Getting Tidal Cloud Configuration Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:33:03 volumio1 volumio[965]: info: Getting Qobuz Cloud Configuration Oct 13 18:33:03 volumio1 volumio[965]: info: Asking plugin for UI Config Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:33:03 volumio1 volumio[965]: info: Getting Spotify Cloud Configuration Oct 13 18:33:03 volumio1 volumio[965]: info: Asking plugin for UI Config Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:33:03 volumio1 volumio[965]: info: Saving Spotify Acccount Oct 13 18:33:03 volumio1 volumio[965]: info: Got Tidal Cloud Configuration Oct 13 18:33:03 volumio1 volumio[965]: info: Got it Oct 13 18:33:03 volumio1 volumio[965]: info: Got it Oct 13 18:33:03 volumio1 volumio[965]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:33:03 volumio1 volumio[965]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13176, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13183, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13190, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13197, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13204, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13211, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13218, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13225, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13232, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13239, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13246, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13253, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13260, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 13 18:33:03 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13267, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13274, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13281, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13288, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13295, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13302, instance 1, run: true Oct 13 18:33:03 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13309, instance 1, run: true Oct 13 18:33:04 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13316, instance 1, run: true Oct 13 18:33:04 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13323, instance 1, run: true Oct 13 18:33:04 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13330, instance 1, run: true Oct 13 18:33:04 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13337, instance 1, run: true Oct 13 18:33:04 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13344, instance 1, run: true Oct 13 18:33:04 volumio1 volumio[965]: info: camilladsp spawned new process with pid 13351, instance 1, run: true Oct 13 18:33:04 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 13 18:33:04 volumio1 volumio[965]: info: Loading i18n strings for locale ja Oct 13 18:33:04 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 13 18:33:04 volumio1 volumio[965]: info: Setting timezone to Asia/Tokyo Oct 13 18:33:04 volumio1 sudo[13359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 13 18:33:04 volumio1 sudo[13359]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:04 volumio1 sudo[13359]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:04 volumio1 sudo[13362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Oct 13 18:33:04 volumio1 sudo[13362]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:04 volumio1 sudo[13362]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:04 volumio1 sudo[13365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 13 18:33:04 volumio1 sudo[13365]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:04 volumio1 sudo[13365]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:04 volumio1 sudo[13368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Oct 13 18:33:04 volumio1 sudo[13368]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:04 volumio1 dbus-daemon[625]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=13369 comm="/usr/bin/timedatectl set-timezone Asia/Tokyo ") Oct 13 18:33:04 volumio1 systemd[1]: Starting Time & Date Service... Oct 13 18:33:18 volumio1 volumio-remote-updater[646]: [2024-10-13 18:33:18] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 13 18:33:23 volumio1 volumio-remote-updater[646]: [2024-10-13 18:33:23] [connect] Successful connection Oct 13 18:33:29 volumio1 dbus-daemon[625]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 13 18:33:29 volumio1 sudo[13368]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:29 volumio1 volumio[965]: Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 13 18:33:29 volumio1 volumio[965]: info: Could not set timezone, retrying Oct 13 18:33:29 volumio1 volumio[965]: info: Fetching Streaming Services browse cache Oct 13 18:33:29 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 18:33:29 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 13 18:33:29 volumio1 volumio[965]: info: Loading i18n strings for locale ja Oct 13 18:33:29 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 13 18:33:29 volumio1 volumio[965]: info: Setting timezone to Asia/Tokyo Oct 13 18:33:29 volumio1 sudo[13374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 13 18:33:29 volumio1 sudo[13374]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:29 volumio1 sudo[13374]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:29 volumio1 sudo[13377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Oct 13 18:33:29 volumio1 sudo[13377]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:29 volumio1 sudo[13377]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:29 volumio1 sudo[13380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 13 18:33:29 volumio1 sudo[13380]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:29 volumio1 sudo[13380]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:29 volumio1 sudo[13383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Oct 13 18:33:29 volumio1 sudo[13383]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:29 volumio1 dbus-daemon[625]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.21' (uid=0 pid=13384 comm="/usr/bin/timedatectl set-timezone Asia/Tokyo ") Oct 13 18:33:35 volumio1 volumio-remote-updater[646]: [2024-10-13 18:33:35] [connect] Successful connection Oct 13 18:33:50 volumio1 volumio-remote-updater[646]: [2024-10-13 18:33:50] [connect] Successful connection Oct 13 18:33:54 volumio1 dbus-daemon[625]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 13 18:33:54 volumio1 sudo[13383]: pam_unix(sudo:session): session closed for user root Oct 13 18:33:54 volumio1 volumio[965]: Failed to set time zone: Connection timed out Oct 13 18:33:54 volumio1 volumio[965]: info: Could not set timezone, retrying Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 18:33:54 volumio1 volumio[965]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 13 18:33:54 volumio1 volumio[965]: info: Received Get System Version Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 18:33:54 volumio1 volumio[965]: info: Received Get System Info Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:33:54 volumio1 volumio[965]: info: Discovery: Getting this device information Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::volumioGetState Oct 13 18:33:54 volumio1 volumio[965]: info: CorePlayQueue::getTrack 0 Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Oct 13 18:33:54 volumio1 sudo[13442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 13 18:33:54 volumio1 sudo[13442]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 18:33:54 volumio1 volumio[965]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 18:33:54 volumio1 volumio[965]: info: Fetching Streaming Services browse cache Oct 13 18:33:54 volumio1 sudo[13447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Oct 13 18:33:54 volumio1 sudo[13447]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:33:54 volumio1 dbus-daemon[625]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=13448 comm="/usr/bin/timedatectl set-timezone Asia/Tokyo ") Oct 13 18:33:58 volumio1 sudo[13442]: pam_unix(sudo:session): session closed for user root Oct 13 18:34:05 volumio1 volumio-remote-updater[646]: [2024-10-13 18:34:05] [connect] Successful connection Oct 13 18:34:19 volumio1 dbus-daemon[625]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 13 18:34:19 volumio1 sudo[13447]: pam_unix(sudo:session): session closed for user root Oct 13 18:34:19 volumio1 volumio[965]: Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 13 18:34:19 volumio1 volumio[965]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 18:34:19 volumio1 volumio[965]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Asia/Tokyo' Oct 13 18:34:19 volumio1 volumio[965]: Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Oct 13 18:34:19 volumio1 volumio[965]: at checkExecSyncError (child_process.js:616:11) Oct 13 18:34:19 volumio1 volumio[965]: at execSync (child_process.js:652:15) Oct 13 18:34:19 volumio1 volumio[965]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Oct 13 18:34:19 volumio1 volumio[965]: at listOnTimeout (internal/timers.js:554:17) Oct 13 18:34:19 volumio1 volumio[965]: at processTimers (internal/timers.js:497:7) { Oct 13 18:34:19 volumio1 volumio[965]: status: 1, Oct 13 18:34:19 volumio1 volumio[965]: signal: null, Oct 13 18:34:19 volumio1 volumio[965]: output: [ Oct 13 18:34:19 volumio1 volumio[965]: null, Oct 13 18:34:19 volumio1 volumio[965]: '', Oct 13 18:34:19 volumio1 volumio[965]: "Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms)\n" Oct 13 18:34:19 volumio1 volumio[965]: ], Oct 13 18:34:19 volumio1 volumio[965]: pid: 13446, Oct 13 18:34:19 volumio1 volumio[965]: stdout: '', Oct 13 18:34:19 volumio1 volumio[965]: stderr: "Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms)\n" Oct 13 18:34:19 volumio1 volumio[965]: } Oct 13 18:34:19 volumio1 volumio[965]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 18:34:20 volumio1 sudo[13459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 18:33 Oct 13 18:34:20 volumio1 sudo[13459]: 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"