Log in New API Help About

Playback.stop() doesnt work if playback.seek() is used

37.4 KB of Plain text
Created 2 months, 3 weeks ago — expires in 278 days
https://dpaste.com/C7HGDDFCB
COPY TO CLIPBOARD SOFT WRAP RAW TEXT DUPLICATE DIFF
INFO     2021-07-25 15:37:07,472 [3660:MainThread] mopidy.__main__
  Starting Mopidy 3.2.0
DEBUG    2021-07-25 15:37:07,484 [3660:MainThread] mopidy.ext
  Loading entry point: Figlio = mopidy_figlio:Extension
DEBUG    2021-07-25 15:37:07,487 [3660:MainThread] mopidy.ext
  Loaded extension: Mopidy-Figlio 0.0.1
DEBUG    2021-07-25 15:37:07,488 [3660:MainThread] mopidy.ext
  Loading entry point: file = mopidy.file:Extension
DEBUG    2021-07-25 15:37:07,489 [3660:MainThread] mopidy.ext
  Loaded extension: Mopidy-File 3.2.0
DEBUG    2021-07-25 15:37:07,489 [3660:MainThread] mopidy.ext
  Loading entry point: http = mopidy.http:Extension
DEBUG    2021-07-25 15:37:07,490 [3660:MainThread] mopidy.ext
  Loaded extension: Mopidy-HTTP 3.2.0
DEBUG    2021-07-25 15:37:07,490 [3660:MainThread] mopidy.ext
  Loading entry point: m3u = mopidy.m3u:Extension
DEBUG    2021-07-25 15:37:07,492 [3660:MainThread] mopidy.ext
  Loaded extension: Mopidy-M3U 3.2.0
DEBUG    2021-07-25 15:37:07,492 [3660:MainThread] mopidy.ext
  Loading entry point: softwaremixer = mopidy.softwaremixer:Extension
DEBUG    2021-07-25 15:37:07,493 [3660:MainThread] mopidy.ext
  Loaded extension: Mopidy-SoftwareMixer 3.2.0
DEBUG    2021-07-25 15:37:07,493 [3660:MainThread] mopidy.ext
  Loading entry point: stream = mopidy.stream:Extension
DEBUG    2021-07-25 15:37:07,493 [3660:MainThread] mopidy.ext
  Loaded extension: Mopidy-Stream 3.2.0
DEBUG    2021-07-25 15:37:07,494 [3660:MainThread] mopidy.ext
  Loading entry point: iris = mopidy_iris:Extension
DEBUG    2021-07-25 15:37:07,495 [3660:MainThread] mopidy.ext
  Loaded extension: Mopidy-Iris 3.58.0
DEBUG    2021-07-25 15:37:07,500 [3660:MainThread] mopidy.ext
  Discovered extensions: Figlio, file, http, m3u, softwaremixer, stream, iris
DEBUG    2021-07-25 15:37:07,509 [3660:MainThread] mopidy.config.keyring
  Fetching passwords from your keyring failed. Any passwords stored in the keyring will not be available. (org.freedesktop.secrets service not running)
INFO     2021-07-25 15:37:07,510 [3660:MainThread] mopidy.config
  Loading config from builtin defaults
DEBUG    2021-07-25 15:37:07,512 [3660:MainThread] mopidy.config
  Loading config from file:///etc/xdg/mopidy/mopidy.conf failed; it does not exist
INFO     2021-07-25 15:37:07,513 [3660:MainThread] mopidy.config
  Loading config from file:///home/pi/.config/mopidy/mopidy.conf
INFO     2021-07-25 15:37:07,514 [3660:MainThread] mopidy.config
  Loading config from command line options
DEBUG    2021-07-25 15:37:07,527 [3660:MainThread] mopidy.ext
  Validating extension: Figlio
DEBUG    2021-07-25 15:37:07,766 [3660:MainThread] mopidy.ext
  Validating extension: file
DEBUG    2021-07-25 15:37:07,769 [3660:MainThread] mopidy.ext
  Validating extension: http
DEBUG    2021-07-25 15:37:07,911 [3660:MainThread] mopidy.ext
  Validating extension: m3u
DEBUG    2021-07-25 15:37:07,913 [3660:MainThread] mopidy.ext
  Validating extension: softwaremixer
DEBUG    2021-07-25 15:37:07,916 [3660:MainThread] mopidy.ext
  Validating extension: stream
DEBUG    2021-07-25 15:37:07,919 [3660:MainThread] mopidy.ext
  Validating extension: iris
