Dec 16 15:19:00 volumio volumio[1051]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 16 15:19:00 volumio volumio[1051]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 16 15:19:00 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:00 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:00 volumio volumio[1051]: info: Starting MyVolumio Remote Streaming Endpoints Dec 16 15:19:00 volumio volumio[1051]: info: MyVolumio login type: Token Dec 16 15:19:00 volumio volumio[1051]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 16 15:19:00 volumio volumio[1051]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 16 15:19:01 volumio volumio[1051]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 16 15:19:01 volumio volumio[1051]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 16 15:19:01 volumio volumio[1051]: info: Streaming services startup Dec 16 15:19:01 volumio volumio[1051]: info: Starting Streaming Daemon Dec 16 15:19:01 volumio sudo[1939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 16 15:19:01 volumio volumio[1051]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 16 15:19:01 volumio sudo[1939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 15:19:01 volumio sudo[1939]: pam_unix(sudo:session): session closed for user root Dec 16 15:19:01 volumio volumio[1051]: error: Cannot start Volumio Streaming Daemon Dec 16 15:19:01 volumio volumio[1051]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 16 15:19:01 volumio volumio[1051]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 16 15:19:01 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 16 15:19:01 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Dec 16 15:19:01 volumio volumio[1051]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_history%22%7D%7D Dec 16 15:19:02 volumio upmpdcli[1959]: writing RSA key Dec 16 15:19:13 volumio volumio[1051]: info: [ytmusic] Innertube support service: Start service with Deno: deno 2.6.1 (stable, release, x86_64-unknown-linux-gnu) Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Dec 16 15:19:13 volumio volumio[1051]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_history%22%7D%7D Dec 16 15:19:13 volumio volumio[1051]: error: MyVolumio Custom Token format not valid, refreshing it Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 15:19:13 volumio volumio[1051]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 16 15:19:13 volumio volumio[1051]: info: Received Get System Version Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:19:13 volumio volumio[1051]: info: Received Get System Info Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 15:19:13 volumio volumio[1051]: info: Discovery: Getting this device information Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState Dec 16 15:19:13 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 15:19:13 volumio volumio-remote-updater[771]: Test mode disabled Dec 16 15:19:13 volumio volumio-remote-updater[771]: Alpha mode disabled Dec 16 15:19:13 volumio volumio-remote-updater[771]: Alpha legacy test mode disabled Dec 16 15:19:13 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 16 15:19:14 volumio volumio[1051]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 16 15:19:14 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 16 15:19:14 volumio volumio[1051]: info: MyVolumio login type: Token Dec 16 15:19:14 volumio volumio[1051]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 16 15:19:15 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 15:19:15 volumio volumio[1051]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 16 15:19:15 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState Dec 16 15:19:15 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:19:16 volumio volumio[1051]: info: MyVolumio token set successfully Dec 16 15:19:16 volumio volumio[1051]: info: MYVOLUMIO: Adding device Dec 16 15:19:16 volumio volumio[1051]: info: MYVOLUMIO: Evaluating Server Dec 16 15:19:16 volumio volumio[1051]: info: MyVolumio status changed Dec 16 15:19:16 volumio volumio[1051]: info: Streaming services startup Dec 16 15:19:16 volumio volumio[1051]: info: Starting Streaming Daemon Dec 16 15:19:16 volumio volumio[1051]: info: Removing browser output: myVolumio user plan is not superstar Dec 16 15:19:16 volumio volumio[1051]: info: Removing audio output: Dec 16 15:19:16 volumio volumio[1051]: info: Stoppping Tunnel 1 Dec 16 15:19:16 volumio sudo[2112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 16 15:19:16 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 15:19:16 volumio sudo[2114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 16 15:19:16 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 15:19:16 volumio sudo[2112]: pam_unix(sudo:session): session closed for user root Dec 16 15:19:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 15:19:16 volumio volumio[1051]: error: Cannot start Volumio Streaming Daemon Dec 16 15:19:16 volumio volumio[1051]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 16 15:19:16 volumio volumio[1051]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 16 15:19:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 15:19:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 15:19:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 15:19:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 15:19:16 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 15:19:16 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root Dec 16 15:19:16 volumio volumio[1051]: info: Remote SSH Stopped Dec 16 15:19:20 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:19:20 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:19:20 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 16 15:19:20 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 16 15:19:20 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 16 15:19:20 volumio volumio[1051]: info: CoreCommandRouter::volumioGetBrowseSources Dec 16 15:19:20 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:19:21 volumio volumio[1051]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 16 15:19:21 volumio volumio[1051]: info: Completed starting MyVolumio Plugin Dec 16 15:19:21 volumio volumio[1051]: [Metrics] CommandRouter: 63s 44.20ms Dec 16 15:19:21 volumio volumio[1051]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 15:19:21 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:19:21 volumio volumio[1051]: info: VolumeController:: Setting startup Volume 80 Dec 16 15:19:21 volumio volumio[1051]: info: VolumeController::SetAlsaVolume80 Dec 16 15:19:21 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:21 volumio volumio[1051]: info: CoreCommandRouter::Close All Modals sent Dec 16 15:19:21 volumio volumio[1051]: info: CoreCommandRouter::Close All Modals sent Dec 16 15:19:21 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:19:21 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:19:21 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:19:21 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:19:21 volumio volumio[1051]: info: FusionDsp - Volumio is not playing Dec 16 15:19:21 volumio volumio[1051]: info: FusionDsp - Clipped samples monitor stopped Dec 16 15:19:21 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:19:21 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:19:21 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:19:21 volumio volumio[1051]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 16 15:19:21 volumio volumio[1051]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 16 15:19:21 volumio volumio[1051]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 16 15:19:21 volumio volumio[1051]: info: camilladsp stopping service pid 1804... Dec 16 15:19:22 volumio volumio[1051]: grep: /proc/1804/cmdline: binary file matches Dec 16 15:19:22 volumio volumio[1051]: grep: /proc/1804/cmdline: binary file matches Dec 16 15:19:22 volumio volumio[1051]: grep: /proc/1804/cmdline: binary file matches Dec 16 15:19:22 volumio volumio[1051]: info: camilladsp service terminated, instance 1 Dec 16 15:19:22 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:19:22 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:19:22 volumio volumio[1051]: info: camilladsp service started and running in background, instance 1 Dec 16 15:19:22 volumio volumio[1051]: info: Setting Geolocation for MyVolumio to as1 Dec 16 15:19:22 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:22 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:22 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:22 volumio volumio[1051]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 16 15:19:22 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 15:19:22 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 15:19:22 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 15:19:23 volumio volumio[1051]: info: Updating MyVolumio device info Dec 16 15:19:23 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:23 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:23 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:23 volumio volumio[1051]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 16 15:19:24 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Dec 16 15:19:24 volumio volumio[1051]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_history%22%7D%7D Dec 16 15:19:25 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Dec 16 15:19:25 volumio volumio[1051]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_history%22%7D%7D Dec 16 15:19:26 volumio volumio[1051]: info: MYVOLUMIO: Adding device Dec 16 15:19:26 volumio volumio[1051]: info: MYVOLUMIO: Evaluating Server Dec 16 15:19:26 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Dec 16 15:19:26 volumio volumio[1051]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_history%22%7D%7D Dec 16 15:19:27 volumio volumio[1051]: info: [ytmusic] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":38057}} Dec 16 15:19:27 volumio volumio[1051]: info: [ytmusic] Innertube support service running at http://127.0.0.1:38057 Dec 16 15:19:33 volumio volumio[1051]: info: BOOT COMPLETED Dec 16 15:19:33 volumio volumio[1051]: info: Setting Geolocation for MyVolumio to as1 Dec 16 15:19:33 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:33 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:33 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:33 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Dec 16 15:19:33 volumio volumio[1051]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_home%22%7D%7D Dec 16 15:19:33 volumio volumio[1051]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 16 15:19:33 volumio volumio[1051]: info: [ytmusic] Obtained session PO token using datasyncIdToken (expires in 43199 seconds) Dec 16 15:19:34 volumio volumio[1051]: info: Updating MyVolumio device info Dec 16 15:19:34 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:34 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:34 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:19:37 volumio volumio[1051]: info: [ytmusic] Going to refresh session PO token in 43099 seconds Dec 16 15:19:37 volumio volumio[1051]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 16 15:19:37 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 15:19:37 volumio volumio[1051]: info: CURURI: music-library Dec 16 15:19:37 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:38 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:38 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:38 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:38 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:38 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:40 volumio volumio[1051]: error: Request to https://www.youtube.com/youtubei/v1/browse?prettyPrint=false&alt=json failed with status code 400 Error: Request to https://www.youtube.com/youtubei/v1/browse?prettyPrint=false&alt=json failed with status code 400 Dec 16 15:19:40 volumio volumio[1051]: at HTTPClient.fetch (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:2458:11) Dec 16 15:19:40 volumio volumio[1051]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 16 15:19:40 volumio volumio[1051]: at async Actions.execute (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/node_modules/volumio-youtubei.js/bundle/node.cjs:33632:22) Dec 16 15:19:40 volumio volumio[1051]: at async EndpointModel.expandSectionList (/data/plugins/music_service/ytmusic/dist/lib/model/BaseModel.js:26:34) Dec 16 15:19:40 volumio volumio[1051]: at async EndpointModel._EndpointModel_doGetContents (/data/plugins/music_service/ytmusic/dist/lib/model/EndpointModel.js:44:9) Dec 16 15:19:40 volumio volumio[1051]: at async GenericViewHandler.getContents (/data/plugins/music_service/ytmusic/dist/lib/controller/browse/view-handlers/GenericViewHandler.js:36:26) Dec 16 15:19:40 volumio volumio[1051]: at async GenericViewHandler.browse (/data/plugins/music_service/ytmusic/dist/lib/controller/browse/view-handlers/FeedViewHandler.js:24:26) Dec 16 15:19:40 volumio volumio[1051]: at async BrowseController.browseUri (/data/plugins/music_service/ytmusic/dist/lib/controller/browse/BrowseController.js:30:20) Dec 16 15:19:40 volumio volumio[1051]: error: Failed to execute browseSource: Error: Request to https://www.youtube.com/youtubei/v1/browse?prettyPrint=false&alt=json failed with status code 400 Dec 16 15:19:43 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 15:19:43 volumio volumio[1051]: info: CURURI: music-library Dec 16 15:19:43 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:45 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 15:19:45 volumio volumio[1051]: info: CURURI: music-library/INTERNAL Dec 16 15:19:46 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:49 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 15:19:49 volumio volumio[1051]: info: CURURI: music-library/INTERNAL/中文歌 Dec 16 15:19:49 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:57 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 15:19:57 volumio volumio[1051]: info: CURURI: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca Dec 16 15:19:57 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:59 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 16 15:19:59 volumio volumio[1051]: info: CURURI: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon Dec 16 15:19:59 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/01 A Loose for Peace.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/02 Blue Moon.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/03 Boa Viagem.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/04 Graciag A la Vida.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/05 Migrating Bird.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/07 Ela E Carioca.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/08 Why Are The Flowers So Red.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/09 Dancing Queen.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/10 Snaky Year.flac Dec 16 15:19:59 volumio volumio[1051]: info: Preloading song: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/11 The Horsing Hill.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/01 A Loose for Peace.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F01%20A%20Loose%20for%20Peace.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/01 A Loose for Peace.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/02 Blue Moon.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F02%20Blue%20Moon.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/02 Blue Moon.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/03 Boa Viagem.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F03%20Boa%20Viagem.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/03 Boa Viagem.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/04 Graciag A la Vida.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F04%20Graciag%20A%20la%20Vida.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/04 Graciag A la Vida.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/05 Migrating Bird.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F05%20Migrating%20Bird.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/05 Migrating Bird.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F06%20Wind%20Is%20Blowing%E9%A2%A8%E7%B9%BC%E7%BA%8C%E5%90%B9.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/07 Ela E Carioca.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F07%20Ela%20E%20Carioca.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/07 Ela E Carioca.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/08 Why Are The Flowers So Red.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F08%20Why%20Are%20The%20Flowers%20So%20Red.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/08 Why Are The Flowers So Red.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/09 Dancing Queen.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F09%20Dancing%20Queen.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/09 Dancing Queen.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/10 Snaky Year.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F10%20Snaky%20Year.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/10 Snaky Year.flac Dec 16 15:19:59 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/11 The Horsing Hill.flac in service mpd Dec 16 15:19:59 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=Sylvain%20Gagnon%20feat.%20Bianca/Blue%20Moon/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2FSylvain%20Gagnon%20feat.%20Bianca%2FBlue%20Moon%2F11%20The%20Horsing%20Hill.flac&metadata=false Dec 16 15:19:59 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/11 The Horsing Hill.flac Dec 16 15:20:06 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:20:06 volumio volumio[1051]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 16 15:20:06 volumio volumio[1051]: info: CoreStateMachine::ClearQueue Dec 16 15:20:06 volumio volumio[1051]: info: CoreStateMachine::stop Dec 16 15:20:06 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 15:20:06 volumio volumio[1051]: info: CorePlayQueue::clearPlayQueue Dec 16 15:20:06 volumio volumio[1051]: info: CorePlayQueue::saveQueue Dec 16 15:20:06 volumio volumio[1051]: info: CoreCommandRouter::volumioPushQueue Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::addQueueItems Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::addQueueItems Dec 16 15:20:07 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:20:07 volumio volumio[1051]: info: Adding Item to queue: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac Dec 16 15:20:07 volumio volumio[1051]: info: Using cached record of: music-library/INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushQueue Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::saveQueue Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::updateTrackBlock Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrackBlock Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPlay Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::play index 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::stop Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::play index undefined Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::startPlaybackTimer Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand stop took 4 milliseconds Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand clear took 1 milliseconds Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac" Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Dec 16 15:20:07 volumio volumio[1051]: info: Ignoring MPD Status Update Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Dec 16 15:20:07 volumio volumio[1051]: info: Ignoring MPD Status Update Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand add "INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac" took 2 milliseconds Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand play Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Dec 16 15:20:07 volumio volumio[1051]: info: Ignoring MPD Status Update Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Dec 16 15:20:07 volumio volumio[1051]: info: Ignoring MPD Status Update Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Dec 16 15:20:07 volumio volumio[1051]: info: Ignoring MPD Status Update Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 7ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 6ms Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand play took 5 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 4ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 3ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 4ms Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Dec 16 15:20:07 volumio volumio[1051]: info: Ignoring MPD Status Update Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Dec 16 15:20:07 volumio volumio[1051]: info: Ignoring MPD Status Update Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces system playlist update Dec 16 15:20:07 volumio volumio[1051]: info: Ignoring MPD Status Update Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 3ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 2ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 2ms Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces state update: player Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::getState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces state update: player Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::getState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces state update: player Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::getState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces state update: player Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::getState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces state update: player Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand status took 22 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::getState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand status took 20 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand status took 15 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand status took 14 milliseconds Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces state update: player Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::getState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces state update: player Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::getState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:20:07 volumio volumio[1051]: info: Dec 16 15:20:07 volumio volumio[1051]: ---------------------------- MPD announces state update: player Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::getState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand status Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand status took 9 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"553 Kbps","isStreaming":false,"title":"Wind Is Blowing風繼續吹","artist":"Sylvain Gagnon feat. Bianca","album":"Blue Moon","uri":"INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac","trackType":"flac"} Dec 16 15:20:07 volumio volumio[1051]: verbose: CURRENT POSITION 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus stop Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"557 Kbps","isStreaming":false,"title":"Wind Is Blowing風繼續吹","artist":"Sylvain Gagnon feat. Bianca","album":"Blue Moon","uri":"INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac","trackType":"flac"} Dec 16 15:20:07 volumio volumio[1051]: verbose: CURRENT POSITION 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Dec 16 15:20:07 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"556 Kbps","isStreaming":false,"title":"Wind Is Blowing風繼續吹","artist":"Sylvain Gagnon feat. Bianca","album":"Blue Moon","uri":"INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac","trackType":"flac"} Dec 16 15:20:07 volumio volumio[1051]: verbose: CURRENT POSITION 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Dec 16 15:20:07 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"556 Kbps","isStreaming":false,"title":"Wind Is Blowing風繼續吹","artist":"Sylvain Gagnon feat. Bianca","album":"Blue Moon","uri":"INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac","trackType":"flac"} Dec 16 15:20:07 volumio volumio[1051]: verbose: CURRENT POSITION 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Dec 16 15:20:07 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 67ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 95ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 95ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 90ms Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand status took 71 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand status took 71 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand status took 70 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 67 milliseconds Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseState Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"550 Kbps","isStreaming":false,"title":"Wind Is Blowing風繼續吹","artist":"Sylvain Gagnon feat. Bianca","album":"Blue Moon","uri":"INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac","trackType":"flac"} Dec 16 15:20:07 volumio volumio[1051]: verbose: CURRENT POSITION 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Dec 16 15:20:07 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 118ms Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 183 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 179 milliseconds Dec 16 15:20:07 volumio volumio[1051]: info: sendMpdCommand playlistinfo took 179 milliseconds Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:20:07 volumio volumio[1051]: verbose: ControllerMpd::parseTrackInfo Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"550 Kbps","isStreaming":false,"title":"Wind Is Blowing風繼續吹","artist":"Sylvain Gagnon feat. Bianca","album":"Blue Moon","uri":"INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac","trackType":"flac"} Dec 16 15:20:07 volumio volumio[1051]: verbose: CURRENT POSITION 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Dec 16 15:20:07 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"550 Kbps","isStreaming":false,"title":"Wind Is Blowing風繼續吹","artist":"Sylvain Gagnon feat. Bianca","album":"Blue Moon","uri":"INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac","trackType":"flac"} Dec 16 15:20:07 volumio volumio[1051]: verbose: CURRENT POSITION 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Dec 16 15:20:07 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: ControllerMpd::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::servicePushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":372,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"550 Kbps","isStreaming":false,"title":"Wind Is Blowing風繼續吹","artist":"Sylvain Gagnon feat. Bianca","album":"Blue Moon","uri":"INTERNAL/中文歌/Sylvain Gagnon feat. Bianca/Blue Moon/06 Wind Is Blowing風繼續吹.flac","trackType":"flac"} Dec 16 15:20:07 volumio volumio[1051]: verbose: CURRENT POSITION 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState stateService play Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::syncState currentStatus play Dec 16 15:20:07 volumio volumio[1051]: info: Received an update from plugin. extracting info from payload Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: CoreStateMachine::pushState Dec 16 15:20:07 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:07 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 333ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 333ms Dec 16 15:20:07 volumio volumio[1051]: info: ------------------------------ 333ms Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Volumio is playing Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - volume level for loudness 80 gain applied 4.84 Dec 16 15:20:07 volumio volumio[1051]: error: FusionDsp - File size not found in array! Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Effects disabled Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:07 volumio volumio[1051]: info: FusionDsp - Clipping Monitor started Dec 16 15:20:12 volumio volumio[1051]: verbose: New Socket.io Connection to 192.168.88.111 from 192.168.88.167 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 8 Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState Dec 16 15:20:12 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState Dec 16 15:20:12 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 15:20:12 volumio volumio[1051]: info: Received Get System Info Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 15:20:12 volumio volumio[1051]: info: Discovery: Getting this device information Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState Dec 16 15:20:12 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState Dec 16 15:20:12 volumio volumio[1051]: info: CorePlayQueue::getTrack 0 Dec 16 15:20:12 volumio volumio[1051]: info: Listing playlists Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::volumioGetQueue Dec 16 15:20:12 volumio volumio[1051]: info: CoreStateMachine::getQueue Dec 16 15:20:12 volumio volumio[1051]: info: CorePlayQueue::getQueue Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 15:20:12 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 16 15:20:13 volumio volumio[1051]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 15:20:17 volumio volumio[1051]: info: CoreCommandRouter::volumioAddQueueItems Dec 16 15:20:17 volumio volumio[1051]: info: CoreStateMachine::addQueueItems Dec 16 15:20:17 volumio volumio[1051]: info: CorePlayQueue::addQueueItems Dec 16 15:20:17 volumio volumio[1051]: info: Preload queue cleared Dec 16 15:20:17 volumio volumio[1051]: info: Adding Item to queue: music-library/INTERNAL/中文歌/周蕙精選 Dec 16 15:20:17 volumio volumio[1051]: info: Exploding uri music-library/INTERNAL/中文歌/周蕙精選 in service mpd Dec 16 15:20:17 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2F%E5%91%A8%E8%95%99%E7%B2%BE%E9%81%B8%2FCDImage.ape&metadata=false Dec 16 15:20:17 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/周蕙精選/CDImage.ape Dec 16 15:20:17 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=/%E7%B2%BE%E9%81%B8/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2F%E5%91%A8%E8%95%99%E7%B2%BE%E9%81%B8%2FCDImage.cue&metadata=false Dec 16 15:20:17 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/周蕙精選/CDImage.cue Dec 16 15:20:17 volumio volumio[1051]: info: ALBUMART /albumart?cacheid=233&web=/%E7%B2%BE%E9%81%B8/extralarge&path=%2Fmnt%2FINTERNAL%2F%E4%B8%AD%E6%96%87%E6%AD%8C%2F%E5%91%A8%E8%95%99%E7%B2%BE%E9%81%B8%2FCDImage.cue&metadata=false Dec 16 15:20:17 volumio volumio[1051]: info: URI /mnt/INTERNAL/中文歌/周蕙精選/CDImage.cue Dec 16 15:20:17 volumio volumio[1051]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:20:17 volumio volumio[1051]: Error: Unable to resolve or reject the same promise twice Dec 16 15:20:17 volumio volumio[1051]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 16 15:20:17 volumio volumio[1051]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 16 15:20:17 volumio volumio[1051]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 16 15:20:17 volumio volumio[1051]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 16 15:20:17 volumio volumio[1051]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 16 15:20:17 volumio volumio[1051]: at Socket.emit (node:events:514:28) Dec 16 15:20:17 volumio volumio[1051]: at addChunk (node:internal/streams/readable:343:12) Dec 16 15:20:17 volumio volumio[1051]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 16 15:20:17 volumio volumio[1051]: at Readable.push (node:internal/streams/readable:253:10) Dec 16 15:20:17 volumio volumio[1051]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 16 15:20:17 volumio volumio[1051]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:20:17 volumio sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-16 15:19' Dec 16 15:20:17 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"