-- Logs begin at Wed 2024-10-30 10:18:52 JST, end at Wed 2024-10-30 14:13:33 JST. -- Oct 30 14:12:00 volumio-jkbx node[17986]: *************************************************************************** Oct 30 14:12:00 volumio-jkbx node[17986]: _ __ __ _ ____ ___ ____ ________ Oct 30 14:12:00 volumio-jkbx node[17986]: | | / /___ / /_ ______ ___ (_)___ / __ \/ | / __ \/ _/ __ \ Oct 30 14:12:00 volumio-jkbx node[17986]: | | / / __ \/ / / / / __ `__ \/ / __ \ / /_/ / /| | / / / // // / / / Oct 30 14:12:00 volumio-jkbx node[17986]: | |/ / /_/ / / /_/ / / / / / / / /_/ / / _, _/ ___ |/ /_/ // // /_/ / Oct 30 14:12:00 volumio-jkbx node[17986]: |___/\____/_/\__,_/_/ /_/ /_/_/\____/ /_/ |_/_/ |_/_____/___/\____/ Oct 30 14:12:00 volumio-jkbx node[17986]: *************************************************************************** Oct 30 14:12:00 volumio-jkbx node[17986]: [2024-10-30T14:12:00.859] [DEBUG] default - Radiko constructor: 1 Oct 30 14:12:04 volumio-jkbx volumio[849]: info: No valid Plugin REST Endpoint Oct 30 14:12:05 volumio-jkbx volumio[849]: info: Preload queue cleared Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::ClearQueue Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::stop Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CorePlayQueue::clearPlayQueue Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CorePlayQueue::saveQueue Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPushQueue Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::addQueueItems Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CorePlayQueue::addQueueItems Oct 30 14:12:05 volumio-jkbx volumio[849]: info: Preload queue cleared Oct 30 14:12:05 volumio-jkbx volumio[849]: info: Adding Item to queue: upnp/folder/http://192.168.24.54:8200/ctl/ContentDir@1$14$0$85 Oct 30 14:12:05 volumio-jkbx volumio[849]: info: Exploding uri upnp/folder/http://192.168.24.54:8200/ctl/ContentDir@1$14$0$85 in service upnp_browser Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPushQueue Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CorePlayQueue::saveQueue Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::updateTrackBlock Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CorePlayQueue::getTrackBlock Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPlay Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::play index 0 Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::stop Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::play index undefined Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CorePlayQueue::getTrack 0 Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CoreStateMachine::startPlaybackTimer Oct 30 14:12:05 volumio-jkbx volumio[849]: info: CorePlayQueue::getTrack 0 Oct 30 14:12:05 volumio-jkbx volumio[849]: info: [1730265125905] ControllerUPNPBrowser::clearAddPlayTrack Oct 30 14:12:05 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 14:12:05 volumio-jkbx volumio[849]: info: sendMpdCommand stop took 53 milliseconds Oct 30 14:12:05 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Oct 30 14:12:06 volumio-jkbx volumio[849]: ---------------------------- MPD announces system playlist update Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Ignoring MPD Status Update Oct 30 14:12:06 volumio-jkbx volumio[849]: info: sendMpdCommand clear took 62 milliseconds Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.24.54:8200/MediaItems/39094.mp3" Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Oct 30 14:12:06 volumio-jkbx volumio[849]: ---------------------------- MPD announces system playlist update Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Ignoring MPD Status Update Oct 30 14:12:06 volumio-jkbx volumio[849]: error: updateQueue error: null Oct 30 14:12:06 volumio-jkbx volumio[849]: info: ------------------------------ 89ms Oct 30 14:12:06 volumio-jkbx volumio[849]: error: updateQueue error: null Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.24.54:8200/MediaItems/39094.mp3" Oct 30 14:12:06 volumio-jkbx volumio[849]: info: ------------------------------ 185ms Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Oct 30 14:12:06 volumio-jkbx volumio[849]: ---------------------------- MPD announces system playlist update Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Ignoring MPD Status Update Oct 30 14:12:06 volumio-jkbx volumio[849]: info: sendMpdCommand add "http://192.168.24.54:8200/MediaItems/39094.mp3" took 26 milliseconds Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand play Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Oct 30 14:12:06 volumio-jkbx volumio[849]: ---------------------------- MPD announces system playlist update Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Ignoring MPD Status Update Oct 30 14:12:06 volumio-jkbx kernel: usb 1-1: 1:1: cannot get freq at ep 0x2 Oct 30 14:12:06 volumio-jkbx volumio[849]: info: ------------------------------ 163ms Oct 30 14:12:06 volumio-jkbx volumio[849]: info: sendMpdCommand play took 152 milliseconds Oct 30 14:12:06 volumio-jkbx volumio[849]: info: ------------------------------ 136ms Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Oct 30 14:12:06 volumio-jkbx volumio[849]: ---------------------------- MPD announces state update: player Oct 30 14:12:06 volumio-jkbx volumio[849]: info: ControllerMpd::getState Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Oct 30 14:12:06 volumio-jkbx volumio[849]: ---------------------------- MPD announces state update: player Oct 30 14:12:06 volumio-jkbx volumio[849]: info: ControllerMpd::getState Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:12:06 volumio-jkbx volumio[849]: info: sendMpdCommand status took 183 milliseconds Oct 30 14:12:06 volumio-jkbx volumio[849]: info: sendMpdCommand status took 155 milliseconds Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::parseState Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::parseState Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:12:06 volumio-jkbx volumio[849]: info: sendMpdCommand playlistinfo took 63 milliseconds Oct 30 14:12:06 volumio-jkbx volumio[849]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:12:06 volumio-jkbx volumio[849]: info: ControllerMpd::pushState Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreCommandRouter::servicePushState Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CorePlayQueue::getTrack 0 Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":496,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"39094.mp3","artist":null,"album":null,"uri":"http://192.168.24.54:8200/MediaItems/39094.mp3","trackType":"mp3"} Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: CURRENT POSITION 0 Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreStateMachine::syncState stateService play Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreStateMachine::syncState currentStatus stop Oct 30 14:12:06 volumio-jkbx volumio[849]: info: ControllerMpd::pushState Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreCommandRouter::servicePushState Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CorePlayQueue::getTrack 0 Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":496,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"39094.mp3","artist":null,"album":null,"uri":"http://192.168.24.54:8200/MediaItems/39094.mp3","trackType":"mp3"} Oct 30 14:12:06 volumio-jkbx volumio[849]: verbose: CURRENT POSITION 0 Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreStateMachine::syncState stateService play Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:12:06 volumio-jkbx volumio[849]: info: Received an update from plugin. extracting info from payload Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreStateMachine::pushState Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:12:06 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPushState Oct 30 14:12:07 volumio-jkbx volumio[849]: info: CoreStateMachine::pushState Oct 30 14:12:07 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPushState Oct 30 14:12:07 volumio-jkbx volumio[849]: info: ------------------------------ 596ms Oct 30 14:12:07 volumio-jkbx volumio[849]: info: ------------------------------ 660ms Oct 30 14:12:07 volumio-jkbx volumio[849]: info: Oct 30 14:12:07 volumio-jkbx volumio[849]: ---------------------------- MPD announces system playlist update Oct 30 14:12:07 volumio-jkbx volumio[849]: info: Ignoring MPD Status Update Oct 30 14:12:07 volumio-jkbx volumio[849]: info: Oct 30 14:12:07 volumio-jkbx volumio[849]: ---------------------------- MPD announces state update: player Oct 30 14:12:07 volumio-jkbx volumio[849]: info: ControllerMpd::getState Oct 30 14:12:07 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:12:07 volumio-jkbx volumio[849]: info: Oct 30 14:12:07 volumio-jkbx volumio[849]: ---------------------------- MPD announces system playlist update Oct 30 14:12:07 volumio-jkbx volumio[849]: info: Ignoring MPD Status Update Oct 30 14:12:07 volumio-jkbx volumio[849]: info: Oct 30 14:12:07 volumio-jkbx volumio[849]: ---------------------------- MPD announces state update: player Oct 30 14:12:07 volumio-jkbx volumio[849]: info: ControllerMpd::getState Oct 30 14:12:07 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:12:08 volumio-jkbx volumio[849]: info: ------------------------------ 338ms Oct 30 14:12:08 volumio-jkbx volumio[849]: info: sendMpdCommand status took 306 milliseconds Oct 30 14:12:08 volumio-jkbx volumio[849]: info: ------------------------------ 288ms Oct 30 14:12:08 volumio-jkbx volumio[849]: info: sendMpdCommand status took 242 milliseconds Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: ControllerMpd::parseState Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: ControllerMpd::parseState Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:12:08 volumio-jkbx volumio[849]: info: sendMpdCommand playlistinfo took 295 milliseconds Oct 30 14:12:08 volumio-jkbx volumio[849]: info: sendMpdCommand playlistinfo took 269 milliseconds Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:12:08 volumio-jkbx volumio[849]: info: ControllerMpd::pushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreCommandRouter::servicePushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CorePlayQueue::getTrack 0 Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":496,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Boogie Wonderland (12\" Version)","artist":"Earth, Wind & Fire with The Emotions","album":"The Essential Emotions - The Columbia Years","uri":"http://192.168.24.54:8200/MediaItems/39094.mp3","trackType":"mp3"} Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: CURRENT POSITION 0 Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreStateMachine::syncState stateService play Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:12:08 volumio-jkbx volumio[849]: info: Received an update from plugin. extracting info from payload Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreStateMachine::pushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreStateMachine::pushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: ControllerMpd::pushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreCommandRouter::servicePushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CorePlayQueue::getTrack 0 Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":496,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Boogie Wonderland (12\" Version)","artist":"Earth, Wind & Fire with The Emotions","album":"The Essential Emotions - The Columbia Years","uri":"http://192.168.24.54:8200/MediaItems/39094.mp3","trackType":"mp3"} Oct 30 14:12:08 volumio-jkbx volumio[849]: verbose: CURRENT POSITION 0 Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreStateMachine::syncState stateService play Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:12:08 volumio-jkbx volumio[849]: info: Received an update from plugin. extracting info from payload Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreStateMachine::pushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreStateMachine::pushState Oct 30 14:12:08 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPushState Oct 30 14:12:09 volumio-jkbx volumio[849]: info: ------------------------------ 1302ms Oct 30 14:12:09 volumio-jkbx volumio[849]: info: ------------------------------ 1232ms Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CoreStateMachine::getQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CorePlayQueue::getQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CoreStateMachine::getQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CorePlayQueue::getQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CoreStateMachine::getQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CorePlayQueue::getQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CoreStateMachine::getQueue Oct 30 14:12:09 volumio-jkbx volumio[849]: info: CorePlayQueue::getQueue Oct 30 14:12:10 volumio-jkbx volumio[849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%BE%E5%B2%A1%E7%9B%B4%E4%B9%9F/6ffd3c97-f6c6-4c5c-afed-54723c7e635b.jpg' Oct 30 14:12:14 volumio-jkbx volumio[849]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%B9%E5%86%85%E3%81%BE%E3%82%8A%E3%82%84/6b25b075-5e56-4c2f-9e39-a0c9cb2b7357.jpg' Oct 30 14:12:14 volumio-jkbx systemd[1]: radio.service: Main process exited, code=killed, status=7/BUS Oct 30 14:12:14 volumio-jkbx systemd[1]: radio.service: Failed with result 'signal'. Oct 30 14:12:15 volumio-jkbx systemd[1]: radio.service: Service RestartSec=100ms expired, scheduling restart. Oct 30 14:12:15 volumio-jkbx systemd[1]: radio.service: Scheduled restart job, restart counter is at 612. Oct 30 14:12:15 volumio-jkbx systemd[1]: Stopped radio. Oct 30 14:12:15 volumio-jkbx systemd[1]: radio.service: Found left-over process 17997 (sh) in control group while starting unit. Ignoring. Oct 30 14:12:15 volumio-jkbx systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 30 14:12:15 volumio-jkbx systemd[1]: radio.service: Found left-over process 17998 (ffmpeg) in control group while starting unit. Ignoring. Oct 30 14:12:15 volumio-jkbx systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 30 14:12:15 volumio-jkbx systemd[1]: Started radio. Oct 30 14:12:22 volumio-jkbx volumio[849]: info: VolumeController::SetAlsaVolume+ Oct 30 14:12:22 volumio-jkbx volumio[849]: info: CoreStateMachine::pushState Oct 30 14:12:22 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:12:22 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioPushState Oct 30 14:12:23 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:12:23 volumio-jkbx volumio[849]: info: CoreStateMachine::getQueue Oct 30 14:12:23 volumio-jkbx volumio[849]: info: CorePlayQueue::getQueue Oct 30 14:12:28 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 14:12:28 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 14:12:28 volumio-jkbx volumio[849]: info: Discovery: Getting this device information Oct 30 14:12:28 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:28 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 14:12:28 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 14:12:28 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 14:12:28 volumio-jkbx volumio[849]: info: Discovery: Getting this device information Oct 30 14:12:28 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:28 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 14:12:29 volumio-jkbx volumio[849]: verbose: New Socket.io Connection to 192.168.24.88:3000 from 192.168.24.163 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 30 14:12:36 volumio-jkbx node[18033]: *************************************************************************** Oct 30 14:12:36 volumio-jkbx node[18033]: _ __ __ _ ____ ___ ____ ________ Oct 30 14:12:36 volumio-jkbx node[18033]: | | / /___ / /_ ______ ___ (_)___ / __ \/ | / __ \/ _/ __ \ Oct 30 14:12:36 volumio-jkbx node[18033]: | | / / __ \/ / / / / __ `__ \/ / __ \ / /_/ / /| | / / / // // / / / Oct 30 14:12:36 volumio-jkbx node[18033]: | |/ / /_/ / / /_/ / / / / / / / /_/ / / _, _/ ___ |/ /_/ // // /_/ / Oct 30 14:12:36 volumio-jkbx node[18033]: |___/\____/_/\__,_/_/ /_/ /_/_/\____/ /_/ |_/_/ |_/_____/___/\____/ Oct 30 14:12:36 volumio-jkbx node[18033]: *************************************************************************** Oct 30 14:12:36 volumio-jkbx node[18033]: [2024-10-30T14:12:36.597] [DEBUG] default - Radiko constructor: 1 Oct 30 14:12:42 volumio-jkbx sudo[18055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 14:12:42 volumio-jkbx sudo[18055]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 14:12:42 volumio-jkbx sudo[18055]: pam_unix(sudo:session): session closed for user root Oct 30 14:12:42 volumio-jkbx sudo[18057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 14:12:42 volumio-jkbx sudo[18057]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 14:12:43 volumio-jkbx sudo[18057]: pam_unix(sudo:session): session closed for user root Oct 30 14:12:43 volumio-jkbx volumio[849]: verbose: New Socket.io Connection to 192.168.24.88 from 192.168.24.163 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 14:12:43 volumio-jkbx volumio[849]: info: Received Get System Info Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 14:12:43 volumio-jkbx volumio[849]: info: Discovery: Getting this device information Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:43 volumio-jkbx volumio[849]: info: Listing playlists Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 14:12:43 volumio-jkbx volumio[849]: info: Received Get System Info Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 14:12:43 volumio-jkbx volumio[849]: info: Discovery: Getting this device information Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:43 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 14:12:44 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 30 14:12:44 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 30 14:12:45 volumio-jkbx sudo[18063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 14:12:45 volumio-jkbx sudo[18063]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 14:12:45 volumio-jkbx sudo[18065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 14:12:45 volumio-jkbx sudo[18065]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 14:12:45 volumio-jkbx sudo[18063]: pam_unix(sudo:session): session closed for user root Oct 30 14:12:45 volumio-jkbx sudo[18065]: pam_unix(sudo:session): session closed for user root Oct 30 14:12:45 volumio-jkbx volumio[849]: verbose: New Socket.io Connection to 192.168.24.88 from 192.168.24.163 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 14:12:46 volumio-jkbx volumio[849]: info: Received Get System Info Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 14:12:46 volumio-jkbx volumio[849]: info: Discovery: Getting this device information Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:46 volumio-jkbx volumio[849]: info: Listing playlists Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 14:12:46 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 30 14:12:47 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 14:12:47 volumio-jkbx volumio[849]: info: Received Get System Info Oct 30 14:12:47 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 14:12:47 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 14:12:47 volumio-jkbx volumio[849]: info: Discovery: Getting this device information Oct 30 14:12:47 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:47 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 14:12:47 volumio-jkbx systemd[1]: radio.service: Main process exited, code=killed, status=7/BUS Oct 30 14:12:47 volumio-jkbx systemd[1]: radio.service: Failed with result 'signal'. Oct 30 14:12:47 volumio-jkbx systemd[1]: radio.service: Service RestartSec=100ms expired, scheduling restart. Oct 30 14:12:47 volumio-jkbx systemd[1]: radio.service: Scheduled restart job, restart counter is at 613. Oct 30 14:12:47 volumio-jkbx systemd[1]: Stopped radio. Oct 30 14:12:47 volumio-jkbx systemd[1]: radio.service: Found left-over process 18052 (sh) in control group while starting unit. Ignoring. Oct 30 14:12:47 volumio-jkbx systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 30 14:12:47 volumio-jkbx systemd[1]: radio.service: Found left-over process 18053 (ffmpeg) in control group while starting unit. Ignoring. Oct 30 14:12:47 volumio-jkbx systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 30 14:12:47 volumio-jkbx systemd[1]: Started radio. Oct 30 14:12:48 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 30 14:12:50 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 14:12:50 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 14:12:50 volumio-jkbx volumio[849]: info: Discovery: Getting this device information Oct 30 14:12:50 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:12:50 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 14:12:53 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 30 14:12:53 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:12:53 volumio-jkbx volumio[849]: info: CoreStateMachine::getQueue Oct 30 14:12:53 volumio-jkbx volumio[849]: info: CorePlayQueue::getQueue Oct 30 14:12:54 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 30 14:12:55 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 30 14:13:03 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 14:13:03 volumio-jkbx volumio[849]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 30 14:13:03 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 30 14:13:03 volumio-jkbx volumio[849]: info: Received Get System Version Oct 30 14:13:03 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 14:13:03 volumio-jkbx volumio[849]: info: Received Get System Info Oct 30 14:13:03 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 14:13:03 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 14:13:03 volumio-jkbx volumio[849]: info: Discovery: Getting this device information Oct 30 14:13:03 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:13:03 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 14:13:05 volumio-jkbx node[18070]: *************************************************************************** Oct 30 14:13:05 volumio-jkbx node[18070]: _ __ __ _ ____ ___ ____ ________ Oct 30 14:13:05 volumio-jkbx node[18070]: | | / /___ / /_ ______ ___ (_)___ / __ \/ | / __ \/ _/ __ \ Oct 30 14:13:05 volumio-jkbx node[18070]: | | / / __ \/ / / / / __ `__ \/ / __ \ / /_/ / /| | / / / // // / / / Oct 30 14:13:05 volumio-jkbx node[18070]: | |/ / /_/ / / /_/ / / / / / / / /_/ / / _, _/ ___ |/ /_/ // // /_/ / Oct 30 14:13:05 volumio-jkbx node[18070]: |___/\____/_/\__,_/_/ /_/ /_/_/\____/ /_/ |_/_/ |_/_____/___/\____/ Oct 30 14:13:05 volumio-jkbx node[18070]: *************************************************************************** Oct 30 14:13:05 volumio-jkbx node[18070]: [2024-10-30T14:13:05.927] [DEBUG] default - Radiko constructor: 1 Oct 30 14:13:09 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 14:13:09 volumio-jkbx volumio[849]: info: Preload queue cleared Oct 30 14:13:10 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 14:13:10 volumio-jkbx volumio[849]: info: Preload queue cleared Oct 30 14:13:12 volumio-jkbx systemd[1]: radio.service: Main process exited, code=killed, status=7/BUS Oct 30 14:13:12 volumio-jkbx systemd[1]: radio.service: Failed with result 'signal'. Oct 30 14:13:12 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 14:13:12 volumio-jkbx systemd[1]: radio.service: Service RestartSec=100ms expired, scheduling restart. Oct 30 14:13:12 volumio-jkbx systemd[1]: radio.service: Scheduled restart job, restart counter is at 614. Oct 30 14:13:12 volumio-jkbx systemd[1]: Stopped radio. Oct 30 14:13:12 volumio-jkbx systemd[1]: radio.service: Found left-over process 18082 (sh) in control group while starting unit. Ignoring. Oct 30 14:13:12 volumio-jkbx systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 30 14:13:12 volumio-jkbx systemd[1]: radio.service: Found left-over process 18083 (ffmpeg) in control group while starting unit. Ignoring. Oct 30 14:13:12 volumio-jkbx systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Oct 30 14:13:12 volumio-jkbx systemd[1]: Started radio. Oct 30 14:13:12 volumio-jkbx volumio[849]: info: Preload queue cleared Oct 30 14:13:13 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 14:13:15 volumio-jkbx volumio[849]: info: Preload queue cleared Oct 30 14:13:20 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 14:13:20 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 14:13:20 volumio-jkbx volumio[849]: info: Discovery: Getting this device information Oct 30 14:13:20 volumio-jkbx volumio[849]: info: CoreCommandRouter::volumioGetState Oct 30 14:13:20 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 14:13:22 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 14:13:23 volumio-jkbx volumio[849]: info: Preload queue cleared Oct 30 14:13:23 volumio-jkbx volumio[849]: info: Preloading song: upnp/http://192.168.24.54:8200/ctl/ContentDir@64$0$85$1 Oct 30 14:13:23 volumio-jkbx volumio[849]: info: Exploding uri upnp/http://192.168.24.54:8200/ctl/ContentDir@64$0$85$1 in service upnp_browser Oct 30 14:13:25 volumio-jkbx volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 14:13:26 volumio-jkbx volumio[849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 14:13:26 volumio-jkbx volumio[849]: TypeError: Cannot read property 'length' of undefined Oct 30 14:13:26 volumio-jkbx volumio[849]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 30 14:13:26 volumio-jkbx volumio[849]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 30 14:13:26 volumio-jkbx volumio[849]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 30 14:13:26 volumio-jkbx volumio[849]: at Parser.emit (events.js:315:20) Oct 30 14:13:26 volumio-jkbx volumio[849]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 30 14:13:26 volumio-jkbx volumio[849]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 30 14:13:26 volumio-jkbx volumio[849]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 30 14:13:26 volumio-jkbx volumio[849]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 30 14:13:26 volumio-jkbx volumio[849]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 30 14:13:26 volumio-jkbx volumio[849]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 30 14:13:26 volumio-jkbx volumio[849]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 30 14:13:26 volumio-jkbx volumio[849]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 30 14:13:26 volumio-jkbx volumio[849]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 30 14:13:26 volumio-jkbx volumio[849]: at IncomingMessage.emit (events.js:327:22) Oct 30 14:13:26 volumio-jkbx volumio[849]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 30 14:13:26 volumio-jkbx volumio[849]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 30 14:13:26 volumio-jkbx volumio[849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 14:13:33 volumio-jkbx sudo[18143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-30 14:12 Oct 30 14:13:33 volumio-jkbx sudo[18143]: 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"