INFO     2021-07-25 15:37:07,981 [3660:MainThread] mopidy.__main__
  Enabled extensions: stream, m3u, file, Figlio, softwaremixer
INFO     2021-07-25 15:37:07,982 [3660:MainThread] mopidy.__main__
  Disabled extensions: http, iris
DEBUG    2021-07-25 15:37:08,097 [3660:MainThread] mopidy.commands
  Available Mopidy mixers: SoftwareMixer
INFO     2021-07-25 15:37:08,097 [3660:MainThread] mopidy.commands
  Starting Mopidy mixer: SoftwareMixer
DEBUG    2021-07-25 15:37:08,098 [3660:MainThread] pykka
  Registered SoftwareMixer (urn:uuid:0f15fef4-0938-4e3b-bcea-f46aa89bc67f)
DEBUG    2021-07-25 15:37:08,098 [3660:MainThread] pykka
  Starting SoftwareMixer (urn:uuid:0f15fef4-0938-4e3b-bcea-f46aa89bc67f)
INFO     2021-07-25 15:37:08,101 [3660:MainThread] mopidy.commands
  Mixer volume set to 5
INFO     2021-07-25 15:37:08,102 [3660:MainThread] mopidy.commands
  Starting Mopidy audio
DEBUG    2021-07-25 15:37:08,103 [3660:MainThread] pykka
  Registered Audio (urn:uuid:2ec801f8-da8b-49ab-9410-86ce50b74807)
DEBUG    2021-07-25 15:37:08,103 [3660:MainThread] pykka
  Starting Audio (urn:uuid:2ec801f8-da8b-49ab-9410-86ce50b74807)
INFO     2021-07-25 15:37:08,108 [3660:MainThread] mopidy.commands
  Starting Mopidy backends: FileBackend, M3UBackend, StreamBackend
DEBUG    2021-07-25 15:37:08,109 [3660:MainThread] pykka
  Registered FileBackend (urn:uuid:05f0622f-1e98-4dc5-b255-5cc070d77b4b)
DEBUG    2021-07-25 15:37:08,109 [3660:MainThread] pykka
  Starting FileBackend (urn:uuid:05f0622f-1e98-4dc5-b255-5cc070d77b4b)
DEBUG    2021-07-25 15:37:08,122 [3660:MainThread] pykka
  Registered M3UBackend (urn:uuid:87f771d1-7c9c-465d-b9dd-74bff301730b)
DEBUG    2021-07-25 15:37:08,123 [3660:MainThread] pykka
  Starting M3UBackend (urn:uuid:87f771d1-7c9c-465d-b9dd-74bff301730b)
DEBUG    2021-07-25 15:37:08,131 [3660:MainThread] pykka
  Registered StreamBackend (urn:uuid:44a7c4b9-818e-452e-b45f-6f382aae58c4)
DEBUG    2021-07-25 15:37:08,131 [3660:MainThread] pykka
  Starting StreamBackend (urn:uuid:44a7c4b9-818e-452e-b45f-6f382aae58c4)
INFO     2021-07-25 15:37:08,140 [3660:MainThread] mopidy.commands
  Starting Mopidy core
INFO     2021-07-25 15:37:08,141 [3660:Audio-2] mopidy.audio.actor
  Audio output set to "alsasink"
DEBUG    2021-07-25 15:37:08,153 [3660:SoftwareMixer-1] mopidy.mixer
  Mixer event: volume_changed(volume=5)
DEBUG    2021-07-25 15:37:08,154 [3660:SoftwareMixer-1] mopidy.listener
  Sending volume_changed to MixerListener: {'volume': 5}
DEBUG    2021-07-25 15:37:08,155 [3660:MainThread] pykka
  Registered Core (urn:uuid:5a10fb09-972b-48bf-9a0a-5bef1dd155ef)
DEBUG    2021-07-25 15:37:08,156 [3660:MainThread] pykka
  Starting Core (urn:uuid:5a10fb09-972b-48bf-9a0a-5bef1dd155ef)
INFO     2021-07-25 15:37:08,167 [3660:MainThread] mopidy.commands
  Starting Mopidy frontends: FiglioFrontend
DEBUG    2021-07-25 15:37:08,188 [3660:MainThread] pykka
  Registered FiglioFrontend (urn:uuid:51eff153-f918-4a07-aca0-ec2b44590d71)
DEBUG    2021-07-25 15:37:08,188 [3660:MainThread] pykka
  Starting FiglioFrontend (urn:uuid:51eff153-f918-4a07-aca0-ec2b44590d71)
