-- Logs begin at Fri 2021-10-01 13:11:44 UTC, end at Tue 2021-10-12 00:02:27 UTC. -- Oct 12 00:01:27 musico bluetoothd[857]: GAP and GATT are mandatory Oct 12 00:01:27 musico bluetoothd[857]: gap-gatt-profile profile probe failed for C4:7D:9F:B8:B4:5E Oct 12 00:01:29 musico kernel: input: C4:7D:9F:B8:B4:5E as /devices/virtual/input/input0 Oct 12 00:01:30 musico volumio[973]: info: VolumeController::SetAlsaVolume60 Oct 12 00:01:30 musico volumio[973]: BT PLUGIN MESSAGE: Volume set to 60 Oct 12 00:01:30 musico volumio[973]: info: CoreStateMachine::pushState Oct 12 00:01:30 musico volumio[973]: info: CoreCommandRouter::volumioPushState Oct 12 00:01:30 musico volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 00:01:30 musico volumio[973]: BT PLUGIN MESSAGE: Duration is 0 Oct 12 00:01:30 musico volumio[973]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 12 00:01:30 musico volumio[973]: xcb_connection_has_error() returned true Oct 12 00:02:09 musico volumio[973]: BT PLUGIN MESSAGE: Duration is 4294967295 Oct 12 00:02:10 musico volumio[973]: BT PLUGIN MESSAGE: Duration is 98000 Oct 12 00:02:10 musico volumio[973]: BT PLUGIN MESSAGE: Duration is 98000 Oct 12 00:02:11 musico volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: paused Oct 12 00:02:11 musico volumio[973]: BT PLUGIN MESSAGE: Playing: false Oct 12 00:02:11 musico volumio[973]: BT PLUGIN MESSAGE: VOLATILE: airplay_emulation Oct 12 00:02:11 musico volumio[973]: BT PLUGIN MESSAGE: STATE:undefined Oct 12 00:02:14 musico volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: playing Oct 12 00:02:14 musico volumio[973]: BT PLUGIN MESSAGE: Playing: true Oct 12 00:02:14 musico volumio[973]: BT PLUGIN MESSAGE: VOLATILE: airplay_emulation Oct 12 00:02:14 musico volumio[973]: BT PLUGIN MESSAGE: STATE:undefined Oct 12 00:02:14 musico volumio[973]: info: CoreCommandRouter::volumioStop Oct 12 00:02:14 musico volumio[973]: info: CoreStateMachine::stop Oct 12 00:02:14 musico volumio[973]: info: CoreStateMachine::serviceStop Oct 12 00:02:14 musico volumio[973]: info: CoreCommandRouter::serviceStop Oct 12 00:02:14 musico volumio[973]: info: Airplay Stop Oct 12 00:02:14 musico volumio[973]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 12 00:02:14 musico sudo[7450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1462 Oct 12 00:02:14 musico sudo[7450]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 00:02:14 musico sudo[7450]: pam_unix(sudo:session): session closed for user root Oct 12 00:02:14 musico systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Oct 12 00:02:14 musico systemd[1]: Unit shairport-sync.service entered failed state. Oct 12 00:02:14 musico shairport-sync[1462]: {"time":1633982837125,"response":"airplayInactive Success"} Oct 12 00:02:14 musico volumio[973]: info: Shairport-Sync paused with USR2 Oct 12 00:02:14 musico volumio[973]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Oct 12 00:02:14 musico volumio[973]: info: Enabling Bluetooth Audio Output Oct 12 00:02:14 musico kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Oct 12 00:02:14 musico kernel: arizona spi0.1: ASRC underclocked Oct 12 00:02:14 musico volumio[973]: info: Bluetooth Audio successfully Enabled Oct 12 00:02:15 musico volumio[973]: info: Restarting Shairport-Sync after stop Oct 12 00:02:15 musico sudo[7471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 00:02:15 musico sudo[7471]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 00:02:15 musico systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 12 00:02:15 musico systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 12 00:02:15 musico sudo[7471]: pam_unix(sudo:session): session closed for user root Oct 12 00:02:15 musico volumio[973]: info: Shairport-Sync retarted Oct 12 00:02:25 musico volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: paused Oct 12 00:02:25 musico volumio[973]: BT PLUGIN MESSAGE: Playing: false Oct 12 00:02:25 musico volumio[973]: BT PLUGIN MESSAGE: VOLATILE: bluetooth Oct 12 00:02:25 musico volumio[973]: BT PLUGIN MESSAGE: STATE:undefined Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::servicePushState Oct 12 00:02:25 musico volumio[973]: info: CoreStateMachine::pushState Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::volumioPushState Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 00:02:25 musico volumio[973]: verbose: UNSET VOLATILE Oct 12 00:02:25 musico volumio[973]: info: Bluetooth Detach Command Oct 12 00:02:25 musico volumio[973]: info: CoreStateMachine::resetVolumioState Oct 12 00:02:25 musico volumio[973]: info: CoreStateMachine::getcurrentVolume Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::volumioStop Oct 12 00:02:25 musico volumio[973]: info: CoreStateMachine::stop Oct 12 00:02:25 musico volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 00:02:25 musico volumio[973]: verbose: UNSET VOLATILE Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::servicePushState Oct 12 00:02:25 musico volumio[973]: info: CorePlayQueue::getTrack 0 Oct 12 00:02:25 musico volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received bluetooth Oct 12 00:02:25 musico volumio[973]: info: CoreStateMachine::pushState Oct 12 00:02:25 musico volumio[973]: info: CorePlayQueue::getTrack 0 Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::volumioPushState Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 00:02:25 musico volumio[973]: info: CoreStateMachine::updateTrackBlock Oct 12 00:02:25 musico volumio[973]: info: CorePlayQueue::getTrackBlock Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 00:02:25 musico volumio[973]: info: Bluetooth Audio successfully Disabled Oct 12 00:02:25 musico volumio[973]: info: Bluetooth Successfully Detached Oct 12 00:02:25 musico volumio[973]: info: CoreStateMachine::pushState Oct 12 00:02:25 musico volumio[973]: info: CorePlayQueue::getTrack 0 Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::volumioPushState Oct 12 00:02:25 musico volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 00:02:26 musico volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 00:02:26 musico volumio[973]: { Error: certificate is not yet valid Oct 12 00:02:26 musico volumio[973]: at TLSSocket. (_tls_wrap.js:1105:38) Oct 12 00:02:26 musico volumio[973]: at emitNone (events.js:106:13) Oct 12 00:02:26 musico volumio[973]: at TLSSocket.emit (events.js:208:7) Oct 12 00:02:26 musico volumio[973]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Oct 12 00:02:26 musico volumio[973]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Oct 12 00:02:26 musico volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 00:02:27 musico sudo[7537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-10-12 00:01 Oct 12 00:02:27 musico sudo[7537]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="vivaldi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 15:11:44 CEST 2021" VOLUMIO_VERSION="1.081" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Vivaldi" VOLUMIO_MODEL="Musico" VOLUMIO_VENDOR_MODEL="Vivaldi Musico" VOLUMIO_HASH="2294d51e0d5fb0a1d09a7619c5ec8086"