-- Logs begin at Mon 2024-07-15 03:00:56 JST, end at Mon 2024-07-15 03:04:58 JST. -- Jul 15 03:03:16 volumio ntpd[814]: receive: Unexpected origin timestamp 0xea3e9128.f00000da does not match aorg 0000000000.00000000 from server@202.181.103.212 xmt 0xea3e9164.a8f99c70 Jul 15 03:03:16 volumio volumio[983]: info: camilladsp spawned new process with pid 2037, instance 1, run: true Jul 15 03:03:16 volumio volumio-remote-updater[691]: [2024-07-15 03:03:16] [connect] Successful connection Jul 15 03:03:16 volumio volumio[983]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 15 03:03:16 volumio volumio-remote-updater[691]: [2024-07-15 03:03:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720980196 101 Jul 15 03:03:16 volumio volumio[983]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Jul 15 03:03:16 volumio volumio[983]: info: camilladsp spawned new process with pid 2044, instance 1, run: true Jul 15 03:03:17 volumio volumio[983]: info: camilladsp spawned new process with pid 2051, instance 1, run: true Jul 15 03:03:17 volumio volumio[983]: info: camilladsp spawned new process with pid 2058, instance 1, run: true Jul 15 03:03:17 volumio volumio[983]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 15 03:03:17 volumio volumio[983]: info: camilladsp spawned new process with pid 2065, instance 1, run: true Jul 15 03:03:17 volumio ntpd[814]: Soliciting pool server 45.76.221.157 Jul 15 03:03:17 volumio volumio[983]: info: camilladsp spawned new process with pid 2072, instance 1, run: true Jul 15 03:03:18 volumio volumio[983]: info: camilladsp spawned new process with pid 2079, instance 1, run: true Jul 15 03:03:18 volumio volumio[983]: info: camilladsp spawned new process with pid 2086, instance 1, run: true Jul 15 03:03:18 volumio volumio[983]: info: camilladsp spawned new process with pid 2093, instance 1, run: true Jul 15 03:03:18 volumio volumio[983]: info: camilladsp spawned new process with pid 2100, instance 1, run: true Jul 15 03:03:18 volumio volumio[983]: info: camilladsp spawned new process with pid 2107, instance 1, run: true Jul 15 03:03:19 volumio volumio[983]: info: camilladsp spawned new process with pid 2114, instance 1, run: true Jul 15 03:03:19 volumio volumio[983]: info: camilladsp spawned new process with pid 2121, instance 1, run: true Jul 15 03:03:19 volumio volumio[983]: info: camilladsp spawned new process with pid 2128, instance 1, run: true Jul 15 03:03:19 volumio volumio[983]: info: camilladsp spawned new process with pid 2135, instance 1, run: true Jul 15 03:03:19 volumio volumio[983]: info: camilladsp spawned new process with pid 2142, instance 1, run: true Jul 15 03:03:20 volumio volumio[983]: info: camilladsp spawned new process with pid 2149, instance 1, run: true Jul 15 03:03:20 volumio volumio[983]: info: camilladsp spawned new process with pid 2156, instance 1, run: true Jul 15 03:03:20 volumio volumio[983]: info: camilladsp spawned new process with pid 2163, instance 1, run: true Jul 15 03:03:20 volumio volumio[983]: info: camilladsp spawned new process with pid 2170, instance 1, run: true Jul 15 03:03:21 volumio volumio[983]: info: camilladsp spawned new process with pid 2177, instance 1, run: true Jul 15 03:03:21 volumio volumio[983]: info: camilladsp spawned new process with pid 2184, instance 1, run: true Jul 15 03:03:21 volumio volumio[983]: info: camilladsp spawned new process with pid 2191, instance 1, run: true Jul 15 03:03:21 volumio volumio[983]: info: MYVOLUMIO: Adding device Jul 15 03:03:21 volumio volumio[983]: info: MYVOLUMIO: Evaluating Server Jul 15 03:03:21 volumio volumio[983]: info: camilladsp spawned new process with pid 2198, instance 1, run: true Jul 15 03:03:22 volumio volumio[983]: info: camilladsp spawned new process with pid 2220, instance 1, run: true Jul 15 03:03:22 volumio volumio[983]: info: Setting Geolocation for MyVolumio to as1 Jul 15 03:03:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:03:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:03:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:03:22 volumio volumio[983]: info: camilladsp spawned new process with pid 2227, instance 1, run: true Jul 15 03:03:22 volumio volumio[983]: info: camilladsp spawned new process with pid 2234, instance 1, run: true Jul 15 03:03:22 volumio volumio[983]: info: camilladsp spawned new process with pid 2241, instance 1, run: true Jul 15 03:03:22 volumio volumio[983]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 15 03:03:23 volumio volumio[983]: info: camilladsp spawned new process with pid 2248, instance 1, run: true Jul 15 03:03:23 volumio volumio[983]: info: camilladsp spawned new process with pid 2255, instance 1, run: true Jul 15 03:03:23 volumio volumio[983]: info: Updating MyVolumio device info Jul 15 03:03:23 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:03:23 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:03:23 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:03:23 volumio volumio[983]: info: camilladsp spawned new process with pid 2262, instance 1, run: true Jul 15 03:03:23 volumio volumio[983]: info: camilladsp spawned new process with pid 2269, instance 1, run: true Jul 15 03:03:23 volumio volumio[983]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 15 03:03:24 volumio volumio[983]: info: camilladsp spawned new process with pid 2276, instance 1, run: true Jul 15 03:03:24 volumio volumio[983]: info: camilladsp spawned new process with pid 2283, instance 1, run: true Jul 15 03:03:24 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 15 03:03:24 volumio volumio[983]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 15 03:03:24 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:03:24 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:24 volumio volumio[983]: info: camilladsp spawned new process with pid 2290, instance 1, run: true Jul 15 03:03:24 volumio volumio[983]: info: camilladsp spawned new process with pid 2297, instance 1, run: true Jul 15 03:03:24 volumio volumio[983]: info: camilladsp spawned new process with pid 2304, instance 1, run: true Jul 15 03:03:25 volumio volumio[983]: info: camilladsp spawned new process with pid 2311, instance 1, run: true Jul 15 03:03:25 volumio volumio[983]: info: camilladsp spawned new process with pid 2318, instance 1, run: true Jul 15 03:03:25 volumio volumio[983]: info: camilladsp spawned new process with pid 2325, instance 1, run: true Jul 15 03:03:25 volumio volumio[983]: info: camilladsp spawned new process with pid 2332, instance 1, run: true Jul 15 03:03:25 volumio volumio[983]: info: camilladsp spawned new process with pid 2339, instance 1, run: true Jul 15 03:03:26 volumio volumio[983]: info: camilladsp spawned new process with pid 2346, instance 1, run: true Jul 15 03:03:26 volumio volumio[983]: info: camilladsp spawned new process with pid 2353, instance 1, run: true Jul 15 03:03:26 volumio volumio[983]: info: camilladsp spawned new process with pid 2360, instance 1, run: true Jul 15 03:03:26 volumio volumio[983]: info: camilladsp spawned new process with pid 2367, instance 1, run: true Jul 15 03:03:27 volumio volumio[983]: info: camilladsp spawned new process with pid 2374, instance 1, run: true Jul 15 03:03:27 volumio volumio[983]: info: camilladsp spawned new process with pid 2381, instance 1, run: true Jul 15 03:03:27 volumio volumio[983]: info: camilladsp spawned new process with pid 2388, instance 1, run: true Jul 15 03:03:27 volumio volumio[983]: info: camilladsp spawned new process with pid 2395, instance 1, run: true Jul 15 03:03:27 volumio volumio[983]: info: camilladsp spawned new process with pid 2402, instance 1, run: true Jul 15 03:03:28 volumio volumio[983]: info: camilladsp spawned new process with pid 2409, instance 1, run: true Jul 15 03:03:28 volumio volumio[983]: info: camilladsp spawned new process with pid 2416, instance 1, run: true Jul 15 03:03:28 volumio volumio[983]: info: camilladsp spawned new process with pid 2423, instance 1, run: true Jul 15 03:03:28 volumio volumio[983]: info: camilladsp spawned new process with pid 2430, instance 1, run: true Jul 15 03:03:28 volumio volumio[983]: info: camilladsp spawned new process with pid 2437, instance 1, run: true Jul 15 03:03:29 volumio volumio[1118]: .......................................................................................................................................++++ Jul 15 03:03:29 volumio volumio[983]: info: camilladsp spawned new process with pid 2444, instance 1, run: true Jul 15 03:03:29 volumio volumio[983]: info: camilladsp spawned new process with pid 2451, instance 1, run: true Jul 15 03:03:29 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:03:29 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:03:29 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 15 03:03:29 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 15 03:03:29 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 15 03:03:29 volumio volumio[983]: info: CoreCommandRouter::volumioGetBrowseSources Jul 15 03:03:29 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 03:03:29 volumio volumio[983]: info: camilladsp spawned new process with pid 2458, instance 1, run: true Jul 15 03:03:29 volumio volumio[983]: info: camilladsp spawned new process with pid 2465, instance 1, run: true Jul 15 03:03:29 volumio volumio[983]: info: camilladsp spawned new process with pid 2472, instance 1, run: true Jul 15 03:03:30 volumio volumio[983]: info: camilladsp spawned new process with pid 2479, instance 1, run: true Jul 15 03:03:30 volumio volumio[983]: info: camilladsp spawned new process with pid 2486, instance 1, run: true Jul 15 03:03:30 volumio volumio[983]: info: camilladsp spawned new process with pid 2493, instance 1, run: true Jul 15 03:03:30 volumio volumio[983]: info: camilladsp spawned new process with pid 2500, instance 1, run: true Jul 15 03:03:31 volumio volumio[983]: info: camilladsp spawned new process with pid 2507, instance 1, run: true Jul 15 03:03:31 volumio volumio[983]: info: camilladsp spawned new process with pid 2514, instance 1, run: true Jul 15 03:03:31 volumio volumio[983]: info: camilladsp spawned new process with pid 2521, instance 1, run: true Jul 15 03:03:31 volumio volumio[983]: info: camilladsp spawned new process with pid 2528, instance 1, run: true Jul 15 03:03:31 volumio volumio[983]: info: camilladsp spawned new process with pid 2535, instance 1, run: true Jul 15 03:03:32 volumio volumio[983]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 15 03:03:32 volumio volumio[983]: info: Completed starting MyVolumio Plugin Jul 15 03:03:32 volumio volumio[983]: info: BOOT COMPLETED Jul 15 03:03:32 volumio volumio[983]: [Metrics] CommandRouter: 68s 863.50ms Jul 15 03:03:32 volumio volumio[983]: info: CoreCommandRouter::volumiosetStartupVolume Jul 15 03:03:32 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:03:32 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:03:32 volumio volumio[983]: info: CoreCommandRouter::Close All Modals sent Jul 15 03:03:32 volumio volumio[983]: info: CoreCommandRouter::Close All Modals sent Jul 15 03:03:32 volumio volumio[983]: info: camilladsp spawned new process with pid 2544, instance 1, run: true Jul 15 03:03:32 volumio volumio[983]: info: FusionDsp - ---- read samplerate, raw: Jul 15 03:03:32 volumio volumio[983]: error: FusionDsp - invalid sample rate Jul 15 03:03:32 volumio volumio[983]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 15 03:03:32 volumio volumio[983]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 15 03:03:32 volumio volumio[983]: info: camilladsp stopping service pid 2544... Jul 15 03:03:32 volumio volumio[983]: info: camilladsp service terminated, instance 1 Jul 15 03:03:32 volumio volumio[983]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 15 03:03:32 volumio volumio[983]: info: camilladsp service started and running in background, instance 1 Jul 15 03:03:32 volumio volumio[983]: info: camilladsp spawned new process with pid 2550, instance 1, run: true Jul 15 03:03:32 volumio volumio[983]: info: camilladsp spawned new process with pid 2557, instance 1, run: true Jul 15 03:03:32 volumio volumio[983]: info: camilladsp spawned new process with pid 2564, instance 1, run: true Jul 15 03:03:32 volumio volumio[983]: info: camilladsp spawned new process with pid 2571, instance 1, run: true Jul 15 03:03:33 volumio volumio[983]: info: camilladsp spawned new process with pid 2578, instance 1, run: true Jul 15 03:03:33 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 15 03:03:33 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 15 03:03:33 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 15 03:03:33 volumio volumio[983]: info: Version has changed, forcing UI Reload Jul 15 03:03:33 volumio volumio[983]: info: CoreCommandRouter::Reload Ui Jul 15 03:03:33 volumio volumio[983]: info: camilladsp spawned new process with pid 2587, instance 1, run: true Jul 15 03:03:33 volumio volumio[983]: info: camilladsp spawned new process with pid 2594, instance 1, run: true Jul 15 03:03:33 volumio volumio[983]: info: camilladsp spawned new process with pid 2601, instance 1, run: true Jul 15 03:03:34 volumio volumio[983]: info: camilladsp spawned new process with pid 2608, instance 1, run: true Jul 15 03:03:34 volumio volumio[983]: info: camilladsp spawned new process with pid 2615, instance 1, run: true Jul 15 03:03:34 volumio volumio[983]: info: camilladsp spawned new process with pid 2622, instance 1, run: true Jul 15 03:03:35 volumio volumio[983]: info: camilladsp spawned new process with pid 2629, instance 1, run: true Jul 15 03:03:35 volumio volumio[983]: info: camilladsp spawned new process with pid 2636, instance 1, run: true Jul 15 03:03:35 volumio volumio[983]: info: camilladsp spawned new process with pid 2643, instance 1, run: true Jul 15 03:03:35 volumio volumio[983]: info: camilladsp spawned new process with pid 2650, instance 1, run: true Jul 15 03:03:36 volumio volumio[983]: info: camilladsp spawned new process with pid 2657, instance 1, run: true Jul 15 03:03:36 volumio sudo[2665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 15 03:03:36 volumio sudo[2665]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:03:36 volumio sudo[2665]: pam_unix(sudo:session): session closed for user root Jul 15 03:03:36 volumio sudo[2668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 15 03:03:36 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:03:36 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root Jul 15 03:03:36 volumio volumio[983]: info: camilladsp spawned new process with pid 2670, instance 1, run: true Jul 15 03:03:36 volumio volumio[983]: verbose: New Socket.io Connection to 192.168.0.229 from 192.168.0.166 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Jul 15 03:03:36 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 15 03:03:36 volumio volumio[983]: info: camilladsp spawned new process with pid 2677, instance 1, run: true Jul 15 03:03:36 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 15 03:03:36 volumio volumio[983]: info: CoreCommandRouter::volumioGetVisibleSources Jul 15 03:03:36 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 15 03:03:36 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:03:36 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:36 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 15 03:03:36 volumio volumio[983]: info: camilladsp spawned new process with pid 2684, instance 1, run: true Jul 15 03:03:37 volumio volumio[983]: info: camilladsp spawned new process with pid 2691, instance 1, run: true Jul 15 03:03:37 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 15 03:03:37 volumio volumio[983]: info: Received Get System Info Jul 15 03:03:37 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 03:03:37 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 03:03:37 volumio volumio[983]: info: Discovery: Getting this device information Jul 15 03:03:37 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:03:37 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:37 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 03:03:37 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:03:37 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:37 volumio volumio[983]: info: Listing playlists Jul 15 03:03:37 volumio volumio[983]: info: camilladsp spawned new process with pid 2698, instance 1, run: true Jul 15 03:03:37 volumio volumio[983]: info: camilladsp spawned new process with pid 2705, instance 1, run: true Jul 15 03:03:37 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 15 03:03:38 volumio volumio[983]: info: camilladsp spawned new process with pid 2712, instance 1, run: true Jul 15 03:03:38 volumio volumio[983]: info: camilladsp spawned new process with pid 2719, instance 1, run: true Jul 15 03:03:38 volumio volumio[983]: info: camilladsp spawned new process with pid 2726, instance 1, run: true Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 15 03:03:38 volumio volumio[983]: info: Received Get System Info Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 03:03:38 volumio volumio[983]: info: Discovery: Getting this device information Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:03:38 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 03:03:38 volumio volumio[983]: info: VolumeController::SetAlsaVolume0 Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:03:38 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:38 volumio volumio[983]: info: CoreStateMachine::pushState Jul 15 03:03:38 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 03:03:38 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Jul 15 03:03:38 volumio volumio[983]: info: camilladsp spawned new process with pid 2734, instance 1, run: true Jul 15 03:03:38 volumio volumio[983]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SABAJ USB AUDIO ',0 Jul 15 03:03:39 volumio volumio[983]: info: camilladsp spawned new process with pid 2741, instance 1, run: true Jul 15 03:03:39 volumio volumio[983]: info: camilladsp spawned new process with pid 2748, instance 1, run: true Jul 15 03:03:39 volumio volumio[983]: info: camilladsp spawned new process with pid 2755, instance 1, run: true Jul 15 03:03:39 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 15 03:03:39 volumio volumio[983]: info: camilladsp spawned new process with pid 2762, instance 1, run: true Jul 15 03:03:39 volumio volumio[983]: info: camilladsp spawned new process with pid 2769, instance 1, run: true Jul 15 03:03:40 volumio volumio[983]: info: camilladsp spawned new process with pid 2776, instance 1, run: true Jul 15 03:03:40 volumio volumio[983]: info: camilladsp spawned new process with pid 2783, instance 1, run: true Jul 15 03:03:40 volumio volumio[983]: info: camilladsp spawned new process with pid 2790, instance 1, run: true Jul 15 03:03:40 volumio volumio[983]: info: camilladsp spawned new process with pid 2797, instance 1, run: true Jul 15 03:03:41 volumio volumio[983]: info: camilladsp spawned new process with pid 2804, instance 1, run: true Jul 15 03:03:41 volumio volumio[983]: info: camilladsp spawned new process with pid 2811, instance 1, run: true Jul 15 03:03:41 volumio volumio[983]: info: camilladsp spawned new process with pid 2818, instance 1, run: true Jul 15 03:03:41 volumio volumio[983]: info: camilladsp spawned new process with pid 2825, instance 1, run: true Jul 15 03:03:41 volumio volumio[983]: info: camilladsp spawned new process with pid 2832, instance 1, run: true Jul 15 03:03:42 volumio volumio[983]: info: camilladsp spawned new process with pid 2839, instance 1, run: true Jul 15 03:03:42 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 15 03:03:42 volumio volumio[983]: info: camilladsp spawned new process with pid 2846, instance 1, run: true Jul 15 03:03:42 volumio volumio[983]: info: camilladsp spawned new process with pid 2853, instance 1, run: true Jul 15 03:03:42 volumio volumio[983]: info: camilladsp spawned new process with pid 2860, instance 1, run: true Jul 15 03:03:42 volumio volumio[983]: info: camilladsp spawned new process with pid 2867, instance 1, run: true Jul 15 03:03:43 volumio volumio[983]: info: camilladsp spawned new process with pid 2874, instance 1, run: true Jul 15 03:03:43 volumio volumio[983]: info: camilladsp spawned new process with pid 2881, instance 1, run: true Jul 15 03:03:43 volumio volumio[983]: info: camilladsp spawned new process with pid 2888, instance 1, run: true Jul 15 03:03:43 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:03:43 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 15 03:03:43 volumio volumio[983]: info: camilladsp spawned new process with pid 2895, instance 1, run: true Jul 15 03:03:44 volumio volumio[983]: info: camilladsp spawned new process with pid 2916, instance 1, run: true Jul 15 03:03:44 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 15 03:03:44 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 15 03:03:44 volumio volumio[983]: info: camilladsp spawned new process with pid 2923, instance 1, run: true Jul 15 03:03:44 volumio volumio[983]: info: camilladsp spawned new process with pid 2930, instance 1, run: true Jul 15 03:03:44 volumio volumio[983]: info: camilladsp spawned new process with pid 2937, instance 1, run: true Jul 15 03:03:45 volumio volumio[983]: info: camilladsp spawned new process with pid 2944, instance 1, run: true Jul 15 03:03:45 volumio volumio[983]: info: camilladsp spawned new process with pid 2951, instance 1, run: true Jul 15 03:03:45 volumio volumio[983]: info: camilladsp spawned new process with pid 2958, instance 1, run: true Jul 15 03:03:45 volumio volumio[983]: info: camilladsp spawned new process with pid 2965, instance 1, run: true Jul 15 03:03:45 volumio volumio[983]: info: camilladsp spawned new process with pid 2972, instance 1, run: true Jul 15 03:03:46 volumio volumio[983]: info: camilladsp spawned new process with pid 2979, instance 1, run: true Jul 15 03:03:46 volumio volumio[983]: info: camilladsp spawned new process with pid 2986, instance 1, run: true Jul 15 03:03:46 volumio volumio[983]: info: camilladsp spawned new process with pid 2993, instance 1, run: true Jul 15 03:03:46 volumio volumio[983]: info: camilladsp spawned new process with pid 3000, instance 1, run: true Jul 15 03:03:46 volumio volumio[983]: info: camilladsp spawned new process with pid 3007, instance 1, run: true Jul 15 03:03:47 volumio volumio[983]: info: Received Get System Version Jul 15 03:03:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 03:03:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 15 03:03:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 03:03:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 03:03:47 volumio volumio[983]: info: Discovery: Getting this device information Jul 15 03:03:47 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:03:47 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 03:03:47 volumio volumio[983]: info: camilladsp spawned new process with pid 3014, instance 1, run: true Jul 15 03:03:47 volumio volumio[983]: info: camilladsp spawned new process with pid 3021, instance 1, run: true Jul 15 03:03:47 volumio volumio[983]: info: camilladsp spawned new process with pid 3028, instance 1, run: true Jul 15 03:03:47 volumio volumio[983]: info: camilladsp spawned new process with pid 3035, instance 1, run: true Jul 15 03:03:48 volumio volumio[983]: info: camilladsp spawned new process with pid 3042, instance 1, run: true Jul 15 03:03:48 volumio volumio[983]: info: camilladsp spawned new process with pid 3049, instance 1, run: true Jul 15 03:03:48 volumio volumio[983]: info: camilladsp spawned new process with pid 3056, instance 1, run: true Jul 15 03:03:48 volumio volumio[983]: info: camilladsp spawned new process with pid 3063, instance 1, run: true Jul 15 03:03:48 volumio volumio[983]: info: camilladsp spawned new process with pid 3070, instance 1, run: true Jul 15 03:03:49 volumio volumio[983]: info: camilladsp spawned new process with pid 3077, instance 1, run: true Jul 15 03:03:49 volumio volumio[983]: info: camilladsp spawned new process with pid 3084, instance 1, run: true Jul 15 03:03:49 volumio volumio[983]: info: camilladsp spawned new process with pid 3091, instance 1, run: true Jul 15 03:03:49 volumio volumio[983]: info: camilladsp spawned new process with pid 3098, instance 1, run: true Jul 15 03:03:50 volumio volumio[983]: info: camilladsp spawned new process with pid 3105, instance 1, run: true Jul 15 03:03:50 volumio volumio[983]: info: camilladsp spawned new process with pid 3112, instance 1, run: true Jul 15 03:03:50 volumio volumio[983]: info: camilladsp spawned new process with pid 3119, instance 1, run: true Jul 15 03:03:50 volumio volumio[983]: info: camilladsp spawned new process with pid 3126, instance 1, run: true Jul 15 03:03:50 volumio volumio[983]: info: camilladsp spawned new process with pid 3133, instance 1, run: true Jul 15 03:03:51 volumio volumio[983]: info: camilladsp spawned new process with pid 3140, instance 1, run: true Jul 15 03:03:51 volumio volumio[983]: info: camilladsp spawned new process with pid 3147, instance 1, run: true Jul 15 03:03:51 volumio volumio[983]: info: camilladsp spawned new process with pid 3154, instance 1, run: true Jul 15 03:03:51 volumio volumio[983]: info: camilladsp spawned new process with pid 3161, instance 1, run: true Jul 15 03:03:52 volumio volumio[983]: info: camilladsp spawned new process with pid 3168, instance 1, run: true Jul 15 03:03:52 volumio volumio[983]: info: camilladsp spawned new process with pid 3175, instance 1, run: true Jul 15 03:03:52 volumio volumio[1118]: .....................................................................................++++ Jul 15 03:03:52 volumio volumio[1118]: e is 65537 (0x010001) Jul 15 03:03:52 volumio volumio[1118]: writing RSA key Jul 15 03:03:52 volumio volumio[983]: info: camilladsp spawned new process with pid 3183, instance 1, run: true Jul 15 03:03:52 volumio volumio[983]: info: camilladsp spawned new process with pid 3200, instance 1, run: true Jul 15 03:03:52 volumio volumio[983]: info: camilladsp spawned new process with pid 3209, instance 1, run: true Jul 15 03:03:53 volumio volumio[983]: info: camilladsp spawned new process with pid 3216, instance 1, run: true Jul 15 03:03:53 volumio volumio[983]: info: camilladsp spawned new process with pid 3223, instance 1, run: true Jul 15 03:03:53 volumio volumio[983]: info: camilladsp spawned new process with pid 3230, instance 1, run: true Jul 15 03:03:53 volumio volumio[983]: info: camilladsp spawned new process with pid 3237, instance 1, run: true Jul 15 03:03:53 volumio volumio[983]: info: camilladsp spawned new process with pid 3244, instance 1, run: true Jul 15 03:03:54 volumio volumio[983]: info: camilladsp spawned new process with pid 3251, instance 1, run: true Jul 15 03:03:54 volumio volumio[983]: info: camilladsp spawned new process with pid 3258, instance 1, run: true Jul 15 03:03:54 volumio volumio[983]: info: camilladsp spawned new process with pid 3265, instance 1, run: true Jul 15 03:03:54 volumio volumio[983]: info: camilladsp spawned new process with pid 3272, instance 1, run: true Jul 15 03:03:55 volumio volumio[983]: info: camilladsp spawned new process with pid 3279, instance 1, run: true Jul 15 03:03:55 volumio volumio[983]: info: camilladsp spawned new process with pid 3286, instance 1, run: true Jul 15 03:03:55 volumio volumio[983]: info: camilladsp spawned new process with pid 3293, instance 1, run: true Jul 15 03:03:55 volumio volumio[983]: info: camilladsp spawned new process with pid 3300, instance 1, run: true Jul 15 03:03:55 volumio volumio[983]: info: camilladsp spawned new process with pid 3307, instance 1, run: true Jul 15 03:03:56 volumio volumio[983]: info: camilladsp spawned new process with pid 3314, instance 1, run: true Jul 15 03:03:56 volumio volumio[983]: info: camilladsp spawned new process with pid 3321, instance 1, run: true Jul 15 03:03:56 volumio volumio[983]: info: camilladsp spawned new process with pid 3328, instance 1, run: true Jul 15 03:03:56 volumio volumio[983]: info: camilladsp spawned new process with pid 3335, instance 1, run: true Jul 15 03:03:56 volumio volumio[983]: info: camilladsp spawned new process with pid 3342, instance 1, run: true Jul 15 03:03:57 volumio volumio[983]: info: camilladsp spawned new process with pid 3349, instance 1, run: true Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 15 03:03:57 volumio volumio[983]: info: Received Get System Info Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 03:03:57 volumio volumio[983]: info: Discovery: Getting this device information Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:03:57 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 15 03:03:57 volumio volumio[983]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 15 03:03:57 volumio volumio[983]: info: Received Get System Version Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 15 03:03:57 volumio volumio[983]: info: Received Get System Info Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 15 03:03:57 volumio volumio[983]: info: Discovery: Getting this device information Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:03:57 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:03:57 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 15 03:03:57 volumio volumio[983]: info: camilladsp spawned new process with pid 3356, instance 1, run: true Jul 15 03:03:57 volumio volumio[983]: info: camilladsp spawned new process with pid 3363, instance 1, run: true Jul 15 03:03:57 volumio volumio[983]: info: camilladsp spawned new process with pid 3370, instance 1, run: true Jul 15 03:03:58 volumio volumio[983]: info: camilladsp spawned new process with pid 3377, instance 1, run: true Jul 15 03:03:58 volumio volumio[983]: info: camilladsp spawned new process with pid 3384, instance 1, run: true Jul 15 03:03:58 volumio volumio[983]: info: camilladsp spawned new process with pid 3391, instance 1, run: true Jul 15 03:03:58 volumio volumio[983]: info: camilladsp spawned new process with pid 3398, instance 1, run: true Jul 15 03:03:59 volumio volumio[983]: info: camilladsp spawned new process with pid 3405, instance 1, run: true Jul 15 03:03:59 volumio volumio[983]: info: camilladsp spawned new process with pid 3412, instance 1, run: true Jul 15 03:03:59 volumio volumio[983]: info: camilladsp spawned new process with pid 3419, instance 1, run: true Jul 15 03:03:59 volumio volumio[983]: info: camilladsp spawned new process with pid 3426, instance 1, run: true Jul 15 03:03:59 volumio volumio[983]: info: camilladsp spawned new process with pid 3433, instance 1, run: true Jul 15 03:04:00 volumio volumio[983]: info: camilladsp spawned new process with pid 3440, instance 1, run: true Jul 15 03:04:00 volumio volumio[983]: info: camilladsp spawned new process with pid 3447, instance 1, run: true Jul 15 03:04:00 volumio volumio[983]: info: camilladsp spawned new process with pid 3454, instance 1, run: true Jul 15 03:04:00 volumio volumio[983]: info: camilladsp spawned new process with pid 3461, instance 1, run: true Jul 15 03:04:00 volumio volumio[983]: info: camilladsp spawned new process with pid 3468, instance 1, run: true Jul 15 03:04:01 volumio volumio[983]: info: camilladsp spawned new process with pid 3475, instance 1, run: true Jul 15 03:04:01 volumio volumio[983]: info: camilladsp spawned new process with pid 3482, instance 1, run: true Jul 15 03:04:01 volumio volumio[983]: info: camilladsp spawned new process with pid 3489, instance 1, run: true Jul 15 03:04:01 volumio volumio[983]: info: camilladsp spawned new process with pid 3496, instance 1, run: true Jul 15 03:04:02 volumio volumio[983]: info: camilladsp spawned new process with pid 3503, instance 1, run: true Jul 15 03:04:02 volumio volumio[983]: info: camilladsp spawned new process with pid 3510, instance 1, run: true Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 15 03:04:02 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 15 03:04:02 volumio volumio[983]: info: camilladsp spawned new process with pid 3521, instance 1, run: true Jul 15 03:04:02 volumio volumio[983]: info: camilladsp spawned new process with pid 3528, instance 1, run: true Jul 15 03:04:03 volumio volumio[983]: info: camilladsp spawned new process with pid 3535, instance 1, run: true Jul 15 03:04:03 volumio volumio[983]: info: camilladsp spawned new process with pid 3580, instance 1, run: true Jul 15 03:04:03 volumio volumio[983]: info: camilladsp spawned new process with pid 3587, instance 1, run: true Jul 15 03:04:03 volumio volumio[983]: info: camilladsp spawned new process with pid 3594, instance 1, run: true Jul 15 03:04:04 volumio volumio[983]: info: camilladsp spawned new process with pid 3601, instance 1, run: true Jul 15 03:04:04 volumio volumio[983]: info: camilladsp spawned new process with pid 3608, instance 1, run: true Jul 15 03:04:04 volumio volumio[983]: info: camilladsp spawned new process with pid 3615, instance 1, run: true Jul 15 03:04:04 volumio volumio[983]: info: camilladsp spawned new process with pid 3622, instance 1, run: true Jul 15 03:04:04 volumio volumio[983]: info: camilladsp spawned new process with pid 3629, instance 1, run: true Jul 15 03:04:05 volumio volumio[983]: info: camilladsp spawned new process with pid 3636, instance 1, run: true Jul 15 03:04:05 volumio volumio[983]: info: camilladsp spawned new process with pid 3643, instance 1, run: true Jul 15 03:04:05 volumio volumio[983]: info: camilladsp spawned new process with pid 3650, instance 1, run: true Jul 15 03:04:05 volumio volumio[983]: info: camilladsp spawned new process with pid 3657, instance 1, run: true Jul 15 03:04:05 volumio volumio[983]: info: camilladsp spawned new process with pid 3664, instance 1, run: true Jul 15 03:04:06 volumio volumio[983]: info: camilladsp spawned new process with pid 3671, instance 1, run: true Jul 15 03:04:06 volumio volumio[983]: info: camilladsp spawned new process with pid 3678, instance 1, run: true Jul 15 03:04:06 volumio volumio[983]: info: camilladsp spawned new process with pid 3685, instance 1, run: true Jul 15 03:04:06 volumio volumio[983]: info: camilladsp spawned new process with pid 3692, instance 1, run: true Jul 15 03:04:07 volumio volumio[983]: info: camilladsp spawned new process with pid 3699, instance 1, run: true Jul 15 03:04:07 volumio volumio[983]: info: camilladsp spawned new process with pid 3706, instance 1, run: true Jul 15 03:04:07 volumio volumio[983]: info: camilladsp spawned new process with pid 3713, instance 1, run: true Jul 15 03:04:07 volumio volumio[983]: info: camilladsp spawned new process with pid 3721, instance 1, run: true Jul 15 03:04:07 volumio volumio[983]: info: camilladsp spawned new process with pid 3728, instance 1, run: true Jul 15 03:04:08 volumio volumio[983]: info: camilladsp spawned new process with pid 3735, instance 1, run: true Jul 15 03:04:08 volumio volumio[983]: info: camilladsp spawned new process with pid 3742, instance 1, run: true Jul 15 03:04:08 volumio volumio[983]: info: camilladsp spawned new process with pid 3749, instance 1, run: true Jul 15 03:04:08 volumio volumio[983]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 15 03:04:08 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 15 03:04:08 volumio volumio[983]: info: Preparing to save Alsa Options, stopping services first Jul 15 03:04:08 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:04:08 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:08 volumio volumio[983]: info: CoreCommandRouter::volumioPause Jul 15 03:04:08 volumio volumio[983]: info: CoreStateMachine::pause Jul 15 03:04:08 volumio volumio[983]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jul 15 03:04:08 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 15 03:04:08 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 15 03:04:08 volumio volumio[983]: info: Found match in Cards Database: setting mixer PCM for card Headphones Jul 15 03:04:08 volumio volumio[983]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 15 03:04:08 volumio volumio[983]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; linear Vol Steps: 1 Jul 15 03:04:08 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 15 03:04:08 volumio volumio[983]: info: Disabling external Volume Control Jul 15 03:04:08 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:04:08 volumio volumio[983]: info: Preparing to generate the ALSA configuration file Jul 15 03:04:08 volumio volumio[983]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 15 03:04:08 volumio volumio[983]: info: Reading ALSA contributions from plugins. Jul 15 03:04:09 volumio volumio[983]: info: camilladsp spawned new process with pid 3764, instance 1, run: true Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 15 03:04:09 volumio volumio[983]: info: VolumeController:: Volume=96 Mute =false Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:04:09 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:09 volumio volumio[983]: info: CoreStateMachine::pushState Jul 15 03:04:09 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Jul 15 03:04:09 volumio volumio[983]: info: camilladsp spawned new process with pid 3775, instance 1, run: true Jul 15 03:04:09 volumio volumio[983]: info: Asound.conf file written Jul 15 03:04:09 volumio sudo[3783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 15 03:04:09 volumio sudo[3783]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:09 volumio sudo[3783]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:09 volumio volumio[983]: info: Output device has changed, restarting MPD Jul 15 03:04:09 volumio sudo[3788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 03:04:09 volumio volumio[983]: info: Output device has changed, restarting Shairport Sync Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:09 volumio sudo[3788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:09 volumio sudo[3788]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:09 volumio sudo[3790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 03:04:09 volumio sudo[3790]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:09 volumio systemd[1]: Stopping Music Player Daemon... Jul 15 03:04:09 volumio volumio[983]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 15 03:04:09 volumio volumio[983]: info: Output device has changed, restarting MPD Jul 15 03:04:09 volumio sudo[3797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 03:04:09 volumio sudo[3797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:09 volumio volumio[983]: info: Output device has changed, restarting Shairport Sync Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:09 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:09 volumio sudo[3797]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:09 volumio sudo[3800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 03:04:09 volumio sudo[3800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:10 volumio volumio[983]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 15 03:04:10 volumio volumio[983]: info: MPD Permissions set Jul 15 03:04:10 volumio volumio[983]: info: MPD Permissions set Jul 15 03:04:10 volumio volumio[983]: info: camilladsp spawned new process with pid 3805, instance 1, run: true Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio systemd[1]: mpd.service: Succeeded. Jul 15 03:04:10 volumio systemd[1]: Stopped Music Player Daemon. Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio systemd[1]: Starting Music Player Daemon... Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:10 volumio volumio[983]: info: Starting Shairport Sync Jul 15 03:04:10 volumio volumio[983]: info: Starting Shairport Sync Jul 15 03:04:10 volumio sudo[3822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 15 03:04:10 volumio sudo[3822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:10 volumio sudo[3825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 15 03:04:10 volumio sudo[3825]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 15 03:04:10 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 15 03:04:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 15 03:04:10 volumio sudo[3816]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 15 03:04:10 volumio sudo[3816]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:10 volumio sudo[3816]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 15 03:04:10 volumio sudo[3822]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 15 03:04:10 volumio volumio[983]: info: Shairport-Sync Started Jul 15 03:04:10 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 15 03:04:10 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 15 03:04:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 15 03:04:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 15 03:04:10 volumio sudo[3825]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:10 volumio volumio[983]: info: Shairport-Sync Started Jul 15 03:04:12 volumio volumio[983]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 15 03:04:12 volumio volumio[983]: info: CoreStateMachine::getcurrentVolume Jul 15 03:04:12 volumio volumio[983]: info: CoreCommandRouter::volumioRetrievevolume Jul 15 03:04:13 volumio volumio[983]: info: VolumeController:: Volume=96 Mute =false Jul 15 03:04:13 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:04:13 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:13 volumio volumio[983]: info: CoreStateMachine::pushState Jul 15 03:04:13 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:13 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Jul 15 03:04:13 volumio volumio[983]: info: CoreStateMachine::updateTrackBlock Jul 15 03:04:13 volumio volumio[983]: info: CorePlayQueue::getTrackBlock Jul 15 03:04:13 volumio volumio[983]: info: CoreCommandRouter::volumioRetrievevolume Jul 15 03:04:13 volumio mpd[3829]: Jul 15 03:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 15 03:04:13 volumio systemd[1]: Started Music Player Daemon. Jul 15 03:04:13 volumio sudo[3790]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:13 volumio sudo[3800]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:13 volumio volumio[983]: error: MPD error: The expression evaluated to a falsy value: Jul 15 03:04:13 volumio volumio[983]: assert.ok(self.idling) Jul 15 03:04:13 volumio volumio[983]: error: The expression evaluated to a falsy value: Jul 15 03:04:13 volumio volumio[983]: assert.ok(self.idling) Jul 15 03:04:13 volumio volumio[983]: error: updateQueue error: null Jul 15 03:04:13 volumio volumio[983]: info: VolumeController:: Volume=96 Mute =false Jul 15 03:04:13 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:04:13 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:13 volumio volumio[983]: info: CoreStateMachine::pushState Jul 15 03:04:13 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:13 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Jul 15 03:04:15 volumio volumio[983]: info: camilladsp spawned new process with pid 3839, instance 1, run: true Jul 15 03:04:19 volumio kernel: hwmon hwmon1: Voltage normalised Jul 15 03:04:22 volumio volumio[983]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:04:22 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:22 volumio volumio[983]: info: Restoring Previous Volume level: 100 false false Jul 15 03:04:22 volumio volumio[983]: info: Output device has changed, restarting MPD Jul 15 03:04:22 volumio sudo[3848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 03:04:22 volumio sudo[3848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:22 volumio volumio[983]: info: Output device has changed, restarting Shairport Sync Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:22 volumio sudo[3848]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:22 volumio sudo[3851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 03:04:22 volumio sudo[3851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:22 volumio systemd[1]: Stopping Music Player Daemon... Jul 15 03:04:22 volumio volumio[983]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 15 03:04:22 volumio volumio[983]: info: Volume configurations have been set Jul 15 03:04:22 volumio systemd[1]: mpd.service: Succeeded. Jul 15 03:04:22 volumio systemd[1]: Stopped Music Player Daemon. Jul 15 03:04:22 volumio systemd[1]: Starting Music Player Daemon... Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 15 03:04:22 volumio volumio[983]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: Max Vol: 100 Vol Curve; linear Vol Steps: 1 Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 15 03:04:22 volumio volumio[983]: info: Disabling external Volume Control Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:04:22 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:22 volumio volumio[983]: info: CoreStateMachine::pushState Jul 15 03:04:22 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Jul 15 03:04:22 volumio volumio[983]: info: MPD Permissions set Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:22 volumio sudo[3856]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 15 03:04:22 volumio sudo[3856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:22 volumio sudo[3856]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 15 03:04:22 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 15 03:04:23 volumio volumio[983]: info: Starting Shairport Sync Jul 15 03:04:23 volumio sudo[3871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 15 03:04:23 volumio sudo[3871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 15 03:04:23 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 15 03:04:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 15 03:04:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 15 03:04:23 volumio sudo[3871]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:23 volumio volumio[983]: info: Shairport-Sync Started Jul 15 03:04:23 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 15 03:04:25 volumio mpd[3866]: Jul 15 03:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 15 03:04:25 volumio systemd[1]: Started Music Player Daemon. Jul 15 03:04:25 volumio sudo[3851]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:25 volumio volumio[983]: error: updateQueue error: null Jul 15 03:04:26 volumio volumio[983]: info: VolumeController::SetAlsaVolume100 Jul 15 03:04:26 volumio volumio[983]: info: CoreCommandRouter::volumioGetState Jul 15 03:04:26 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:26 volumio volumio[983]: info: CoreStateMachine::pushState Jul 15 03:04:26 volumio volumio[983]: info: CorePlayQueue::getTrack 0 Jul 15 03:04:26 volumio volumio[983]: info: CoreCommandRouter::volumioPushState Jul 15 03:04:29 volumio kernel: hwmon hwmon1: Voltage normalised Jul 15 03:04:34 volumio volumio[983]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jul 15 03:04:34 volumio sudo[3882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 03:04:34 volumio sudo[3882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:34 volumio sudo[3882]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:34 volumio volumio[983]: info: MPD Permissions set Jul 15 03:04:34 volumio sudo[3885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 03:04:34 volumio sudo[3885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:34 volumio systemd[1]: Stopping Music Player Daemon... Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 03:04:34 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 03:04:34 volumio systemd[1]: mpd.service: Succeeded. Jul 15 03:04:34 volumio systemd[1]: Stopped Music Player Daemon. Jul 15 03:04:34 volumio systemd[1]: Starting Music Player Daemon... Jul 15 03:04:34 volumio sudo[3891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 15 03:04:34 volumio sudo[3891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:34 volumio sudo[3891]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:36 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 15 03:04:37 volumio mpd[3893]: Jul 15 03:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 15 03:04:37 volumio systemd[1]: Started Music Player Daemon. Jul 15 03:04:37 volumio sudo[3885]: pam_unix(sudo:session): session closed for user root Jul 15 03:04:37 volumio volumio[983]: error: updateQueue error: null Jul 15 03:04:40 volumio kernel: hwmon hwmon1: Voltage normalised Jul 15 03:04:46 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:04:47 volumio volumio[983]: info: Retrieving Cloud Streaming UI Jul 15 03:04:47 volumio volumio[983]: info: Getting Tidal Cloud Configuration Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:04:47 volumio volumio[983]: info: Getting Qobuz Cloud Configuration Jul 15 03:04:47 volumio volumio[983]: info: Asking plugin for UI Config Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:04:47 volumio volumio[983]: info: Getting Spotify Cloud Configuration Jul 15 03:04:47 volumio volumio[983]: info: Asking plugin for UI Config Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:04:47 volumio volumio[983]: info: Saving Spotify Acccount Jul 15 03:04:47 volumio volumio[983]: info: Got Tidal Cloud Configuration Jul 15 03:04:47 volumio volumio[983]: info: Got it Jul 15 03:04:47 volumio volumio[983]: info: Got it Jul 15 03:04:47 volumio volumio[983]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 03:04:47 volumio volumio[983]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::volumioGetBrowseSources Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::volumioGetBrowseSources Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::volumioGetBrowseSources Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 03:04:47 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 15 03:04:50 volumio kernel: hwmon hwmon1: Voltage normalised Jul 15 03:04:51 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 03:04:54 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 15 03:04:55 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 15 03:04:55 volumio volumio[983]: info: Disabling MyMusic plugin upnp Jul 15 03:04:55 volumio sudo[3903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jul 15 03:04:55 volumio sudo[3903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 03:04:55 volumio volumio[983]: error: Upnp client error: Error: This socket has been ended by the other party Jul 15 03:04:55 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Jul 15 03:04:58 volumio volumio[983]: info: Enabling MyMusic plugin upnp Jul 15 03:04:58 volumio volumio[983]: info: Enabling plugin upnp Jul 15 03:04:58 volumio volumio[983]: info: Loading plugin "upnp"... Jul 15 03:04:58 volumio volumio[983]: info: [1720980298068] Starting Upmpd Daemon Jul 15 03:04:58 volumio volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 15 03:04:58 volumio volumio[983]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 03:04:58 volumio volumio[983]: Error: listen EADDRINUSE: address already in use :::6599 Jul 15 03:04:58 volumio volumio[983]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jul 15 03:04:58 volumio volumio[983]: at listenInCluster (net.js:1366:12) Jul 15 03:04:58 volumio volumio[983]: at Server.listen (net.js:1452:7) Jul 15 03:04:58 volumio volumio[983]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jul 15 03:04:58 volumio volumio[983]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jul 15 03:04:58 volumio volumio[983]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 15 03:04:58 volumio volumio[983]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 15 03:04:58 volumio volumio[983]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 15 03:04:58 volumio volumio[983]: code: 'EADDRINUSE', Jul 15 03:04:58 volumio volumio[983]: errno: -98, Jul 15 03:04:58 volumio volumio[983]: syscall: 'listen', Jul 15 03:04:58 volumio volumio[983]: address: '::', Jul 15 03:04:58 volumio volumio[983]: port: 6599 Jul 15 03:04:58 volumio volumio[983]: } Jul 15 03:04:58 volumio volumio[983]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 03:04:58 volumio sudo[3914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-15 03:03 Jul 15 03:04:58 volumio sudo[3914]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"