INFO     2021-07-25 15:37:08,189 [3660:FiglioFrontend-7] mopidy_figlio.frontend
  !!!!!!!!!!!!!!!!! Figlio Frontend started !!!!!!!!!!!!!!!!!!!!!!!!!!!!
INFO     2021-07-25 15:37:08,190 [3660:MainThread] mopidy.commands
  Starting GLib mainloop
INFO     2021-07-25 15:37:11,115 [3660:Dummy-8] mopidy_figlio.frontend
  Card ID: 840610300028 Content: m3u:Blues.m3u8,4,120000                         
INFO     2021-07-25 15:37:11,118 [3660:Dummy-8] mopidy_figlio.frontend
  Playlist URI: m3u:Blues.m3u8 tlid: 4 seek: 120000                         
INFO     2021-07-25 15:37:11,128 [3660:Dummy-8] mopidy_figlio.frontend
  Playing: Blues Last Modifed:1624622678010 URI:m3u:Blues.m3u8
DEBUG    2021-07-25 15:37:11,160 [3660:Core-6] mopidy.core.tracklist
  Triggering options changed event
DEBUG    2021-07-25 15:37:11,160 [3660:Core-6] mopidy.listener
  Sending options_changed to CoreListener: {}
DEBUG    2021-07-25 15:37:11,170 [3660:FileBackend-3] mopidy.file.library
  Looking up file URI: file:///home/pi/Music/Blues/004%20doggin%27%20the%20blues.mp3
0:00:03.776783804  3660 0xb2207400 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<filesrc0> pad not activated yet
0:00:03.786869415  3660  0x1574800 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
0:00:03.799443345  3660 0xb2207400 FIXME                    bin gstbin.c:4338:gst_bin_query: implement duration caching in GstBin again
DEBUG    2021-07-25 15:37:11,200 [3660:FileBackend-3] mopidy.audio.scan
  Using workaround for duration missing before play.
0:00:03.800911207  3660 0xb2207400 WARN                     bin gstbin.c:2787:gst_bin_do_latency_func:<pipeline0> did not really configure latency of 0:00:00.000000000
DEBUG    2021-07-25 15:37:11,217 [3660:FileBackend-3] mopidy.file.library
  Looking up file URI: file:///home/pi/Music/Blues/003%20friendless%20blues.mp3
0:00:03.819289939  3660 0xb2207400 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<filesrc1> pad not activated yet
0:00:03.821705815  3660  0x1574800 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
DEBUG    2021-07-25 15:37:11,229 [3660:FileBackend-3] mopidy.audio.scan
  Using workaround for duration missing before play.
0:00:03.829864492  3660 0xb2207400 WARN                     bin gstbin.c:2787:gst_bin_do_latency_func:<pipeline1> did not really configure latency of 0:00:00.000000000
DEBUG    2021-07-25 15:37:11,244 [3660:FileBackend-3] mopidy.file.library
  Looking up file URI: file:///home/pi/Music/Blues/008%20it%27s%20your%20time%20to%20worr.mp3
0:00:03.846638955  3660 0xb2207400 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<filesrc2> pad not activated yet
0:00:03.849028479  3660  0x1574800 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
DEBUG    2021-07-25 15:37:11,257 [3660:FileBackend-3] mopidy.audio.scan
  Using workaround for duration missing before play.
0:00:03.857364859  3660 0xb2207400 WARN                     bin gstbin.c:2787:gst_bin_do_latency_func:<pipeline2> did not really configure latency of 0:00:00.000000000
DEBUG    2021-07-25 15:37:11,271 [3660:FileBackend-3] mopidy.file.library
  Looking up file URI: file:///home/pi/Music/Blues/005%20meet%20me%20around%20the%20cor.mp3
0:00:03.873812620  3660 0xb2207400 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<filesrc3> pad not activated yet
0:00:03.876239903  3660  0x1574800 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
DEBUG    2021-07-25 15:37:11,284 [3660:FileBackend-3] mopidy.audio.scan
  Using workaround for duration missing before play.
0:00:03.883931194  3660 0xb2207400 WARN                     bin gstbin.c:2787:gst_bin_do_latency_func:<pipeline3> did not really configure latency of 0:00:00.000000000
DEBUG    2021-07-25 15:37:11,296 [3660:FileBackend-3] mopidy.file.library
  Looking up file URI: file:///home/pi/Music/Blues/006%20mistreater%2Cyou%27re%20goin.mp3
0:00:03.899649107  3660 0xb2207400 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<filesrc4> pad not activated yet
0:00:03.902429962  3660  0x1574800 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
DEBUG    2021-07-25 15:37:11,310 [3660:FileBackend-3] mopidy.audio.scan
  Using workaround for duration missing before play.
0:00:03.911297284  3660 0xb2207400 WARN                     bin gstbin.c:2787:gst_bin_do_latency_func:<pipeline4> did not really configure latency of 0:00:00.000000000
DEBUG    2021-07-25 15:37:11,323 [3660:FileBackend-3] mopidy.file.library
  Looking up file URI: file:///home/pi/Music/Blues/007%20my%20own%20boogie.mp3
0:00:03.925821037  3660 0xb2207400 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<filesrc5> pad not activated yet
0:00:03.930090550  3660  0x1574800 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
DEBUG    2021-07-25 15:37:11,337 [3660:FileBackend-3] mopidy.audio.scan
  Using workaround for duration missing before play.
0:00:03.937070234  3660 0xb2207400 WARN                     bin gstbin.c:2787:gst_bin_do_latency_func:<pipeline5> did not really configure latency of 0:00:00.000000000
DEBUG    2021-07-25 15:37:11,348 [3660:FileBackend-3] mopidy.file.library
  Looking up file URI: file:///home/pi/Music/Blues/002%20nobody%20in%20town%20can%20bak.mp3
0:00:03.950865565  3660 0xb2207400 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<filesrc6> pad not activated yet
0:00:03.953294033  3660  0x1574800 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
DEBUG    2021-07-25 15:37:11,360 [3660:FileBackend-3] mopidy.audio.scan
  Using workaround for duration missing before play.
0:00:03.960357902  3660 0xb2207400 WARN                     bin gstbin.c:2787:gst_bin_do_latency_func:<pipeline6> did not really configure latency of 0:00:00.000000000
DEBUG    2021-07-25 15:37:11,372 [3660:FileBackend-3] mopidy.file.library
  Looking up file URI: file:///home/pi/Music/Blues/009%20not%20guilty%20blues.mp3
0:00:03.974351991  3660 0xb2207400 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<filesrc7> pad not activated yet
0:00:03.976759682  3660  0x1574800 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
DEBUG    2021-07-25 15:37:11,383 [3660:FileBackend-3] mopidy.audio.scan
  Using workaround for duration missing before play.
0:00:03.983660588  3660 0xb2207400 WARN                     bin gstbin.c:2787:gst_bin_do_latency_func:<pipeline7> did not really configure latency of 0:00:00.000000000
DEBUG    2021-07-25 15:37:11,395 [3660:FileBackend-3] mopidy.file.library
  Looking up file URI: file:///home/pi/Music/Blues/001%20rock%20me%20mamma.mp3
0:00:03.997756807  3660 0xb2207400 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<filesrc8> pad not activated yet
0:00:04.000338700  3660  0x1574800 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
DEBUG    2021-07-25 15:37:11,407 [3660:FileBackend-3] mopidy.audio.scan
  Using workaround for duration missing before play.
0:00:04.007738381  3660 0xb2207400 WARN                     bin gstbin.c:2787:gst_bin_do_latency_func:<pipeline8> did not really configure latency of 0:00:00.000000000
DEBUG    2021-07-25 15:37:11,421 [3660:Core-6] mopidy.core.tracklist
  Triggering event: tracklist_changed()
DEBUG    2021-07-25 15:37:11,421 [3660:Core-6] mopidy.listener
  Sending tracklist_changed to CoreListener: {}
INFO     2021-07-25 15:37:11,423 [3660:Dummy-8] mopidy_figlio.frontend
  TL Tracks Type:<class 'list'>
INFO     2021-07-25 15:37:11,424 [3660:Dummy-8] mopidy_figlio.frontend
  TLIDs: [TlTrack(tlid=1, track=Track(artists=[Artist(name='Big Joe Turner')], bitrate=160001, genre='Blues', length=185971, name="Doggin' The Blues", uri='file:///home/pi/Music/Blues/004%20doggin%27%20the%20blues.mp3')), TlTrack(tlid=2, track=Track(artists=[Artist(name='Big Bill Broonzy')], bitrate=160001, genre='Blues', length=205419, name='Friendless Blues', uri='file:///home/pi/Music/Blues/003%20friendless%20blues.mp3')), TlTrack(tlid=3, track=Track(artists=[Artist(name='Blind Willie McTell')], bitrate=160001, genre='Blues', length=183308, name="It's Your Time To Worry", uri='file:///home/pi/Music/Blues/008%20it%27s%20your%20time%20to%20worr.mp3')), TlTrack(tlid=4, track=Track(artists=[Artist(name='Big Joe Williams')], bitrate=160001, genre='Blues', length=178896, name='Meet Me Around The Corner', uri='file:///home/pi/Music/Blues/005%20meet%20me%20around%20the%20cor.mp3')), TlTrack(tlid=5, track=Track(artists=[Artist(name='Blind Boy Fuller, Sonny Terry')], bitrate=160001, genre='Blues', length=145378, name="Mistreater,You're Going To Be Sorry", uri='file:///home/pi/Music/Blues/006%20mistreater%2Cyou%27re%20goin.mp3')), TlTrack(tlid=6, track=Track(artists=[Artist(name='Blind John Davis')], bitrate=160001, genre='Blues', length=145299, name='My Own Boogie', uri='file:///home/pi/Music/Blues/007%20my%20own%20boogie.mp3')), TlTrack(tlid=7, track=Track(artists=[Artist(name='Bessie Smith')], bitrate=160001, genre='Blues', length=213016, name='Nobody In Town Can Bake A Sweet Jelly Roll Like Mine', uri='file:///home/pi/Music/Blues/002%20nobody%20in%20town%20can%20bak.mp3')), TlTrack(tlid=8, track=Track(artists=[Artist(name='Brownie McGhee')], bitrate=160001, genre='Blues', length=157125, name='Not Guilty Blues', uri='file:///home/pi/Music/Blues/009%20not%20guilty%20blues.mp3')), TlTrack(tlid=9, track=Track(artists=[Artist(name='Arthur "Big Boy" Crudup')], bitrate=160001, genre='Blues', length=177617, name='Rock Me Mamma', uri='file:///home/pi/Music/Blues/001%20rock%20me%20mamma.mp3'))]
DEBUG    2021-07-25 15:37:11,442 [3660:Audio-2] mopidy.audio.gst
  Changing state to GST_STATE_READY: result=GST_STATE_CHANGE_SUCCESS
DEBUG    2021-07-25 15:37:11,444 [3660:MainThread] mopidy.audio.gst
  Got STATE_CHANGED bus message: old=GST_STATE_NULL new=GST_STATE_READY pending=GST_STATE_VOID_PENDING
DEBUG    2021-07-25 15:37:11,445 [3660:Audio-2] mopidy.audio.actor
  Flags: 2
DEBUG    2021-07-25 15:37:11,447 [3660:SoftwareMixer-1] mopidy.mixer
  Mixer event: volume_changed(volume=5)
DEBUG    2021-07-25 15:37:11,447 [3660:SoftwareMixer-1] mopidy.listener
  Sending volume_changed to MixerListener: {'volume': 5}
0:00:04.051802897  3660 0xb2c281c0 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<source> pad not activated yet
DEBUG    2021-07-25 15:37:11,453 [3660:Audio-2] mopidy.audio.gst
  Got source-setup signal: element=GstFileSrc
0:00:04.054594604  3660 0xb2c281c0 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<source> pad not activated yet
0:00:04.056937832  3660  0x1574350 WARN                    alsa conf.c:5014:snd_config_expand: alsalib error: Unknown parameters {AES0 0x02 AES1 0x82 AES2 0x00 AES3 0x02}
0:00:04.057011591  3660  0x1574350 WARN                    alsa pcm.c:2565:snd_pcm_open_noupdate: alsalib error: Unknown PCM default:{AES0 0x02 AES1 0x82 AES2 0x00 AES3 0x02}
DEBUG    2021-07-25 15:37:11,457 [3660:Audio-2] mopidy.audio.gst
  Changing state to GST_STATE_PLAYING: result=GST_STATE_CHANGE_ASYNC
DEBUG    2021-07-25 15:37:11,458 [3660:Core-6] mopidy.listener
  Sending volume_changed to CoreListener: {'volume': 5}
INFO     2021-07-25 15:37:11,460 [3660:Dummy-8] mopidy_figlio.frontend
  Tracklist version: 1 Length: 9 Index:3
0:00:04.062920688  3660  0x1574350 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<source> pad not activated yet
DEBUG    2021-07-25 15:37:11,465 [3660:Audio-2] mopidy.audio.gst
  Changing state to GST_STATE_READY: result=GST_STATE_CHANGE_SUCCESS
