-- Logs begin at Sat 2022-02-12 08:02:59 UTC, end at Sun 2024-10-27 12:22:41 UTC. -- Oct 27 12:21:19 volumio sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 12:21:19 volumio sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 12:21:19 volumio sudo[1176]: pam_unix(sudo:session): session closed for user root Oct 27 12:21:19 volumio sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 12:21:19 volumio sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 12:21:19 volumio sudo[1178]: pam_unix(sudo:session): session closed for user root Oct 27 12:21:19 volumio volumio[802]: verbose: New Socket.io Connection to 192.168.0.16 from 192.168.0.13 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Total Clients: 3 Oct 27 12:21:19 volumio sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 12:21:19 volumio sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 12:21:19 volumio sudo[1183]: pam_unix(sudo:session): session closed for user root Oct 27 12:21:19 volumio sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 12:21:19 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 12:21:19 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root Oct 27 12:21:19 volumio volumio[802]: verbose: New Socket.io Connection to 192.168.0.16 from 192.168.0.13 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Total Clients: 4 Oct 27 12:21:20 volumio volumio[802]: info: CoreCommandRouter::volumioGetState Oct 27 12:21:20 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Oct 27 12:21:20 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 12:21:20 volumio volumio[802]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 12:21:20 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 12:21:20 volumio volumio[802]: info: Listing playlists Oct 27 12:21:20 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 27 12:21:20 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 27 12:21:20 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 27 12:21:20 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 12:21:22 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 12:21:22 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 27 12:21:26 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 12:21:38 volumio volumio[802]: info: CoreCommandRouter::volumioPlay Oct 27 12:21:38 volumio volumio[802]: verbose: UNSET VOLATILE Oct 27 12:21:38 volumio volumio[802]: info: CoreStateMachine::play index undefined Oct 27 12:21:38 volumio volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 12:21:38 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Oct 27 12:21:38 volumio volumio[802]: info: CoreStateMachine::startPlaybackTimer Oct 27 12:21:38 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Oct 27 12:21:38 volumio volumio[802]: info: [1730031698913] ControllerWebradio::clearAddPlayTrack Oct 27 12:21:38 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 12:21:38 volumio volumio[802]: info: sendMpdCommand stop took 2 milliseconds Oct 27 12:21:38 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 12:21:38 volumio volumio[802]: info: sendMpdCommand clear took 1 milliseconds Oct 27 12:21:38 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" Oct 27 12:21:38 volumio volumio[802]: info: Oct 27 12:21:38 volumio volumio[802]: ---------------------------- MPD announces system playlist update Oct 27 12:21:38 volumio volumio[802]: info: Ignoring MPD Status Update Oct 27 12:21:38 volumio volumio[802]: error: Upnp client error: Error: This socket has been ended by the other party Oct 27 12:21:39 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 12:21:39 volumio volumio[802]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 27 12:21:39 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 27 12:21:39 volumio volumio[802]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 27 12:21:39 volumio volumio[802]: info: Received Get System Version Oct 27 12:21:39 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 12:21:40 volumio volumio[802]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" took 1128 milliseconds Oct 27 12:21:40 volumio volumio[802]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 12:21:40 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand play Oct 27 12:21:40 volumio volumio[802]: info: Oct 27 12:21:40 volumio volumio[802]: ---------------------------- MPD announces system playlist update Oct 27 12:21:40 volumio volumio[802]: info: Ignoring MPD Status Update Oct 27 12:21:40 volumio volumio[802]: info: ------------------------------ 1130ms Oct 27 12:21:40 volumio volumio[802]: info: sendMpdCommand play took 6 milliseconds Oct 27 12:21:40 volumio volumio[802]: info: ------------------------------ 5ms Oct 27 12:21:41 volumio volumio[802]: info: Oct 27 12:21:41 volumio volumio[802]: ---------------------------- MPD announces state update: player Oct 27 12:21:41 volumio volumio[802]: info: ControllerMpd::getState Oct 27 12:21:41 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Oct 27 12:21:41 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Oct 27 12:21:41 volumio volumio[802]: info: Oct 27 12:21:41 volumio volumio[802]: ---------------------------- MPD announces state update: player Oct 27 12:21:41 volumio volumio[802]: info: sendMpdCommand status took 7 milliseconds Oct 27 12:21:41 volumio volumio[802]: info: ControllerMpd::getState Oct 27 12:21:41 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand status Oct 27 12:21:41 volumio volumio[802]: verbose: ControllerMpd::parseState Oct 27 12:21:41 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 12:21:41 volumio volumio[802]: info: sendMpdCommand status took 12 milliseconds Oct 27 12:21:41 volumio volumio[802]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 27 12:21:41 volumio volumio[802]: verbose: ControllerMpd::parseState Oct 27 12:21:41 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 12:21:41 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Oct 27 12:21:41 volumio volumio[802]: info: ControllerMpd::pushState Oct 27 12:21:41 volumio volumio[802]: info: CoreCommandRouter::servicePushState Oct 27 12:21:41 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Oct 27 12:21:41 volumio volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"202 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1730204385&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMwMjA0Mzg1fX19XX0_&Signature=jij91bYQGeePRsT3M9~j2F-95y1ObubG0mt25UtUMpMIefu9MDxGugXZajfRO9Rkyw0qX~-zjFqkz9Kk9VggCim~MUVLsCMnxACOwO3Dk0JBIw~8iNyZGb43Ct7bIDJ-r2CiUje6IxW3Ur1sz3xzgtSywYs47lusUPvTZSwTCP1u4~x3Rr2Wq~1Rt9DW1E-MI41jeM~-HVPU9t1nvlOMIcQ6AXBDrG0hD0ut4h0ZiIdR-qW7gwDMjtcDxKhKFGx7tI6yr3Ky3LOkkWezE3RKxRL3cwWu7Kg54p5c9hU67XUPUj9gJU8QZ1V9XdT6feHoKu0v6mXT~uzLRJG13icUNA__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 27 12:21:41 volumio volumio[802]: verbose: CURRENT POSITION 0 Oct 27 12:21:41 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Oct 27 12:21:41 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus stop Oct 27 12:21:41 volumio volumio[802]: info: CoreStateMachine::pushState Oct 27 12:21:41 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 12:21:41 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Oct 27 12:21:41 volumio volumio[802]: info: ------------------------------ 46ms Oct 27 12:21:41 volumio volumio[802]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 27 12:21:41 volumio volumio[802]: verbose: ControllerMpd::parseTrackInfo Oct 27 12:21:41 volumio volumio[802]: info: ControllerMpd::pushState Oct 27 12:21:41 volumio volumio[802]: info: CoreCommandRouter::servicePushState Oct 27 12:21:41 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Oct 27 12:21:41 volumio volumio[802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"202 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1730204385&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMwMjA0Mzg1fX19XX0_&Signature=jij91bYQGeePRsT3M9~j2F-95y1ObubG0mt25UtUMpMIefu9MDxGugXZajfRO9Rkyw0qX~-zjFqkz9Kk9VggCim~MUVLsCMnxACOwO3Dk0JBIw~8iNyZGb43Ct7bIDJ-r2CiUje6IxW3Ur1sz3xzgtSywYs47lusUPvTZSwTCP1u4~x3Rr2Wq~1Rt9DW1E-MI41jeM~-HVPU9t1nvlOMIcQ6AXBDrG0hD0ut4h0ZiIdR-qW7gwDMjtcDxKhKFGx7tI6yr3Ky3LOkkWezE3RKxRL3cwWu7Kg54p5c9hU67XUPUj9gJU8QZ1V9XdT6feHoKu0v6mXT~uzLRJG13icUNA__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 27 12:21:41 volumio volumio[802]: verbose: CURRENT POSITION 0 Oct 27 12:21:41 volumio volumio[802]: info: CoreStateMachine::syncState stateService play Oct 27 12:21:41 volumio volumio[802]: info: CoreStateMachine::syncState currentStatus play Oct 27 12:21:41 volumio volumio[802]: info: Received an update from plugin. extracting info from payload Oct 27 12:21:41 volumio volumio[802]: info: CoreStateMachine::pushState Oct 27 12:21:41 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 12:21:41 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Oct 27 12:21:41 volumio volumio[802]: info: CoreStateMachine::pushState Oct 27 12:21:41 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Oct 27 12:21:41 volumio volumio[802]: info: ------------------------------ 54ms Oct 27 12:21:57 volumio nmbd[614]: [2024/10/27 12:21:57.129250, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 27 12:21:57 volumio nmbd[614]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.16 for name WORKGROUP<1d>. Oct 27 12:21:57 volumio nmbd[614]: This response was from IP 192.168.0.13, reporting an IP address of 192.168.0.13. Oct 27 12:22:36 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Oct 27 12:22:36 volumio volumio[802]: info: CoreCommandRouter::volumioGetQueue Oct 27 12:22:36 volumio volumio[802]: info: CoreStateMachine::getQueue Oct 27 12:22:36 volumio volumio[802]: info: CorePlayQueue::getQueue Oct 27 12:22:39 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Oct 27 12:22:40 volumio volumio[802]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 27 12:22:40 volumio volumio[802]: info: CoreStateMachine::ClearQueue Oct 27 12:22:40 volumio volumio[802]: info: CoreStateMachine::stop Oct 27 12:22:40 volumio volumio[802]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 12:22:40 volumio volumio[802]: verbose: UNSET VOLATILE Oct 27 12:22:40 volumio volumio[802]: info: CoreStateMachine::stPlaybackTimer Oct 27 12:22:40 volumio volumio[802]: info: CoreStateMachine::updateTrackBlock Oct 27 12:22:40 volumio volumio[802]: info: CorePlayQueue::getTrackBlock Oct 27 12:22:40 volumio volumio[802]: info: CoreStateMachine::pushState Oct 27 12:22:40 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Oct 27 12:22:40 volumio volumio[802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 12:22:40 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Oct 27 12:22:40 volumio volumio[802]: info: CoreStateMachine::serviceStop Oct 27 12:22:40 volumio volumio[802]: info: CorePlayQueue::getTrack 0 Oct 27 12:22:40 volumio volumio[802]: info: CoreCommandRouter::serviceStop Oct 27 12:22:40 volumio volumio[802]: info: [1730031760896] ControllerWebradio::stop Oct 27 12:22:40 volumio volumio[802]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 12:22:40 volumio volumio[802]: info: CorePlayQueue::clearPlayQueue Oct 27 12:22:40 volumio volumio[802]: info: CorePlayQueue::saveQueue Oct 27 12:22:40 volumio volumio[802]: info: CoreCommandRouter::volumioPushState Oct 27 12:22:40 volumio volumio[802]: info: CoreCommandRouter::volumioPushQueue Oct 27 12:22:40 volumio volumio[802]: info: CoreStateMachine::addQueueItems Oct 27 12:22:40 volumio volumio[802]: info: CorePlayQueue::addQueueItems Oct 27 12:22:40 volumio volumio[802]: info: Adding Item to queue: webmbc/1 Oct 27 12:22:40 volumio volumio[802]: info: Exploding uri webmbc/1 in service personal_radio Oct 27 12:22:40 volumio volumio[802]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 12:22:40 volumio volumio[802]: ReferenceError: self is not defined Oct 27 12:22:40 volumio volumio[802]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Oct 27 12:22:40 volumio volumio[802]: at /data/plugins/music_service/personal_radio/index.js:487:12 Oct 27 12:22:40 volumio volumio[802]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Oct 27 12:22:40 volumio volumio[802]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Oct 27 12:22:40 volumio volumio[802]: at Request.emit (events.js:315:20) Oct 27 12:22:40 volumio volumio[802]: at Request.init (/data/plugins/music_service/personal_radio/node_modules/request/request.js:275:17) Oct 27 12:22:40 volumio volumio[802]: at new Request (/data/plugins/music_service/personal_radio/node_modules/request/request.js:129:8) Oct 27 12:22:40 volumio volumio[802]: at Function.request (/data/plugins/music_service/personal_radio/node_modules/request/index.js:55:10) Oct 27 12:22:40 volumio volumio[802]: at Object.end (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:595:27) Oct 27 12:22:40 volumio volumio[802]: at ControllerPersonalRadio.getStreamUrl (/data/plugins/music_service/personal_radio/index.js:482:4) Oct 27 12:22:40 volumio volumio[802]: at ControllerPersonalRadio.explodeUri (/data/plugins/music_service/personal_radio/index.js:420:12) Oct 27 12:22:40 volumio volumio[802]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1324:16) Oct 27 12:22:40 volumio volumio[802]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:144:48) Oct 27 12:22:40 volumio volumio[802]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:243:25) Oct 27 12:22:40 volumio volumio[802]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:487:23) Oct 27 12:22:40 volumio volumio[802]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:457:17) Oct 27 12:22:40 volumio volumio[802]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 12:22:41 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-27 12:21 Oct 27 12:22:41 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"