-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Tue 2026-03-24 16:13:34 JST. --
Mar 24 16:12:30 volumioes9023w systemd-timedated[1099]: Changed local time to Tue Mar 24 16:12:30 2026
Mar 24 16:12:30 volumioes9023w sudo[1083]: pam_unix(sudo:session): session closed for user root
Mar 24 16:12:30 volumioes9023w volumio-time-update[565]: volumio-time-update-util: System time updated successfully.
Mar 24 16:12:30 volumioes9023w systemd[1]: Starting Daily man-db regeneration...
Mar 24 16:12:30 volumioes9023w systemd[1]: Starting Daily apt download activities...
Mar 24 16:12:30 volumioes9023w systemd[1]: Started Volumio Time Update Utility.
Mar 24 16:12:30 volumioes9023w systemd[1]: Reached target Multi-User System.
Mar 24 16:12:30 volumioes9023w systemd[1]: Reached target Graphical Interface.
Mar 24 16:12:30 volumioes9023w systemd[1]: Starting Update UTMP about System Runlevel Changes...
Mar 24 16:12:30 volumioes9023w systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Mar 24 16:12:30 volumioes9023w systemd[1]: Started Update UTMP about System Runlevel Changes.
Mar 24 16:12:34 volumioes9023w volumio-remote-updater[575]: [2026-03-24 16:12:34] [connect] Successful connection
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.540647, 0] ../source3/libsmb/nmblib.c:917(send_udp)
Mar 24 16:12:39 volumioes9023w nmbd[678]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.540928, 0] ../source3/libsmb/nmblib.c:917(send_udp)
Mar 24 16:12:39 volumioes9023w nmbd[678]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.541038, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
Mar 24 16:12:39 volumioes9023w nmbd[678]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.541133, 0] ../source3/libsmb/nmblib.c:917(send_udp)
Mar 24 16:12:39 volumioes9023w nmbd[678]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.541223, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
Mar 24 16:12:39 volumioes9023w nmbd[678]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.541303, 0] ../source3/libsmb/nmblib.c:917(send_udp)
Mar 24 16:12:39 volumioes9023w nmbd[678]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.541376, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
Mar 24 16:12:39 volumioes9023w nmbd[678]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.541460, 0] ../source3/libsmb/nmblib.c:917(send_udp)
Mar 24 16:12:39 volumioes9023w nmbd[678]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.541533, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
Mar 24 16:12:39 volumioes9023w nmbd[678]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.541620, 0] ../source3/libsmb/nmblib.c:917(send_udp)
Mar 24 16:12:39 volumioes9023w nmbd[678]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable
Mar 24 16:12:39 volumioes9023w nmbd[678]: [2026/03/24 16:12:39.541688, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet)
Mar 24 16:12:39 volumioes9023w nmbd[678]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed
Mar 24 16:12:49 volumioes9023w volumio-remote-updater[575]: [2026-03-24 16:12:49] [connect] Successful connection
Mar 24 16:12:54 volumioes9023w ntpd[719]: Soliciting pool server 160.16.113.133
Mar 24 16:12:54 volumioes9023w ntpd[719]: Soliciting pool server 133.130.121.141
Mar 24 16:12:54 volumioes9023w ntpd[719]: Soliciting pool server 162.159.200.1
Mar 24 16:12:55 volumioes9023w ntpd[719]: Soliciting pool server 172.105.192.74
Mar 24 16:12:55 volumioes9023w ntpd[719]: Soliciting pool server 129.250.35.251
Mar 24 16:13:00 volumioes9023w systemd[1]: systemd-timedated.service: Succeeded.
Mar 24 16:13:01 volumioes9023w ntpd[719]: Soliciting pool server 172.233.91.137
Mar 24 16:13:02 volumioes9023w ntpd[719]: Soliciting pool server 167.179.119.205
Mar 24 16:13:02 volumioes9023w ntpd[719]: Soliciting pool server 85.121.50.82
Mar 24 16:13:02 volumioes9023w ntpd[719]: Soliciting pool server 43.167.237.61
Mar 24 16:13:02 volumioes9023w ntpd[719]: Soliciting pool server 208.88.66.8
Mar 24 16:13:03 volumioes9023w ntpd[719]: Soliciting pool server 142.91.105.55
Mar 24 16:13:03 volumioes9023w ntpd[719]: Soliciting pool server 142.91.108.61
Mar 24 16:13:03 volumioes9023w ntpd[719]: Soliciting pool server 85.121.122.62
Mar 24 16:13:03 volumioes9023w ntpd[719]: Soliciting pool server 139.162.81.45
Mar 24 16:13:04 volumioes9023w ntpd[719]: Soliciting pool server 162.159.200.123
Mar 24 16:13:04 volumioes9023w ntpd[719]: Soliciting pool server 43.229.16.149
Mar 24 16:13:04 volumioes9023w ntpd[719]: Soliciting pool server 46.250.253.227
Mar 24 16:13:04 volumioes9023w volumio-remote-updater[575]: [2026-03-24 16:13:04] [connect] Successful connection
Mar 24 16:13:14 volumioes9023w ntpd[719]: receive: Unexpected origin timestamp 0xed6cb802.906b2376 does not match aorg 0000000000.00000000 from server@43.167.237.61 xmt 0xed6cb80a.76da48e4
Mar 24 16:13:14 volumioes9023w ntpd[719]: receive: Unexpected origin timestamp 0xed6cb802.906dd64f does not match aorg 0000000000.00000000 from server@172.105.192.74 xmt 0xed6cb80a.76b768b8
Mar 24 16:13:14 volumioes9023w ntpd[719]: receive: Unexpected origin timestamp 0xed6cb802.906ce1c1 does not match aorg 0000000000.00000000 from server@129.250.35.251 xmt 0xed6cb80a.76c07231
Mar 24 16:13:14 volumioes9023w ntpd[719]: receive: Unexpected origin timestamp 0xed6cb802.906c16b9 does not match aorg 0000000000.00000000 from server@172.233.91.137 xmt 0xed6cb80a.77a0929b
Mar 24 16:13:14 volumioes9023w ntpd[719]: receive: Unexpected origin timestamp 0xed6cb802.90678e55 does not match aorg 0000000000.00000000 from server@142.91.108.61 xmt 0xed6cb80a.76d0e356
Mar 24 16:13:14 volumioes9023w ntpd[719]: receive: Unexpected origin timestamp 0xed6cb802.906a4cee does not match aorg 0000000000.00000000 from server@167.179.119.205 xmt 0xed6cb80a.76dba97b
Mar 24 16:13:14 volumioes9023w ntpd[719]: receive: Unexpected origin timestamp 0xed6cb802.9065477b does not match aorg 0000000000.00000000 from server@142.91.105.55 xmt 0xed6cb80a.797664fc
Mar 24 16:13:26 volumioes9023w systemd[1]: man-db.service: Succeeded.
Mar 24 16:13:26 volumioes9023w systemd[1]: Started Daily man-db regeneration.
Mar 24 16:13:27 volumioes9023w volumio-remote-updater[575]: [2026-03-24 16:13:27] [connect] Successful connection
Mar 24 16:13:29 volumioes9023w volumio[968]: info: Loading plugin "autostart"...
Mar 24 16:13:29 volumioes9023w systemd[1]: apt-daily.service: Succeeded.
Mar 24 16:13:29 volumioes9023w systemd[1]: Started Daily apt download activities.
Mar 24 16:13:29 volumioes9023w volumio[968]: info: Applying required configuration parameters for plugin autostart
Mar 24 16:13:29 volumioes9023w volumio[968]: info: AutoStart - onVolumioStart - read config.json
Mar 24 16:13:29 volumioes9023w volumio[968]: info: Loading plugin "now_playing"...
Mar 24 16:13:30 volumioes9023w systemd[1]: Starting Daily apt upgrade and clean activities...
Mar 24 16:13:31 volumioes9023w systemd[1]: apt-daily-upgrade.service: Succeeded.
Mar 24 16:13:31 volumioes9023w systemd[1]: Started Daily apt upgrade and clean activities.
Mar 24 16:13:31 volumioes9023w systemd[1]: Startup finished in 35.638s (kernel) + 1min 41.198s (userspace) = 2min 16.837s.
Mar 24 16:13:31 volumioes9023w volumio[968]: info: Loading plugin "outputs"...
Mar 24 16:13:31 volumioes9023w volumio[968]: info: Loading plugin "albumart"...
Mar 24 16:13:31 volumioes9023w volumio[968]: info: Plugin example_plugin is not enabled
Mar 24 16:13:31 volumioes9023w volumio[968]: info: Loading plugin "inputs"...
Mar 24 16:13:31 volumioes9023w volumio[968]: info: Loading plugin "updater_comm"...
Mar 24 16:13:32 volumioes9023w volumio[968]: Forking 3 albumart workers
Mar 24 16:13:32 volumioes9023w volumio[968]: info: Plugin mpdemulation is not enabled
Mar 24 16:13:32 volumioes9023w volumio[968]: info: Loading plugin "rest_api"...
Mar 24 16:13:32 volumioes9023w volumio[968]: info: Loading plugin "websocket"...
Mar 24 16:13:32 volumioes9023w volumio[968]: info: Starting Socket.io Server version 2.3.0
Mar 24 16:13:32 volumioes9023w volumio[968]: info: Loading plugin "mpdoutput"...
Mar 24 16:13:32 volumioes9023w volumio[968]: info: Loading plugin "80s80s"...
Mar 24 16:13:32 volumioes9023w volumio[968]: Starting albumart workers
Mar 24 16:13:32 volumioes9023w volumio[968]: Starting albumart workers
Mar 24 16:13:32 volumioes9023w volumio[968]: Starting albumart workers
Mar 24 16:13:33 volumioes9023w volumio[968]: info: Applying required configuration parameters for plugin 80s80s
Mar 24 16:13:33 volumioes9023w volumio[968]: info: [1774336413097] [80s80s] API delay: 30
Mar 24 16:13:33 volumioes9023w volumio[968]: info: Loading plugin "personal_radio"...
Mar 24 16:13:33 volumioes9023w volumio[968]: info: ControllerPersonalRadio::constructor
Mar 24 16:13:33 volumioes9023w volumio[968]: info: Loading plugin "touch_display"...
Mar 24 16:13:34 volumioes9023w volumio[968]: info: Applying required configuration parameters for plugin touch_display
Mar 24 16:13:34 volumioes9023w volumio[968]: info: Loading i18n strings for locale ja
Mar 24 16:13:34 volumioes9023w volumio[968]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Mar 24 16:13:34 volumioes9023w volumio[968]: Updating browse sources language
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::initPlayerControls
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 24 16:13:34 volumioes9023w volumio[968]: Express server listening on port 3000
Mar 24 16:13:34 volumioes9023w volumio[968]: [Metrics] WebUI: 75s 492.39ms
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreStateMachine::resetVolumioState
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreStateMachine::getcurrentVolume
Mar 24 16:13:34 volumioes9023w volumio[968]: info: CoreCommandRouter::volumioRetrievevolume
Mar 24 16:13:34 volumioes9023w volumio[968]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 24 16:13:34 volumioes9023w volumio[968]: TypeError: Cannot read property 'charAt' of undefined
Mar 24 16:13:34 volumioes9023w volumio[968]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24)
Mar 24 16:13:34 volumioes9023w volumio[968]: at listOnTimeout (internal/timers.js:554:17)
Mar 24 16:13:34 volumioes9023w volumio[968]: at processTimers (internal/timers.js:497:7)
Mar 24 16:13:34 volumioes9023w volumio[968]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 24 16:13:34 volumioes9023w sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-24 16:12
Mar 24 16:13:34 volumioes9023w sudo[1392]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET"
VOLUMIO_VERSION="3.912"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"