Skip to content

Instantly share code, notes, and snippets.

@pruperting

pruperting/gist:9c8ac97e73e4fc75acfdd64ddcb06237

Created September 18, 2024 21:19
Show Gist options
  • Save pruperting/9c8ac97e73e4fc75acfdd64ddcb06237 to your computer and use it in GitHub Desktop.
Save pruperting/9c8ac97e73e4fc75acfdd64ddcb06237 to your computer and use it in GitHub Desktop.
Download ZIP
Detailed logs from navidrome jukebox mode
Raw
gistfile1.txt
Sep 18 22:15:38 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:38+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:15:38 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:38+01:00" level=info msg="JukeboxControl request received" action=set requestId=hc2audioserver/WO914CAHl2-000002
Sep 18 22:15:38 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:38+01:00" level=debug msg="Processing Set action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" ids="[]" requestId=hc2audioserver/WO914CAHl2-000002
Sep 18 22:15:38 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:38+01:00" level=debug msg="Processing Clear action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" requestId=hc2audioserver/WO914CAHl2-000002
Sep 18 22:15:38 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:38+01:00" level=debug msg="Processing Add action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" ids="[]" requestId=hc2audioserver/WO914CAHl2-000002
Sep 18 22:15:38 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:38+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000002 status=OK
Sep 18 22:15:38 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:38+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=set" elapsedTime=24.6ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000002 responseSize=249 userAgent=DSub
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000003 username=prupert version=1.2.0
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=info msg="JukeboxControl request received" action=set requestId=hc2audioserver/WO914CAHl2-000003
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing Set action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" ids="[]" requestId=hc2audioserver/WO914CAHl2-000003
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing Clear action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" requestId=hc2audioserver/WO914CAHl2-000003
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing Add action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" ids="[]" requestId=hc2audioserver/WO914CAHl2-000003
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000003 status=OK
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=set" elapsedTime=32.6ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000003 responseSize=249 userAgent=DSub
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000004 username=prupert version=1.2.0
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=info msg="JukeboxControl request received" action=set requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing Set action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" ids="[f9aa22fc5662d9f30ba40b117c729047 a77204808c96d2739d055a98b26901a3 9e32bfe65f74402bda36a1a09012f53d bffc98fb978b8a73eef781bb92a3ed46 aaf4e847fa4137cebb193f8661f87dd9 54759f620e82720d81e8edb1badf07a5 4255c3fc5b1a453e49bbc88d20832fc9 9ad15f90a1bf3ebf7d4daba7e2c7ef8c]" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing Clear action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing Add action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" ids="[f9aa22fc5662d9f30ba40b117c729047 a77204808c96d2739d055a98b26901a3 9e32bfe65f74402bda36a1a09012f53d bffc98fb978b8a73eef781bb92a3ed46 aaf4e847fa4137cebb193f8661f87dd9 54759f620e82720d81e8edb1badf07a5 4255c3fc5b1a453e49bbc88d20832fc9 9ad15f90a1bf3ebf7d4daba7e2c7ef8c]" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found mediafile: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found mediafile: /mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found mediafile: /mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found mediafile: /mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found mediafile: /mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found mediafile: /mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found mediafile: /mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found mediafile: /mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac" requestId=hc2audioserver/WO914CAHl2-000004
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000004 status=OK
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=set&id=f9aa22fc5662d9f30ba40b117c729047&id=a77204808c96d2739d055a98b26901a3&id=9e32bfe65f74402bda36a1a09012f53d&id=bffc98fb978b8a73eef781bb92a3ed46&id=aaf4e847fa4137cebb193f8661f87dd9&id=54759f620e82720d81e8edb1badf07a5&id=4255c3fc5b1a453e49bbc88d20832fc9&id=9ad15f90a1bf3ebf7d4daba7e2c7ef8c" elapsedTime=124.1ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000004 responseSize=248 userAgent=DSub
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="API: New request /rest/scrobble.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000005 username=prupert version=1.2.0
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000005 type=DSub username=prupert
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=info msg="Now Playing" artist="Penny Penny" player="DSub [DSub]" requestId=hc2audioserver/WO914CAHl2-000005 title="Ibola Aids" user=prupert
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000006 username=prupert version=1.2.0
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=info msg="JukeboxControl request received" action=skip requestId=hc2audioserver/WO914CAHl2-000006
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Processing Skip action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" index=0 offset=0 requestId=hc2audioserver/WO914CAHl2-000006
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Loading track" mediaType=audio/flac trackPath="/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac"
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=info msg="Found mpv" path=/usr/bin/mpv
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Executing mpv command" cmd="[/usr/bin/mpv --no-audio-display --pause /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac --input-ipc-server=/tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket --audio-channels=stereo --audio-samplerate=48000 --audio-format=s16 --ao=pcm --ao-pcm-file=/tmp/mpd]"
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Sending NowPlaying update" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000005 scrobbler=listenbrainz track="Ibola Aids"
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="API: New request /rest/savePlayQueue.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000007 username=prupert version=1.2.0
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000007 type=DSub username=prupert
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="API: Successful response" endpoint=/rest/savePlayQueue.view requestId=hc2audioserver/WO914CAHl2-000007 status=OK
Sep 18 22:15:55 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:55+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/savePlayQueue.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=f9aa22fc5662d9f30ba40b117c729047&id=a77204808c96d2739d055a98b26901a3&id=9e32bfe65f74402bda36a1a09012f53d&id=bffc98fb978b8a73eef781bb92a3ed46&id=aaf4e847fa4137cebb193f8661f87dd9&id=54759f620e82720d81e8edb1badf07a5&id=4255c3fc5b1a453e49bbc88d20832fc9&id=9ad15f90a1bf3ebf7d4daba7e2c7ef8c&current=f9aa22fc5662d9f30ba40b117c729047&position=0" elapsedTime=14.5ms httpStatus=200 remoteAddr="192.168.1.44:40350" requestId=hc2audioserver/WO914CAHl2-000007 responseSize=175 userAgent=DSub
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Sending NowPlaying update" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000005 scrobbler=lastfm track="Ibola Aids"
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="API: Successful response" endpoint=/rest/scrobble.view requestId=hc2audioserver/WO914CAHl2-000005 status=OK
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/scrobble.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=f9aa22fc5662d9f30ba40b117c729047&submission=false" elapsedTime=695.2ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000005 responseSize=175 userAgent=DSub
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Socket found" retries=9 waitTime=908.4ms
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Setting volume" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket" volume=1
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Setting position" offset=0 track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=1
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=2
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=3
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=4
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=5
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=6
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="No position difference, skipping operation" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=1
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=2
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=3
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=4
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=5
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Got mpv error, retrying..." error="mpv error: property unavailable" retries=6
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000006 status=OK
Sep 18 22:15:56 hc2audioserver navidrome[20591]: time="2024-09-18T22:15:56+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=skip&index=0&offset=0" elapsedTime=1.02s httpStatus=200 remoteAddr="192.168.1.44:40348" requestId=hc2audioserver/WO914CAHl2-000006 responseSize=248 userAgent=DSub
Sep 18 22:16:00 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:00+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000008 username=prupert version=1.2.0
Sep 18 22:16:00 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:00+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:00 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:00+01:00" level=info msg="JukeboxControl request received" action=status requestId=hc2audioserver/WO914CAHl2-000008
Sep 18 22:16:00 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:00+01:00" level=debug msg="processing Status action on: Name: internal, Gain: 1.0000, Loaded track: Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket, queue: #Items: 8, idx: 0, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac " requestId=hc2audioserver/WO914CAHl2-000008
Sep 18 22:16:00 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:00+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:16:00 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:00+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000008 status=OK
Sep 18 22:16:00 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:00+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=status" elapsedTime=44.9ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000008 responseSize=248 userAgent=DSub
Sep 18 22:16:05 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:05+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000009 username=prupert version=1.2.0
Sep 18 22:16:05 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:05+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:05 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:05+01:00" level=info msg="JukeboxControl request received" action=status requestId=hc2audioserver/WO914CAHl2-000009
Sep 18 22:16:05 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:05+01:00" level=debug msg="processing Status action on: Name: internal, Gain: 1.0000, Loaded track: Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket, queue: #Items: 8, idx: 0, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac " requestId=hc2audioserver/WO914CAHl2-000009
Sep 18 22:16:05 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:05+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:16:05 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:05+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000009 status=OK
Sep 18 22:16:05 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:05+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=status" elapsedTime=23ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000009 responseSize=248 userAgent=DSub
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000010 username=prupert version=1.2.0
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=info msg="JukeboxControl request received" action=stop requestId=hc2audioserver/WO914CAHl2-000010
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="Processing Stop action" device="Name: internal, Gain: 1.0000, Loaded track: Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket" requestId=hc2audioserver/WO914CAHl2-000010
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="Pausing track" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000010 status=OK
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=stop" elapsedTime=20.6ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000010 responseSize=248 userAgent=DSub
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="API: New request /rest/savePlayQueue.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000011 username=prupert version=1.2.0
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000011 type=DSub username=prupert
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="API: Successful response" endpoint=/rest/savePlayQueue.view requestId=hc2audioserver/WO914CAHl2-000011 status=OK
Sep 18 22:16:08 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:08+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/savePlayQueue.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=f9aa22fc5662d9f30ba40b117c729047&id=a77204808c96d2739d055a98b26901a3&id=9e32bfe65f74402bda36a1a09012f53d&id=bffc98fb978b8a73eef781bb92a3ed46&id=aaf4e847fa4137cebb193f8661f87dd9&id=54759f620e82720d81e8edb1badf07a5&id=4255c3fc5b1a453e49bbc88d20832fc9&id=9ad15f90a1bf3ebf7d4daba7e2c7ef8c&current=f9aa22fc5662d9f30ba40b117c729047&position=0" elapsedTime=28.1ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000011 responseSize=175 userAgent=DSub
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000012 username=prupert version=1.2.0
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=info msg="JukeboxControl request received" action=start requestId=hc2audioserver/WO914CAHl2-000012
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=debug msg="Processing Start action" device="Name: internal, Gain: 1.0000, Loaded track: Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket" requestId=hc2audioserver/WO914CAHl2-000012
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=info msg="Starting trackSwitcher goroutine" requestId=hc2audioserver/WO914CAHl2-000012
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=debug msg="Started trackSwitcher goroutine" device="Name: internal, Gain: 1.0000, Loaded track: Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=debug msg="Unpausing track" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000012 status=OK
Sep 18 22:16:10 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:10+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=start" elapsedTime=240ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000012 responseSize=260 userAgent=DSub
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=info msg="Hitting end-of-stream, signalling on channel"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Track switching detected"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Closing resources" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac, Socket: /tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="sending shutdown command"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=warning msg="Error sending quit command to mpv-ipc socket" error="trying to send command on closed mpv client"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="cancelling executor"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Removing socketfile" socketfile=/tmp/mpv-ctrl-206c3605-7338-4f2c-8fe1-743b4d9d0f35.socket
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Switching to next song" queue="#Items: 8, idx: 1, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac "
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Loading track" mediaType=audio/flac trackPath="/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Executing mpv command" cmd="[/usr/bin/mpv --no-audio-display --pause /mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac --input-ipc-server=/tmp/mpv-ctrl-d0c8097f-2301-42bb-9fab-6f3f8040301e.socket --audio-channels=stereo --audio-samplerate=48000 --audio-format=s16 --ao=pcm --ao-pcm-file=/tmp/mpd]"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000013 username=prupert version=1.2.0
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=info msg="JukeboxControl request received" action=status requestId=hc2audioserver/WO914CAHl2-000013
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="processing Status action on: Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>), queue: #Items: 8, idx: 1, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac " requestId=hc2audioserver/WO914CAHl2-000013
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000013 status=OK
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=status" elapsedTime=13.4ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000013 responseSize=248 userAgent=DSub
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="API: New request /rest/scrobble.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000014 username=prupert version=1.2.0
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000014 type=DSub username=prupert
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Scrobbling tracks" ids="[f9aa22fc5662d9f30ba40b117c729047]" requestId=hc2audioserver/WO914CAHl2-000014 times="[]"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=info msg=Scrobbled artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000014 timestamp="2024-09-18 22:16:15.687575658 +0100 BST m=+543.256612543" title="Ibola Aids" user=prupert
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000014 scrobbler=lastfm track="Ibola Aids"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000014 scrobbler=listenbrainz track="Ibola Aids"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="API: Successful response" endpoint=/rest/scrobble.view requestId=hc2audioserver/WO914CAHl2-000014 status=OK
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/scrobble.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=f9aa22fc5662d9f30ba40b117c729047&submission=true" elapsedTime=70.1ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000014 responseSize=175 userAgent=DSub
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=lastfm track="Ibola Aids"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=listenbrainz track="Ibola Aids"
Sep 18 22:16:15 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:15+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/api/album?id=950186c576b99d81daace6b1c9f7df8d" elapsedTime=8.9ms httpStatus=200 remoteAddr="192.168.1.34:65200" requestId=hc2audioserver/WO914CAHl2-000015 responseSize=1765 userAgent="Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36"
Sep 18 22:16:16 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:16+01:00" level=debug msg="Socket found" retries=9 waitTime=910.2ms
Sep 18 22:16:16 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:16+01:00" level=debug msg="Setting volume" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac, Socket: /tmp/mpv-ctrl-d0c8097f-2301-42bb-9fab-6f3f8040301e.socket" volume=1
Sep 18 22:16:16 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:16+01:00" level=debug msg="Unpausing track" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac, Socket: /tmp/mpv-ctrl-d0c8097f-2301-42bb-9fab-6f3f8040301e.socket"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=info msg="Hitting end-of-stream, signalling on channel"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Track switching detected"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Closing resources" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac, Socket: /tmp/mpv-ctrl-d0c8097f-2301-42bb-9fab-6f3f8040301e.socket"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="sending shutdown command"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=warning msg="Error sending quit command to mpv-ipc socket" error="trying to send command on closed mpv client"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="cancelling executor"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Removing socketfile" socketfile=/tmp/mpv-ctrl-d0c8097f-2301-42bb-9fab-6f3f8040301e.socket
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Switching to next song" queue="#Items: 8, idx: 2, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac "
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Loading track" mediaType=audio/flac trackPath="/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Executing mpv command" cmd="[/usr/bin/mpv --no-audio-display --pause /mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac --input-ipc-server=/tmp/mpv-ctrl-7eba7f92-ed5d-46a1-8477-5df0b2adce91.socket --audio-channels=stereo --audio-samplerate=48000 --audio-format=s16 --ao=pcm --ao-pcm-file=/tmp/mpd]"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000016 username=prupert version=1.2.0
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=info msg="JukeboxControl request received" action=status requestId=hc2audioserver/WO914CAHl2-000016
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="processing Status action on: Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>), queue: #Items: 8, idx: 2, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac " requestId=hc2audioserver/WO914CAHl2-000016
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000016 status=OK
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=status" elapsedTime=3ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000016 responseSize=248 userAgent=DSub
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="API: New request /rest/scrobble.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000017 username=prupert version=1.2.0
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000017 type=DSub username=prupert
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Scrobbling tracks" ids="[a77204808c96d2739d055a98b26901a3]" requestId=hc2audioserver/WO914CAHl2-000017 times="[]"
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=info msg=Scrobbled artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000017 timestamp="2024-09-18 22:16:20.608097648 +0100 BST m=+548.177134159" title=Ingani user=prupert
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000017 scrobbler=lastfm track=Ingani
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000017 scrobbler=listenbrainz track=Ingani
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=listenbrainz track=Ingani
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="API: Successful response" endpoint=/rest/scrobble.view requestId=hc2audioserver/WO914CAHl2-000017 status=OK
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/scrobble.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=a77204808c96d2739d055a98b26901a3&submission=true" elapsedTime=23.9ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000017 responseSize=175 userAgent=DSub
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=lastfm track=Ingani
Sep 18 22:16:20 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:20+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/api/album?id=950186c576b99d81daace6b1c9f7df8d" elapsedTime=4.9ms httpStatus=200 remoteAddr="192.168.1.34:65200" requestId=hc2audioserver/WO914CAHl2-000018 responseSize=1765 userAgent="Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36"
Sep 18 22:16:21 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:21+01:00" level=debug msg="Socket found" retries=9 waitTime=910ms
Sep 18 22:16:21 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:21+01:00" level=debug msg="Setting volume" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac, Socket: /tmp/mpv-ctrl-7eba7f92-ed5d-46a1-8477-5df0b2adce91.socket" volume=1
Sep 18 22:16:21 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:21+01:00" level=debug msg="Unpausing track" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac, Socket: /tmp/mpv-ctrl-7eba7f92-ed5d-46a1-8477-5df0b2adce91.socket"
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000019 username=prupert version=1.2.0
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=info msg="JukeboxControl request received" action=start requestId=hc2audioserver/WO914CAHl2-000019
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=debug msg="Processing Start action" device="Name: internal, Gain: 1.0000, Loaded track: Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac, Socket: /tmp/mpv-ctrl-7eba7f92-ed5d-46a1-8477-5df0b2adce91.socket" requestId=hc2audioserver/WO914CAHl2-000019
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac, Socket: /tmp/mpv-ctrl-7eba7f92-ed5d-46a1-8477-5df0b2adce91.socket"
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=debug msg="API: New request /rest/scrobble.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000020 username=prupert version=1.2.0
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000020 type=DSub username=prupert
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=info msg="Now Playing" artist="Penny Penny" player="DSub [DSub]" requestId=hc2audioserver/WO914CAHl2-000020 title=Amarumasi user=prupert
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=debug msg="Sending NowPlaying update" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000020 scrobbler=lastfm track=Amarumasi
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=debug msg="trying to start an already playing track"
Sep 18 22:16:24 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:24+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac, Socket: /tmp/mpv-ctrl-7eba7f92-ed5d-46a1-8477-5df0b2adce91.socket"
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="Sending NowPlaying update" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000020 scrobbler=listenbrainz track=Amarumasi
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="API: Successful response" endpoint=/rest/scrobble.view requestId=hc2audioserver/WO914CAHl2-000020 status=OK
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/scrobble.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=9e32bfe65f74402bda36a1a09012f53d&submission=false" elapsedTime=205.8ms httpStatus=200 remoteAddr="192.168.1.44:40348" requestId=hc2audioserver/WO914CAHl2-000020 responseSize=175 userAgent=DSub
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000019 status=OK
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=start" elapsedTime=225.5ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000019 responseSize=262 userAgent=DSub
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=info msg="Hitting end-of-stream, signalling on channel"
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="Track switching detected"
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="Closing resources" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac, Socket: /tmp/mpv-ctrl-7eba7f92-ed5d-46a1-8477-5df0b2adce91.socket"
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="sending shutdown command"
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=warning msg="Error sending quit command to mpv-ipc socket" error="trying to send command on closed mpv client"
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="cancelling executor"
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="Removing socketfile" socketfile=/tmp/mpv-ctrl-7eba7f92-ed5d-46a1-8477-5df0b2adce91.socket
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="Switching to next song" queue="#Items: 8, idx: 3, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac "
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="Loading track" mediaType=audio/flac trackPath="/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac"
Sep 18 22:16:25 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:25+01:00" level=debug msg="Executing mpv command" cmd="[/usr/bin/mpv --no-audio-display --pause /mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac --input-ipc-server=/tmp/mpv-ctrl-7aedf641-78b5-4526-ae02-64330e77f77c.socket --audio-channels=stereo --audio-samplerate=48000 --audio-format=s16 --ao=pcm --ao-pcm-file=/tmp/mpd]"
Sep 18 22:16:26 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:26+01:00" level=debug msg="Socket found" retries=8 waitTime=805.2ms
Sep 18 22:16:26 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:26+01:00" level=debug msg="Setting volume" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac, Socket: /tmp/mpv-ctrl-7aedf641-78b5-4526-ae02-64330e77f77c.socket" volume=1
Sep 18 22:16:26 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:26+01:00" level=debug msg="Unpausing track" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac, Socket: /tmp/mpv-ctrl-7aedf641-78b5-4526-ae02-64330e77f77c.socket"
Sep 18 22:16:29 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:29+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000021 username=prupert version=1.2.0
Sep 18 22:16:29 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:29+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:29 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:29+01:00" level=info msg="JukeboxControl request received" action=status requestId=hc2audioserver/WO914CAHl2-000021
Sep 18 22:16:29 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:29+01:00" level=debug msg="processing Status action on: Name: internal, Gain: 1.0000, Loaded track: Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac, Socket: /tmp/mpv-ctrl-7aedf641-78b5-4526-ae02-64330e77f77c.socket, queue: #Items: 8, idx: 3, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac " requestId=hc2audioserver/WO914CAHl2-000021
Sep 18 22:16:29 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:29+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac, Socket: /tmp/mpv-ctrl-7aedf641-78b5-4526-ae02-64330e77f77c.socket"
Sep 18 22:16:29 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:29+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000021 status=OK
Sep 18 22:16:29 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:29+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=status" elapsedTime=114ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000021 responseSize=262 userAgent=DSub
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="API: New request /rest/scrobble.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000022 username=prupert version=1.2.0
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000022 type=DSub username=prupert
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="Scrobbling tracks" ids="[9e32bfe65f74402bda36a1a09012f53d]" requestId=hc2audioserver/WO914CAHl2-000022 times="[]"
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=info msg=Scrobbled artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000022 timestamp="2024-09-18 22:16:30.262499813 +0100 BST m=+557.831537824" title=Amarumasi user=prupert
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000022 scrobbler=lastfm track=Amarumasi
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000022 scrobbler=listenbrainz track=Amarumasi
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="API: Successful response" endpoint=/rest/scrobble.view requestId=hc2audioserver/WO914CAHl2-000022 status=OK
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/scrobble.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=9e32bfe65f74402bda36a1a09012f53d&submission=true" elapsedTime=51.5ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000022 responseSize=175 userAgent=DSub
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=lastfm track=Amarumasi
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=listenbrainz track=Amarumasi
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/api/album?id=950186c576b99d81daace6b1c9f7df8d" elapsedTime=17.4ms httpStatus=200 remoteAddr="192.168.1.34:65200" requestId=hc2audioserver/WO914CAHl2-000023 responseSize=1765 userAgent="Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36"
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="API: New request /rest/scrobble.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000024 username=prupert version=1.2.0
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000024 type=DSub username=prupert
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=info msg="Now Playing" artist="Penny Penny" player="DSub [DSub]" requestId=hc2audioserver/WO914CAHl2-000024 title="Kulani Kulani" user=prupert
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="Sending NowPlaying update" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000024 scrobbler=lastfm track="Kulani Kulani"
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="Sending NowPlaying update" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000024 scrobbler=listenbrainz track="Kulani Kulani"
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="API: Successful response" endpoint=/rest/scrobble.view requestId=hc2audioserver/WO914CAHl2-000024 status=OK
Sep 18 22:16:30 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:30+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/scrobble.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=bffc98fb978b8a73eef781bb92a3ed46&submission=false" elapsedTime=227ms httpStatus=200 remoteAddr="192.168.1.44:40348" requestId=hc2audioserver/WO914CAHl2-000024 responseSize=175 userAgent=DSub
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=info msg="Hitting end-of-stream, signalling on channel"
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=debug msg="Track switching detected"
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=debug msg="Closing resources" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac, Socket: /tmp/mpv-ctrl-7aedf641-78b5-4526-ae02-64330e77f77c.socket"
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=debug msg="sending shutdown command"
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=warning msg="Error sending quit command to mpv-ipc socket" error="trying to send command on closed mpv client"
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=debug msg="cancelling executor"
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=debug msg="Removing socketfile" socketfile=/tmp/mpv-ctrl-7aedf641-78b5-4526-ae02-64330e77f77c.socket
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=debug msg="Switching to next song" queue="#Items: 8, idx: 4, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac "
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=debug msg="Loading track" mediaType=audio/flac trackPath="/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac"
Sep 18 22:16:31 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:31+01:00" level=debug msg="Executing mpv command" cmd="[/usr/bin/mpv --no-audio-display --pause /mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac --input-ipc-server=/tmp/mpv-ctrl-598dc68c-82db-4f05-a1b6-e08e0d76c678.socket --audio-channels=stereo --audio-samplerate=48000 --audio-format=s16 --ao=pcm --ao-pcm-file=/tmp/mpd]"
Sep 18 22:16:32 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:32+01:00" level=debug msg="Socket found" retries=9 waitTime=906.5ms
Sep 18 22:16:32 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:32+01:00" level=debug msg="Setting volume" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac, Socket: /tmp/mpv-ctrl-598dc68c-82db-4f05-a1b6-e08e0d76c678.socket" volume=1
Sep 18 22:16:32 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:32+01:00" level=debug msg="Unpausing track" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac, Socket: /tmp/mpv-ctrl-598dc68c-82db-4f05-a1b6-e08e0d76c678.socket"
Sep 18 22:16:34 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:34+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000025 username=prupert version=1.2.0
Sep 18 22:16:34 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:34+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:34 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:34+01:00" level=info msg="JukeboxControl request received" action=status requestId=hc2audioserver/WO914CAHl2-000025
Sep 18 22:16:34 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:34+01:00" level=debug msg="processing Status action on: Name: internal, Gain: 1.0000, Loaded track: Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac, Socket: /tmp/mpv-ctrl-598dc68c-82db-4f05-a1b6-e08e0d76c678.socket, queue: #Items: 8, idx: 4, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac " requestId=hc2audioserver/WO914CAHl2-000025
Sep 18 22:16:34 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:34+01:00" level=debug msg="Checking if track is playing" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac, Socket: /tmp/mpv-ctrl-598dc68c-82db-4f05-a1b6-e08e0d76c678.socket"
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000025 status=OK
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=status" elapsedTime=177.2ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000025 responseSize=262 userAgent=DSub
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="API: New request /rest/scrobble.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000026 username=prupert version=1.2.0
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000026 type=DSub username=prupert
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="Scrobbling tracks" ids="[bffc98fb978b8a73eef781bb92a3ed46]" requestId=hc2audioserver/WO914CAHl2-000026 times="[]"
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=info msg=Scrobbled artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000026 timestamp="2024-09-18 22:16:35.237811893 +0100 BST m=+562.806848737" title="Kulani Kulani" user=prupert
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000026 scrobbler=lastfm track="Kulani Kulani"
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000026 scrobbler=listenbrainz track="Kulani Kulani"
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="API: Successful response" endpoint=/rest/scrobble.view requestId=hc2audioserver/WO914CAHl2-000026 status=OK
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/scrobble.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=bffc98fb978b8a73eef781bb92a3ed46&submission=true" elapsedTime=10.4ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000026 responseSize=175 userAgent=DSub
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=listenbrainz track="Kulani Kulani"
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=lastfm track="Kulani Kulani"
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/api/album?id=950186c576b99d81daace6b1c9f7df8d" elapsedTime=17.3ms httpStatus=200 remoteAddr="192.168.1.34:65200" requestId=hc2audioserver/WO914CAHl2-000027 responseSize=1765 userAgent="Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36"
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="API: New request /rest/scrobble.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000028 username=prupert version=1.2.0
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000028 type=DSub username=prupert
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=info msg="Now Playing" artist="Penny Penny" player="DSub [DSub]" requestId=hc2audioserver/WO914CAHl2-000028 title="Hai Kamina" user=prupert
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="Sending NowPlaying update" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000028 scrobbler=lastfm track="Hai Kamina"
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="Sending NowPlaying update" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000028 scrobbler=listenbrainz track="Hai Kamina"
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="API: Successful response" endpoint=/rest/scrobble.view requestId=hc2audioserver/WO914CAHl2-000028 status=OK
Sep 18 22:16:35 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:35+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/scrobble.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=aaf4e847fa4137cebb193f8661f87dd9&submission=false" elapsedTime=220.3ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000028 responseSize=175 userAgent=DSub
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=info msg="Hitting end-of-stream, signalling on channel"
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=debug msg="Track switching detected"
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=debug msg="Closing resources" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac, Socket: /tmp/mpv-ctrl-598dc68c-82db-4f05-a1b6-e08e0d76c678.socket"
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=debug msg="sending shutdown command"
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=warning msg="Error sending quit command to mpv-ipc socket" error="trying to send command on closed mpv client"
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=debug msg="cancelling executor"
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=debug msg="Removing socketfile" socketfile=/tmp/mpv-ctrl-598dc68c-82db-4f05-a1b6-e08e0d76c678.socket
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=debug msg="Switching to next song" queue="#Items: 8, idx: 5, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac "
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=debug msg="Loading track" mediaType=audio/flac trackPath="/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac"
Sep 18 22:16:36 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:36+01:00" level=debug msg="Executing mpv command" cmd="[/usr/bin/mpv --no-audio-display --pause /mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac --input-ipc-server=/tmp/mpv-ctrl-d985dfb2-ce1b-4f3b-bedd-0eb60c4f3794.socket --audio-channels=stereo --audio-samplerate=48000 --audio-format=s16 --ao=pcm --ao-pcm-file=/tmp/mpd]"
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="API: New request /rest/jukeboxControl.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000029 username=prupert version=1.2.0
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Processing GetDevice" user=prupert
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=info msg="JukeboxControl request received" action=stop requestId=hc2audioserver/WO914CAHl2-000029
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Processing Stop action" device="Name: internal, Gain: 1.0000, Loaded track: %!s(<nil>)" requestId=hc2audioserver/WO914CAHl2-000029
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="API: Successful response" endpoint=/rest/jukeboxControl.view requestId=hc2audioserver/WO914CAHl2-000029 status=OK
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/jukeboxControl.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&action=stop" elapsedTime=3.9ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000029 responseSize=248 userAgent=DSub
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="API: New request /rest/scrobble.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000030 username=prupert version=1.2.0
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="API: New request /rest/savePlayQueue.view" client=DSub requestId=hc2audioserver/WO914CAHl2-000031 username=prupert version=1.2.0
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000030 type=DSub username=prupert
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Found matching player" client=DSub id=cc2a618d-96c3-4a61-999e-c00e69c5faee requestId=hc2audioserver/WO914CAHl2-000031 type=DSub username=prupert
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Scrobbling tracks" ids="[aaf4e847fa4137cebb193f8661f87dd9]" requestId=hc2audioserver/WO914CAHl2-000030 times="[]"
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="API: Successful response" endpoint=/rest/savePlayQueue.view requestId=hc2audioserver/WO914CAHl2-000031 status=OK
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/savePlayQueue.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=f9aa22fc5662d9f30ba40b117c729047&id=a77204808c96d2739d055a98b26901a3&id=9e32bfe65f74402bda36a1a09012f53d&id=bffc98fb978b8a73eef781bb92a3ed46&id=aaf4e847fa4137cebb193f8661f87dd9&id=54759f620e82720d81e8edb1badf07a5&id=4255c3fc5b1a453e49bbc88d20832fc9&id=9ad15f90a1bf3ebf7d4daba7e2c7ef8c&current=aaf4e847fa4137cebb193f8661f87dd9&position=176000" elapsedTime=13.3ms httpStatus=200 remoteAddr="192.168.1.44:40348" requestId=hc2audioserver/WO914CAHl2-000031 responseSize=175 userAgent=DSub
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=info msg=Scrobbled artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000030 timestamp="2024-09-18 22:16:37.270102906 +0100 BST m=+564.839141001" title="Hai Kamina" user=prupert
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000030 scrobbler=listenbrainz track="Hai Kamina"
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Buffering Scrobble" artist="Penny Penny" requestId=hc2audioserver/WO914CAHl2-000030 scrobbler=lastfm track="Hai Kamina"
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="API: Successful response" endpoint=/rest/scrobble.view requestId=hc2audioserver/WO914CAHl2-000030 status=OK
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/rest/scrobble.view?u=prupert&s=dvmd5ah8u7q06d5ts834eh3o6u&t=50b61dd7b597e48541be29dc26df1e2e&v=1.2.0&c=DSub&id=aaf4e847fa4137cebb193f8661f87dd9&submission=true" elapsedTime=25.8ms httpStatus=200 remoteAddr="192.168.1.44:40340" requestId=hc2audioserver/WO914CAHl2-000030 responseSize=175 userAgent=DSub
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=listenbrainz track="Hai Kamina"
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Sending scrobble" artist="Penny Penny" scrobbler=lastfm track="Hai Kamina"
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="HTTP: GET http://192.168.1.22:4533/api/album?id=950186c576b99d81daace6b1c9f7df8d" elapsedTime=28ms httpStatus=200 remoteAddr="192.168.1.34:65200" requestId=hc2audioserver/WO914CAHl2-000032 responseSize=1765 userAgent="Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36"
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Socket found" retries=9 waitTime=907.5ms
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Setting volume" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac, Socket: /tmp/mpv-ctrl-d985dfb2-ce1b-4f3b-bedd-0eb60c4f3794.socket" volume=1
Sep 18 22:16:37 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:37+01:00" level=debug msg="Unpausing track" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac, Socket: /tmp/mpv-ctrl-d985dfb2-ce1b-4f3b-bedd-0eb60c4f3794.socket"
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=info msg="Hitting end-of-stream, signalling on channel"
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=debug msg="Track switching detected"
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=debug msg="Closing resources" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac, Socket: /tmp/mpv-ctrl-d985dfb2-ce1b-4f3b-bedd-0eb60c4f3794.socket"
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=debug msg="sending shutdown command"
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=warning msg="Error sending quit command to mpv-ipc socket" error="trying to send command on closed mpv client"
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=debug msg="cancelling executor"
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=debug msg="Removing socketfile" socketfile=/tmp/mpv-ctrl-d985dfb2-ce1b-4f3b-bedd-0eb60c4f3794.socket
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=debug msg="Switching to next song" queue="#Items: 8, idx: 6, files: 0:/mnt/sda/FLAC/World/90s/Yogo Yogo/02-Ibola Aids.flac 1:/mnt/sda/FLAC/World/90s/Yogo Yogo/03-Ingani.flac 2:/mnt/sda/FLAC/World/90s/Yogo Yogo/04-Amarumasi.flac 3:/mnt/sda/FLAC/World/90s/Yogo Yogo/05-Kulani Kulani.flac 4:/mnt/sda/FLAC/World/90s/Yogo Yogo/06-Hai Kamina.flac 5:/mnt/sda/FLAC/World/90s/Yogo Yogo/07-Yogo Yogo.flac 6:/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac 7:/mnt/sda/FLAC/World/90s/Yogo Yogo/09-Ama Owners.flac "
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=debug msg="Loading track" mediaType=audio/flac trackPath="/mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac"
Sep 18 22:16:41 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:41+01:00" level=debug msg="Executing mpv command" cmd="[/usr/bin/mpv --no-audio-display --pause /mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac --input-ipc-server=/tmp/mpv-ctrl-a6841d07-7cc0-411d-a5a6-c80aaa9c2238.socket --audio-channels=stereo --audio-samplerate=48000 --audio-format=s16 --ao=pcm --ao-pcm-file=/tmp/mpd]"
Sep 18 22:16:42 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:42+01:00" level=debug msg="Socket found" retries=9 waitTime=911.2ms
Sep 18 22:16:42 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:42+01:00" level=debug msg="Setting volume" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac, Socket: /tmp/mpv-ctrl-a6841d07-7cc0-411d-a5a6-c80aaa9c2238.socket" volume=1
Sep 18 22:16:42 hc2audioserver navidrome[20591]: time="2024-09-18T22:16:42+01:00" level=debug msg="Unpausing track" track="Name: /mnt/sda/FLAC/World/90s/Yogo Yogo/08-Ti Samboko.flac, Socket: /tmp/mpv-ctrl-a6841d07-7cc0-411d-a5a6-c80aaa9c2238.socket"
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment