-- Logs begin at Fri 2024-09-27 12:06:41 JST, end at Tue 2024-10-01 14:45:57 JST. -- Oct 01 14:44:08 volumio volumio[935]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=34.69922324923677&lon=135.76401360324465: Error: Response error: 401 - Unauthorized Oct 01 14:44:08 volumio volumio[935]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 01 14:44:08 volumio volumio[935]: at runMicrotasks () Oct 01 14:44:08 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 01 14:44:08 volumio volumio[935]: at async Promise.all (index 0) Oct 01 14:44:08 volumio volumio[935]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 01 14:44:08 volumio volumio[935]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 01 14:44:08 volumio volumio[935]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 01 14:44:08 volumio volumio[935]: info: [now-playing] Fetching API key... Oct 01 14:44:08 volumio volumio[935]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=34.69922324923677&lon=135.76401360324465: Error: Response error: 401 - Unauthorized Oct 01 14:44:08 volumio volumio[935]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 01 14:44:08 volumio volumio[935]: at runMicrotasks () Oct 01 14:44:08 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 01 14:44:08 volumio volumio[935]: at async Promise.all (index 1) Oct 01 14:44:12 volumio volumio[935]: info: [now-playing] OpenWeatherMap API key obtained. Oct 01 14:44:13 volumio volumio[935]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=34.69922324923677&lon=135.76401360324465: Error: Response error: 401 - Unauthorized Oct 01 14:44:13 volumio volumio[935]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 01 14:44:13 volumio volumio[935]: at runMicrotasks () Oct 01 14:44:13 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 01 14:44:13 volumio volumio[935]: at async Promise.all (index 0) Oct 01 14:44:13 volumio volumio[935]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 01 14:44:13 volumio volumio[935]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 01 14:44:13 volumio volumio[935]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 01 14:44:13 volumio volumio[935]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Response error: 401 - Unauthorized Oct 01 14:44:13 volumio volumio[935]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:114:19) Oct 01 14:44:13 volumio volumio[935]: at runMicrotasks () Oct 01 14:44:13 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 01 14:44:13 volumio volumio[935]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Oct 01 14:44:13 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized Oct 01 14:44:13 volumio volumio[935]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 01 14:44:13 volumio volumio[935]: at runMicrotasks () Oct 01 14:44:13 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 01 14:44:13 volumio volumio[935]: at async Promise.all (index 0) Oct 01 14:44:13 volumio volumio[935]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Oct 01 14:44:13 volumio volumio[935]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Oct 01 14:44:13 volumio volumio[935]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Oct 01 14:44:13 volumio volumio[935]: (node:935) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1211) Oct 01 14:44:13 volumio volumio[935]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=34.69922324923677&lon=135.76401360324465: Error: Response error: 401 - Unauthorized Oct 01 14:44:13 volumio volumio[935]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Oct 01 14:44:13 volumio volumio[935]: at runMicrotasks () Oct 01 14:44:13 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 01 14:44:13 volumio volumio[935]: at async Promise.all (index 1) Oct 01 14:45:42 volumio volumio[935]: info: CorePlayQueue::getTrack 11 Oct 01 14:45:42 volumio volumio[935]: info: CorePlayQueue::getTrack 12 Oct 01 14:45:42 volumio volumio[935]: info: Prefetching next song Oct 01 14:45:42 volumio volumio[935]: info: DOING PREFETCH IN MPD Oct 01 14:45:42 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/EAC/水瀬いのり/[2019.01.23] 水瀬いのり｜Wonder Caravan!/02. 君色プロローグ.flac" Oct 01 14:45:42 volumio volumio[935]: info: sendMpdCommand add "NAS/music/EAC/水瀬いのり/[2019.01.23] 水瀬いのり｜Wonder Caravan!/02. 君色プロローグ.flac" took 16 milliseconds Oct 01 14:45:42 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 01 14:45:42 volumio volumio[935]: info: Oct 01 14:45:42 volumio volumio[935]: ---------------------------- MPD announces system playlist update Oct 01 14:45:42 volumio volumio[935]: info: Ignoring MPD Status Update Oct 01 14:45:42 volumio volumio[935]: info: Oct 01 14:45:42 volumio volumio[935]: ---------------------------- MPD announces system playlist update Oct 01 14:45:42 volumio volumio[935]: info: Ignoring MPD Status Update Oct 01 14:45:42 volumio volumio[935]: info: sendMpdCommand consume 1 took 51 milliseconds Oct 01 14:45:42 volumio volumio[935]: info: ------------------------------ 36ms Oct 01 14:45:42 volumio volumio[935]: info: ------------------------------ 23ms Oct 01 14:45:42 volumio nfsidmap[12190]: nss_getpwnam: name 'makoto' not found in domain 'localdomain' Oct 01 14:45:44 volumio nmbd[480]: [2024/10/01 14:45:44.111968, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 01 14:45:44 volumio nmbd[480]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.24.125 for name WORKGROUP<1d>. Oct 01 14:45:44 volumio nmbd[480]: This response was from IP 192.168.24.1, reporting an IP address of 192.168.24.1. Oct 01 14:45:47 volumio volumio[935]: info: CoreStateMachine::startPlaybackTimer Oct 01 14:45:47 volumio volumio[935]: info: CorePlayQueue::getTrack 12 Oct 01 14:45:48 volumio volumio[935]: info: Oct 01 14:45:48 volumio volumio[935]: ---------------------------- MPD announces system playlist update Oct 01 14:45:48 volumio volumio[935]: info: Ignoring MPD Status Update Oct 01 14:45:48 volumio volumio[935]: info: Oct 01 14:45:48 volumio volumio[935]: ---------------------------- MPD announces state update: player Oct 01 14:45:48 volumio volumio[935]: info: ControllerMpd::getState Oct 01 14:45:48 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Oct 01 14:45:48 volumio volumio[935]: info: ------------------------------ 35ms Oct 01 14:45:48 volumio volumio[935]: info: sendMpdCommand status took 31 milliseconds Oct 01 14:45:48 volumio volumio[935]: verbose: ControllerMpd::parseState Oct 01 14:45:48 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 14:45:48 volumio volumio[935]: info: Oct 01 14:45:48 volumio volumio[935]: ---------------------------- MPD announces system playlist update Oct 01 14:45:48 volumio volumio[935]: info: Ignoring MPD Status Update Oct 01 14:45:48 volumio volumio[935]: info: Oct 01 14:45:48 volumio volumio[935]: ---------------------------- MPD announces state update: player Oct 01 14:45:48 volumio volumio[935]: info: ControllerMpd::getState Oct 01 14:45:48 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand status Oct 01 14:45:48 volumio volumio[935]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 01 14:45:48 volumio volumio[935]: info: ------------------------------ 27ms Oct 01 14:45:48 volumio volumio[935]: info: sendMpdCommand status took 16 milliseconds Oct 01 14:45:48 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Oct 01 14:45:48 volumio volumio[935]: verbose: ControllerMpd::parseState Oct 01 14:45:48 volumio volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 01 14:45:48 volumio volumio[935]: info: ControllerMpd::pushState Oct 01 14:45:48 volumio volumio[935]: info: CoreCommandRouter::servicePushState Oct 01 14:45:48 volumio volumio[935]: info: CorePlayQueue::getTrack 12 Oct 01 14:45:48 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"895 Kbps","isStreaming":false,"title":"君色プロローグ","artist":"水瀬いのり","album":"Wonder Caravan!","uri":"NAS/music/EAC/水瀬いのり/[2019.01.23] 水瀬いのり｜Wonder Caravan!/02. 君色プロローグ.flac","trackType":"flac"} Oct 01 14:45:48 volumio volumio[935]: verbose: CURRENT POSITION 12 Oct 01 14:45:48 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Oct 01 14:45:48 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Oct 01 14:45:48 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Oct 01 14:45:48 volumio volumio[935]: info: CoreStateMachine::pushState Oct 01 14:45:48 volumio volumio[935]: info: CorePlayQueue::getTrack 12 Oct 01 14:45:48 volumio volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 14:45:48 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 01 14:45:48 volumio volumio[935]: info: CoreStateMachine::pushState Oct 01 14:45:48 volumio volumio[935]: info: CorePlayQueue::getTrack 12 Oct 01 14:45:48 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 01 14:45:48 volumio volumio[935]: info: ------------------------------ 206ms Oct 01 14:45:48 volumio volumio[935]: info: sendMpdCommand playlistinfo took 126 milliseconds Oct 01 14:45:48 volumio volumio[935]: verbose: ControllerMpd::parseTrackInfo Oct 01 14:45:48 volumio volumio[935]: info: ControllerMpd::pushState Oct 01 14:45:48 volumio volumio[935]: info: CoreCommandRouter::servicePushState Oct 01 14:45:48 volumio volumio[935]: info: CorePlayQueue::getTrack 12 Oct 01 14:45:48 volumio volumio[935]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"895 Kbps","isStreaming":false,"title":"君色プロローグ","artist":"水瀬いのり","album":"Wonder Caravan!","uri":"NAS/music/EAC/水瀬いのり/[2019.01.23] 水瀬いのり｜Wonder Caravan!/02. 君色プロローグ.flac","trackType":"flac"} Oct 01 14:45:48 volumio volumio[935]: verbose: CURRENT POSITION 12 Oct 01 14:45:48 volumio volumio[935]: info: CoreStateMachine::syncState stateService play Oct 01 14:45:48 volumio volumio[935]: info: CoreStateMachine::syncState currentStatus play Oct 01 14:45:48 volumio volumio[935]: info: Received an update from plugin. extracting info from payload Oct 01 14:45:48 volumio volumio[935]: info: CoreStateMachine::pushState Oct 01 14:45:48 volumio volumio[935]: info: CorePlayQueue::getTrack 12 Oct 01 14:45:48 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 01 14:45:48 volumio volumio[935]: info: CoreStateMachine::pushState Oct 01 14:45:48 volumio volumio[935]: info: CorePlayQueue::getTrack 12 Oct 01 14:45:48 volumio volumio[935]: info: CoreCommandRouter::volumioPushState Oct 01 14:45:48 volumio volumio[935]: info: ------------------------------ 302ms Oct 01 14:45:48 volumio volumio[935]: info: [LastFM] Current track has sufficient metadata: title (君色プロローグ) and artist (水瀬いのり) passed on explicitly Oct 01 14:45:48 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Oct 01 14:45:48 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Oct 01 14:45:48 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Oct 01 14:45:48 volumio volumio[935]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Oct 01 14:45:52 volumio volumio[935]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 14:45:52 volumio volumio[935]: TypeError: Cannot read property '@' of null Oct 01 14:45:52 volumio volumio[935]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Oct 01 14:45:52 volumio volumio[935]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Oct 01 14:45:52 volumio volumio[935]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:304:18) Oct 01 14:45:52 volumio volumio[935]: at Parser.emit (events.js:315:20) Oct 01 14:45:52 volumio volumio[935]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:314:16) Oct 01 14:45:52 volumio volumio[935]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Oct 01 14:45:52 volumio volumio[935]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Oct 01 14:45:52 volumio volumio[935]: at IncomingMessage.emit (events.js:327:22) Oct 01 14:45:52 volumio volumio[935]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 01 14:45:52 volumio volumio[935]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 01 14:45:52 volumio volumio[935]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 14:45:57 volumio sudo[12203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-01 14:44 Oct 01 14:45:57 volumio sudo[12203]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"