DEBUG    2021-07-25 15:37:11,467 [3660:Audio-2] mopidy.audio.actor
  Flags: 2
DEBUG    2021-07-25 15:37:11,468 [3660:MainThread] mopidy.audio.gst
  Got STATE_CHANGED bus message: old=GST_STATE_READY new=GST_STATE_READY pending=GST_STATE_VOID_PENDING
DEBUG    2021-07-25 15:37:11,469 [3660:SoftwareMixer-1] mopidy.mixer
  Mixer event: volume_changed(volume=5)
DEBUG    2021-07-25 15:37:11,470 [3660:SoftwareMixer-1] mopidy.listener
  Sending volume_changed to MixerListener: {'volume': 5}
0:00:04.072227303  3660 0xb2c281c0 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<source> pad not activated yet
DEBUG    2021-07-25 15:37:11,472 [3660:Audio-2] mopidy.audio.gst
  Got source-setup signal: element=GstFileSrc
0:00:04.073239242  3660 0xb2c281c0 WARN                 basesrc gstbasesrc.c:3583:gst_base_src_start_complete:<source> pad not activated yet
DEBUG    2021-07-25 15:37:11,474 [3660:Audio-2] mopidy.audio.gst
  Changing state to GST_STATE_PLAYING: result=GST_STATE_CHANGE_ASYNC
0:00:04.074766030  3660  0x1574db0 WARN                    alsa conf.c:5014:snd_config_expand: alsalib error: Unknown parameters {AES0 0x02 AES1 0x82 AES2 0x00 AES3 0x02}
DEBUG    2021-07-25 15:37:11,475 [3660:Core-6] mopidy.listener
  Sending volume_changed to CoreListener: {'volume': 5}
0:00:04.075303360  3660  0x1574db0 WARN                    alsa pcm.c:2565:snd_pcm_open_noupdate: alsalib error: Unknown PCM default:{AES0 0x02 AES1 0x82 AES2 0x00 AES3 0x02}
INFO     2021-07-25 15:37:11,477 [3660:Dummy-8] mopidy_figlio.frontend
  Seek: False
0:00:04.077585959  3660  0x1574350 FIXME                  id3v2 gstid3tag.c:142:gst_tag_from_id3_tag: Cannot map ID3v2 tag 'TCP
DEBUG    2021-07-25 15:37:11,501 [3660:MainThread] mopidy.audio.gst
  Got STREAM_START bus message
DEBUG    2021-07-25 15:37:11,501 [3660:MainThread] mopidy.audio.actor
  Audio event: stream_changed(uri='file:///home/pi/Music/Blues/005%20meet%20me%20around%20the%20cor.mp3')
DEBUG    2021-07-25 15:37:11,501 [3660:MainThread] mopidy.listener
  Sending stream_changed to AudioListener: {'uri': 'file:///home/pi/Music/Blues/005%20meet%20me%20around%20the%20cor.mp3'}
DEBUG    2021-07-25 15:37:11,502 [3660:Audio-2] mopidy.audio.gst
  Sending flushing seek: position=160000000000
0:00:04.111317624  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.111524901  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.111710270  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.111889584  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.112067268  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.112249971  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.112465525  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.112633469  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.112809727  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.112989541  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.113164022  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.113337502  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.113519316  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.113690222  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.113858184  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.114084646  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.114297349  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.114554051  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.114748605  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.115121937  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.115364787  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.115571305  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.115971488  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.116333208  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.116830224  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.117050148  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.117254962  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.117481979  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.117664978  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.117985088  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.118571770  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.118796324  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.119119526  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.119363487  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.119569097  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.119764244  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.120071298  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.120296853  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.120501000  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.120883886  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.121233588  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.121467050  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.121681123  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.121886807  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.122096824  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.122354026  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.122557507  3660  0x1574800 WARN          mpegaudioparse gstmpegaudioparse.c:475:gst_mpeg_audio_parse_head_check:<mpegaudioparse9> invalid bitrate: 0xf
0:00:04.139886615  3660  0x1574c60 WARN         audio-resampler audio-resampler.c:274:convert_taps_gint16_c: can't find exact taps
DEBUG    2021-07-25 15:37:11,542 [3660:Dummy-9] mopidy.audio.gst
  Got SEGMENT pad event: rate=1.0 format=time start=160000000000 stop=18446744073709551615 position=160000000000
DEBUG    2021-07-25 15:37:11,542 [3660:Dummy-9] mopidy.audio.actor
  Audio event: position_changed(position=160000)
