-- 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"