-- Logs begin at Tue 2024-11-19 23:08:22 +07, end at Tue 2024-11-19 23:37:16 +07. -- Nov 19 23:36:00 masteraudio wpa_supplicant[963]: wlan0: Failed to initiate sched scan Nov 19 23:36:06 masteraudio volumio[1273]: info: CALLMETHOD: music_service nanosound_cd upgrade undefined Nov 19 23:36:06 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: nanosound_cd , upgrade Nov 19 23:36:08 masteraudio wpa_supplicant[963]: wlan0: Failed to initiate sched scan Nov 19 23:36:11 masteraudio volumio[1273]: info: CALLMETHOD: music_service nanosound_cd eject undefined Nov 19 23:36:11 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: nanosound_cd , eject Nov 19 23:36:12 masteraudio volumio[1273]: info: NanoSound CD daemon started Nov 19 23:36:16 masteraudio wpa_supplicant[963]: wlan0: Failed to initiate sched scan Nov 19 23:36:22 masteraudio nmbd[879]: [2024/11/19 23:36:22.156018, 0] ../source3/libsmb/nmblib.c:873(send_udp) Nov 19 23:36:22 masteraudio nmbd[879]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Nov 19 23:36:24 masteraudio wpa_supplicant[963]: wlan0: Trying to associate with SSID 'HoangAnh' Nov 19 23:36:26 masteraudio wpa_supplicant[963]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Nov 19 23:36:27 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 19 23:36:27 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 19 23:36:27 masteraudio volumio[1273]: info: Discovery: Getting this device information Nov 19 23:36:27 masteraudio volumio[1273]: info: CoreCommandRouter::volumioGetState Nov 19 23:36:27 masteraudio volumio[1273]: info: CorePlayQueue::getTrack 4 Nov 19 23:36:27 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 19 23:36:27 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 19 23:36:27 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 19 23:36:30 masteraudio wpa_supplicant[963]: wlan0: Trying to associate with SSID 'HoangAnh' Nov 19 23:36:32 masteraudio wpa_supplicant[963]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Nov 19 23:36:33 masteraudio volumio[1273]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Nov 19 23:36:33 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 19 23:36:34 masteraudio volumio[1273]: [SpotifyConnect] Closing metadata listener Nov 19 23:36:34 masteraudio volumio[1273]: info: Disabling plugin spop Nov 19 23:36:34 masteraudio volumio[1273]: info: Done. Nov 19 23:36:34 masteraudio sudo[2672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Nov 19 23:36:34 masteraudio sudo[2672]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 23:36:34 masteraudio systemd[1]: Stopping Volspotconnect2 Daemon... Nov 19 23:36:34 masteraudio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 19 23:36:34 masteraudio sudo[2672]: pam_unix(sudo:session): session closed for user root Nov 19 23:36:34 masteraudio volumio[1273]: [SpotifyConnect] Vollibrespot Daemon service stoped! Nov 19 23:36:36 masteraudio wpa_supplicant[963]: wlan0: Trying to associate with SSID 'HoangAnh' Nov 19 23:36:39 masteraudio wpa_supplicant[963]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Nov 19 23:36:39 masteraudio wpa_supplicant[963]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="HoangAnh" auth_failures=1 duration=10 reason=CONN_FAILED Nov 19 23:36:47 masteraudio wpa_supplicant[963]: wlan0: Trying to associate with SSID 'HoangAnh' Nov 19 23:36:50 masteraudio wpa_supplicant[963]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Nov 19 23:36:50 masteraudio wpa_supplicant[963]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="HoangAnh" auth_failures=10 duration=152 reason=CONN_FAILED Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::stop Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 19 23:36:57 masteraudio volumio[1273]: UNSET VOLATILE Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::stPlaybackTimer Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::updateTrackBlock Nov 19 23:36:57 masteraudio volumio[1273]: info: CorePlayQueue::getTrackBlock Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::pushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CorePlayQueue::getTrack 4 Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::volumioPushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::serviceStop Nov 19 23:36:57 masteraudio volumio[1273]: info: CorePlayQueue::getTrack 4 Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::serviceStop Nov 19 23:36:57 masteraudio volumio[1273]: info: ControllerMpd::stop Nov 19 23:36:57 masteraudio volumio[1273]: verbose: ControllerMpd::sendMpdCommand stop Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 19 23:36:57 masteraudio volumio[1273]: info: Discovery: Getting this device information Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::volumioGetState Nov 19 23:36:57 masteraudio volumio[1273]: info: CorePlayQueue::getTrack 4 Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 19 23:36:57 masteraudio volumio[1273]: info: Nov 19 23:36:57 masteraudio volumio[1273]: ---------------------------- MPD announces state update: player Nov 19 23:36:57 masteraudio volumio[1273]: info: sendMpdCommand stop took 94 milliseconds Nov 19 23:36:57 masteraudio volumio[1273]: info: ControllerMpd::getState Nov 19 23:36:57 masteraudio volumio[1273]: verbose: ControllerMpd::sendMpdCommand status Nov 19 23:36:57 masteraudio volumio[1273]: info: sendMpdCommand status took 15 milliseconds Nov 19 23:36:57 masteraudio volumio[1273]: verbose: ControllerMpd::parseState Nov 19 23:36:57 masteraudio volumio[1273]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 19 23:36:57 masteraudio volumio[1273]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 19 23:36:57 masteraudio volumio[1273]: verbose: ControllerMpd::parseTrackInfo Nov 19 23:36:57 masteraudio volumio[1273]: info: ControllerMpd::pushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::servicePushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::pushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CorePlayQueue::getTrack 4 Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::volumioPushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 19 23:36:57 masteraudio volumio[1273]: info: CorePlayQueue::getTrack 4 Nov 19 23:36:57 masteraudio volumio[1273]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"005 Chuyện Chúng Mình - Bolero Đường Phố Trịnh Nam Phương.mp4","artist":null,"album":null,"uri":"USB/HOANGANH4TB/MUSIC VCD/VIDEO HD 8/005 Chuyện Chúng Mình - Bolero Đường Phố Trịnh Nam Phương.mp4","trackType":"mp4"} Nov 19 23:36:57 masteraudio volumio[1273]: verbose: CURRENT POSITION 4 Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::syncState stateService stop Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::syncState currentStatus stop Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::pushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CorePlayQueue::getTrack 4 Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::volumioPushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 19 23:36:57 masteraudio sudo[2683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop nanosoundcd_progressweb Nov 19 23:36:57 masteraudio volumio[1273]: info: No code Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreStateMachine::pushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CorePlayQueue::getTrack 4 Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::volumioPushState Nov 19 23:36:57 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 19 23:36:57 masteraudio sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 23:36:57 masteraudio volumio[1273]: info: ------------------------------ 39ms Nov 19 23:36:57 masteraudio sudo[2683]: pam_unix(sudo:session): session closed for user root Nov 19 23:36:57 masteraudio sudo[2692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop nanosoundcd_web Nov 19 23:36:57 masteraudio sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 23:36:57 masteraudio sudo[2692]: pam_unix(sudo:session): session closed for user root Nov 19 23:36:57 masteraudio volumio[1273]: error: Error stopping NanoSound CD:Error: Command failed: /usr/bin/sudo /bin/systemctl stop nanosoundcd_web Nov 19 23:36:57 masteraudio volumio[1273]: Failed to stop nanosoundcd_web.service: Unit nanosoundcd_web.service not loaded. Nov 19 23:36:57 masteraudio volumio[1273]: info: Disabling plugin nanosound_cd Nov 19 23:36:57 masteraudio volumio[1273]: info: Done. Nov 19 23:37:02 masteraudio wpa_supplicant[963]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="HoangAnh" Nov 19 23:37:02 masteraudio wpa_supplicant[963]: wlan0: Trying to associate with SSID 'HoangAnh' Nov 19 23:37:03 masteraudio volumio[1273]: info: Enabling plugin nanosound_cd Nov 19 23:37:03 masteraudio volumio[1273]: info: Loading plugin "nanosound_cd"... Nov 19 23:37:03 masteraudio volumio[1273]: info: Applying required configuration parameters for plugin nanosound_cd Nov 19 23:37:03 masteraudio volumio[1273]: info: PLUGIN START: nanosound_cd Nov 19 23:37:03 masteraudio volumio[1273]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 19 23:37:03 masteraudio volumio[1273]: info: [1732034223089] CoreMusicLibrary::Adding element NanoSound CD Nov 19 23:37:03 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 19 23:37:03 masteraudio sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start nanosoundcd_progressweb Nov 19 23:37:03 masteraudio sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 23:37:03 masteraudio sudo[2704]: pam_unix(sudo:session): session closed for user root Nov 19 23:37:03 masteraudio sudo[2713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start nanosoundcd_web Nov 19 23:37:03 masteraudio sudo[2713]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 23:37:03 masteraudio sudo[2713]: pam_unix(sudo:session): session closed for user root Nov 19 23:37:03 masteraudio volumio[1273]: error: Error starting NanoSound CD: Error: Command failed: /usr/bin/sudo /bin/systemctl start nanosoundcd_web Nov 19 23:37:03 masteraudio volumio[1273]: Failed to start nanosoundcd_web.service: Unit nanosoundcd_web.service failed to load: No such file or directory. Nov 19 23:37:03 masteraudio volumio[1273]: info: Done. Nov 19 23:37:05 masteraudio wpa_supplicant[963]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Nov 19 23:37:05 masteraudio wpa_supplicant[963]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="HoangAnh" auth_failures=2 duration=23 reason=CONN_FAILED Nov 19 23:37:11 masteraudio volumio[1273]: info: Enabling plugin spop Nov 19 23:37:11 masteraudio volumio[1273]: info: Loading plugin "spop"... Nov 19 23:37:11 masteraudio volumio[1273]: info: PLUGIN START: spop Nov 19 23:37:11 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 19 23:37:11 masteraudio volumio[1273]: [SpotifyConnect] Creating VLS config file Nov 19 23:37:11 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 23:37:11 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 23:37:11 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 23:37:11 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 23:37:11 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 19 23:37:11 masteraudio volumio[1273]: [SpotifyConnect] Starting metadata listener Nov 19 23:37:11 masteraudio sudo[2722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Nov 19 23:37:11 masteraudio sudo[2722]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 23:37:11 masteraudio sudo[2722]: pam_unix(sudo:session): session closed for user root Nov 19 23:37:11 masteraudio sudo[2726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Nov 19 23:37:11 masteraudio sudo[2726]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 23:37:11 masteraudio systemd[1]: Starting Volspotconnect2 Daemon... Nov 19 23:37:11 masteraudio systemd[1]: Started Volspotconnect2 Daemon. Nov 19 23:37:11 masteraudio sudo[2726]: pam_unix(sudo:session): session closed for user root Nov 19 23:37:11 masteraudio volumio[2736]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Nov 19 23:37:11 masteraudio volumio[2736]: Reading Config from "/tmp/volspotify.toml" Nov 19 23:37:11 masteraudio volumio[2736]: [Vollibrespot] : Using Alsa backend with device: plughw:2 Nov 19 23:37:11 masteraudio volumio[1273]: [SpotifyConnect] Vollibrespot Daemon service started! Nov 19 23:37:11 masteraudio volumio[1273]: [Metrics] SpotifyConnect: 0s 65.56ms Nov 19 23:37:11 masteraudio volumio[1273]: info: Done. Nov 19 23:37:12 masteraudio volumio[2736]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Nov 19 23:37:14 masteraudio volumio[1273]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Nov 19 23:37:14 masteraudio volumio[1273]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 19 23:37:14 masteraudio volumio[1273]: [SpotifyConnect] Closing metadata listener Nov 19 23:37:14 masteraudio volumio[1273]: info: Disabling plugin spop Nov 19 23:37:14 masteraudio volumio[1273]: info: Done. Nov 19 23:37:14 masteraudio sudo[2742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Nov 19 23:37:14 masteraudio sudo[2742]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 19 23:37:14 masteraudio systemd[1]: Stopping Volspotconnect2 Daemon... Nov 19 23:37:14 masteraudio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 19 23:37:14 masteraudio sudo[2742]: pam_unix(sudo:session): session closed for user root Nov 19 23:37:15 masteraudio volumio[1273]: [SpotifyConnect] Vollibrespot Daemon service stoped! Nov 19 23:37:15 masteraudio volumio[1273]: info: Checking Spotify Web API Nov 19 23:37:15 masteraudio volumio[1273]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 19 23:37:15 masteraudio volumio[1273]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Nov 19 23:37:15 masteraudio volumio[1273]: at Socket._healthCheck (dgram.js:638:11) Nov 19 23:37:15 masteraudio volumio[1273]: at Socket.send (dgram.js:435:8) Nov 19 23:37:15 masteraudio volumio[1273]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Nov 19 23:37:16 masteraudio volumio[1273]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2318:23) Nov 19 23:37:16 masteraudio volumio[1273]: at Timeout.setTimeout [as _onTimeout] (/data/plugins/music_service/spop/index.js:2410:18) Nov 19 23:37:16 masteraudio volumio[1273]: at ontimeout (timers.js:482:11) Nov 19 23:37:16 masteraudio volumio[1273]: at tryOnTimeout (timers.js:317:5) Nov 19 23:37:16 masteraudio volumio[1273]: at Timer.listOnTimeout (timers.js:277:5) Nov 19 23:37:16 masteraudio volumio[1273]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 19 23:37:16 masteraudio sudo[2759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-19 23:36 Nov 19 23:37:16 masteraudio sudo[2759]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="14acf028cf46278bd3fd3dd02523424a9bc825bf" VOLUMIO_FE_VERSION="74f64fbba9dc8053dc27ed7125863d6ad6533965" VOLUMIO_BE_VERSION="0a6008ded215bf284d5d4cd9c4df3333cb320899" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Apr 24 17:39:58 CEST 2021" VOLUMIO_VERSION="2.882" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="1a98a01eef5aa5927a5874c2d3c1b0dc"