DEBUG    2021-07-25 15:37:11,543 [3660:Dummy-9] mopidy.listener
  Sending position_changed to AudioListener: {'position': 160000}
0:00:04.143324114  3660  0x1574d20 WARN                    alsa pcm_hw.c:1355:snd_pcm_hw_get_chmap: alsalib error: Cannot read Channel Map ctl
: No such file or directory
DEBUG    2021-07-25 15:37:11,543 [3660:Core-6] mopidy.core.playback
  Triggering seeked event
DEBUG    2021-07-25 15:37:11,544 [3660:Core-6] mopidy.listener
  Sending seeked to CoreListener: {'time_position': 160000}
0:00:04.145549121  3660 0xb2c42600 WARN               audiosink gstaudiosink.c:218:audioringbuffer_thread_func:<alsasink0> failed to set thread priority
DEBUG    2021-07-25 15:37:11,552 [3660:MainThread] mopidy.audio.gst
  Got TAG bus message: tags={'title': ['Meet Me Around The Corner'], 'artist': ['Big Joe Williams'], 'genre': ['Blues'], 'private-id3v2-frame': [b'TCP\x00\x00\x03\x001\x00'], 'extended-comment': ['iTunPGAP[en]=0', 'iTunNORM[en]= 00000760 00000711 00008386 00007E02 0001F900 0001F900 00006B71 000068FC 00023C72 00023C72', 'iTunSMPB[en]= 00000000 00000210 000007C8 0000000000786CA8 00000000 00369163 00000000 00000000 00000000 00000000 00000000 00000000', 'iTunes_CDDB_IDs[en]=20+775D108BC21654C5A76DC39FE3C380A5+7065810'], 'encoded-by': ['iTunes v7.0.1.8'], 'container-format': ['ID3 tag'], 'has-crc': [False], 'channel-mode': ['joint-stereo'], 'audio-codec': ['MPEG-1 Layer 3 (MP3)'], 'nominal-bitrate': [160000]}
DEBUG    2021-07-25 15:37:11,554 [3660:MainThread] mopidy.audio.actor
  Audio event: tags_changed(tags=['title', 'artist', 'genre', 'private-id3v2-frame', 'extended-comment', 'encoded-by', 'container-format', 'has-crc', 'channel-mode', 'audio-codec', 'nominal-bitrate'])
DEBUG    2021-07-25 15:37:11,555 [3660:MainThread] mopidy.listener
  Sending tags_changed to AudioListener: {'tags': ['title', 'artist', 'genre', 'private-id3v2-frame', 'extended-comment', 'encoded-by', 'container-format', 'has-crc', 'channel-mode', 'audio-codec', 'nominal-bitrate']}
DEBUG    2021-07-25 15:37:11,559 [3660:MainThread] mopidy.audio.gst
  Got STATE_CHANGED bus message: old=GST_STATE_READY new=GST_STATE_PAUSED pending=GST_STATE_PLAYING
DEBUG    2021-07-25 15:37:11,561 [3660:MainThread] mopidy.audio.gst
  Got ASYNC_DONE bus message.
DEBUG    2021-07-25 15:37:11,564 [3660:MainThread] mopidy.audio.gst
  Got TAG bus message: tags={'title': ['Meet Me Around The Corner'], 'artist': ['Big Joe Williams'], 'genre': ['Blues'], 'private-id3v2-frame': [b'TCP\x00\x00\x03\x001\x00'], 'extended-comment': ['iTunPGAP[en]=0', 'iTunNORM[en]= 00000760 00000711 00008386 00007E02 0001F900 0001F900 00006B71 000068FC 00023C72 00023C72', 'iTunSMPB[en]= 00000000 00000210 000007C8 0000000000786CA8 00000000 00369163 00000000 00000000 00000000 00000000 00000000 00000000', 'iTunes_CDDB_IDs[en]=20+775D108BC21654C5A76DC39FE3C380A5+7065810'], 'encoded-by': ['iTunes v7.0.1.8'], 'container-format': ['ID3 tag'], 'has-crc': [False], 'channel-mode': ['joint-stereo'], 'audio-codec': ['MPEG-1 Layer 3 (MP3)'], 'nominal-bitrate': [160000], 'minimum-bitrate': [160168], 'maximum-bitrate': [160168], 'bitrate': [160015]}
DEBUG    2021-07-25 15:37:11,565 [3660:MainThread] mopidy.audio.actor
  Audio event: tags_changed(tags=['minimum-bitrate', 'maximum-bitrate', 'bitrate'])
DEBUG    2021-07-25 15:37:11,567 [3660:MainThread] mopidy.listener
  Sending tags_changed to AudioListener: {'tags': ['minimum-bitrate', 'maximum-bitrate', 'bitrate']}
DEBUG    2021-07-25 15:37:11,570 [3660:MainThread] mopidy.audio.gst
  Got TAG bus message: tags={'title': ['Meet Me Around The Corner'], 'artist': ['Big Joe Williams'], 'genre': ['Blues'], 'private-id3v2-frame': [b'TCP\x00\x00\x03\x001\x00'], 'extended-comment': ['iTunPGAP[en]=0', 'iTunNORM[en]= 00000760 00000711 00008386 00007E02 0001F900 0001F900 00006B71 000068FC 00023C72 00023C72', 'iTunSMPB[en]= 00000000 00000210 000007C8 0000000000786CA8 00000000 00369163 00000000 00000000 00000000 00000000 00000000 00000000', 'iTunes_CDDB_IDs[en]=20+775D108BC21654C5A76DC39FE3C380A5+7065810'], 'encoded-by': ['iTunes v7.0.1.8'], 'container-format': ['ID3 tag'], 'has-crc': [False], 'channel-mode': ['joint-stereo'], 'audio-codec': ['MPEG-1 Layer 3 (MP3)'], 'nominal-bitrate': [160000], 'minimum-bitrate': [159862], 'maximum-bitrate': [160168], 'bitrate': [160001]}
DEBUG    2021-07-25 15:37:11,572 [3660:MainThread] mopidy.audio.actor
  Audio event: tags_changed(tags=['minimum-bitrate', 'bitrate'])
DEBUG    2021-07-25 15:37:11,573 [3660:MainThread] mopidy.listener
  Sending tags_changed to AudioListener: {'tags': ['minimum-bitrate', 'bitrate']}
DEBUG    2021-07-25 15:37:11,578 [3660:MainThread] mopidy.audio.gst
  Got STATE_CHANGED bus message: old=GST_STATE_PAUSED new=GST_STATE_PLAYING pending=GST_STATE_VOID_PENDING
DEBUG    2021-07-25 15:37:11,579 [3660:MainThread] mopidy.audio.actor
  Audio event: state_changed(old_state=stopped, new_state=playing, target_state=None)
DEBUG    2021-07-25 15:37:11,580 [3660:MainThread] mopidy.listener
  Sending state_changed to AudioListener: {'old_state': 'stopped', 'new_state': 'playing', 'target_state': None}
INFO     2021-07-25 15:37:15,892 [3660:Dummy-8] mopidy_figlio.frontend
  Core Playback State:stopped
DEBUG    2021-07-25 15:37:22,324 [3660:Dummy-10] mopidy.audio.gst
  Got about-to-finish event.
DEBUG    2021-07-25 15:37:22,324 [3660:Dummy-10] mopidy.audio.actor
  Running about-to-finish callback.
DEBUG    2021-07-25 15:37:29,838 [3660:MainThread] mopidy.audio.gst
  Got EOS (end of stream) bus message.
DEBUG    2021-07-25 15:37:29,839 [3660:MainThread] mopidy.audio.actor
  Audio event: reached_end_of_stream()
DEBUG    2021-07-25 15:37:29,841 [3660:MainThread] mopidy.listener
  Sending reached_end_of_stream to AudioListener: {}
DEBUG    2021-07-25 15:37:29,842 [3660:Core-6] mopidy.core.playback
  Changing state: stopped -> stopped
DEBUG    2021-07-25 15:37:29,843 [3660:Core-6] mopidy.core.playback
  Triggering playback state change event
DEBUG    2021-07-25 15:37:29,843 [3660:Core-6] mopidy.listener
  Sending playback_state_changed to CoreListener: {'old_state': 'stopped', 'new_state': 'stopped'}
DEBUG    2021-07-25 15:37:29,849 [3660:Core-6] mopidy.core.playback
  Triggering track playback ended event
DEBUG    2021-07-25 15:37:29,849 [3660:Core-6] mopidy.listener
  Sending track_playback_ended to CoreListener: {'tl_track': TlTrack(tlid=4, track=Track(artists=[Artist(name='Big Joe Williams')], bitrate=160001, genre='Blues', length=178896, name='Meet Me Around The Corner', uri='file:///home/pi/Music/Blues/005%20meet%20me%20around%20the%20cor.mp3')), 'time_position': 178285}

Share: