Ticket #13597: mythtv-audio-pause-bug

File mythtv-audio-pause-bug, 19.7 KB (added by Jarno Suni, 4 years ago)

output of mythfrontend -v audio,gpu,gpuaudio,gpuvideo

Line 
12020-03-02 10:49:38.972447 I  Setup Interrupt handler
22020-03-02 10:49:38.972466 I  Setup Terminated handler
32020-03-02 10:49:38.972472 I  Setup Segmentation fault handler
42020-03-02 10:49:38.972476 I  Setup Aborted handler
52020-03-02 10:49:38.972480 I  Setup Bus error handler
62020-03-02 10:49:38.972484 I  Setup Floating point exception handler
72020-03-02 10:49:38.972488 I  Setup Illegal instruction handler
82020-03-02 10:49:38.972494 I  Setup Real-time signal 0 handler
92020-03-02 10:49:38.972498 I  Setup User defined signal 1 handler
102020-03-02 10:49:38.972502 I  Setup User defined signal 2 handler
112020-03-02 10:49:38.972506 I  Setup Hangup handler
122020-03-02 10:49:38.972613 C  mythfrontend version:  [v30.0] www.mythtv.org
132020-03-02 10:49:38.972618 C  Qt version: compile: 5.12.4, runtime: 5.12.4
142020-03-02 10:49:38.972621 N  Enabled verbose msgs:  general audio gpu gpuaudio gpuvideo
152020-03-02 10:49:38.972632 N  Setting Log Level to LOG_INFO
162020-03-02 10:49:38.982994 N  Using runtime prefix = /usr
172020-03-02 10:49:38.983008 N  Using configuration directory = /home/jarnos/.mythtv
182020-03-02 10:49:38.983031 I  Added logging to the console
192020-03-02 10:49:38.983058 I  Assumed character encoding: en_US.UTF-8
202020-03-02 10:49:38.983284 I  Added syslogging
212020-03-02 10:49:38.983427 I  Loading fi translation for module mythfrontend
222020-03-02 10:49:38.983604 I  Empty LocalHostName. This is typical.
232020-03-02 10:49:38.983609 I  Using a profile name of: 'jarnos-OptiPlex-9010' (Usually the same as this host's name.)
242020-03-02 10:49:38.983645 I  UPNP Search up to 2 secs
252020-03-02 10:49:39.432248 I  UPNP Search up to 1 secs
262020-03-02 10:49:39.810480 I  UPNP Search up to 1 secs
272020-03-02 10:49:40.883666 I  Start up testing connections. DB 192.168.100.51, BE , attempt 0, status dbAwake, Delay: 2000
282020-03-02 10:49:43.012843 N  Setting QT default locale to fi_FI
292020-03-02 10:49:43.012854 I  Current locale fi_FI
302020-03-02 10:49:43.012889 E  No locale defaults file for fi_FI, skipping
312020-03-02 10:49:43.015635 I  Starting process manager
322020-03-02 10:49:43.015639 I  Starting process signal handler
332020-03-02 10:49:43.016911 I  Starting IO manager (write)
342020-03-02 10:49:43.017012 I  Starting IO manager (read)
352020-03-02 10:49:43.066650 I  ScreenSaverX11Private: DPMS is active.
362020-03-02 10:49:43.179379 N  Desktop video mode: 1920x1080 50.000 Hz
372020-03-02 10:49:43.634399 I  Listening on TCP 0.0.0.0:6547
382020-03-02 10:49:43.643013 I  Listening on TCP [::]:6547
392020-03-02 10:49:46.352315 I  Loading fi translation for module mythfrontend
402020-03-02 10:49:46.400409 E  LIRC: Failed to connect to Unix socket '/var/run/lirc/lircd'
41                        eno: No such file or directory (2)
422020-03-02 10:49:46.400453 I  No joystick configuration found, not enabling joystick control
432020-03-02 10:49:46.543803 E  CECAdapter: Failed to find any CEC devices.
442020-03-02 10:49:46.543935 I  CECAdapter: Closing down CEC.
452020-03-02 10:49:46.543958 I  UDPListener: Enabling
462020-03-02 10:49:46.548705 I  Binding to UDP 0.0.0.0:6948
472020-03-02 10:49:46.548748 I  Binding to UDP [::]:6948
482020-03-02 10:49:46.888874 I  Using Frameless Window
492020-03-02 10:49:46.888879 I  Using Full Screen Window
502020-03-02 10:49:46.906695 I  UI Screen Resolution: 1920 x 1080
512020-03-02 10:49:46.923473 W  OpenGL: Could not determine whether Sync to VBlank is enabled.
522020-03-02 10:49:47.017960 I  Trying the OpenGL 2.0 render
532020-03-02 10:49:47.037205 I  OpenGL painter using existing OpenGL context.
542020-03-02 10:49:47.048698 I  OpenGL2: GLSL supported
552020-03-02 10:49:47.048738 I  OpenGL: OpenGL vendor  : Intel Open Source Technology Center
562020-03-02 10:49:47.048742 I  OpenGL: OpenGL renderer: Mesa DRI Intel(R) Ivybridge Desktop
572020-03-02 10:49:47.048745 I  OpenGL: OpenGL version : 3.0 Mesa 19.2.8
582020-03-02 10:49:47.048749 I  OpenGL: Max texture size: 16384 x 16384
592020-03-02 10:49:47.048751 I  OpenGL: Max texture units: 8
602020-03-02 10:49:47.048755 I  OpenGL: Direct rendering: Yes
612020-03-02 10:49:47.048759 I  OpenGL: Extensions Supported: 3e1b
622020-03-02 10:49:47.048761 I  OpenGL: PixelBufferObject support available
632020-03-02 10:49:47.049289 I  OpenGL: Initialised MythRenderOpenGL
642020-03-02 10:49:47.060074 I  MythUIHelper: Pruning cache directory: /home/jarnos/.mythtv/cache/remotecache
652020-03-02 10:49:47.060361 I  MythUIHelper: Kept 50 files, deleted 0 files, stat error on 0 files
662020-03-02 10:49:47.060382 I  MythUIHelper: Pruning cache directory: /home/jarnos/.mythtv/cache/thumbnails
672020-03-02 10:49:47.060597 I  MythUIHelper: Kept 42 files, deleted 0 files, stat error on 0 files
682020-03-02 10:49:47.196047 E  RAOP Device: Aborting startup - no key found.
692020-03-02 10:49:47.200502 I  AirPlay: Created airplay objects.
702020-03-02 10:49:47.201132 I  Listening on TCP 0.0.0.0:5100
712020-03-02 10:49:47.201168 I  Listening on TCP [::]:5100
722020-03-02 10:49:47.275912 I  Current MythTV Schema Version (DBSchemaVer): 1350
732020-03-02 10:49:47.330061 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.100.51:6543 (try 1 of 1)
742020-03-02 10:49:47.335660 I  MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
752020-03-02 10:49:48.242797 I  Bonjour: Service registration complete: name 'MythTV on jarnos-OptiPlex-9010' type '_airplay._tcp.' domain: 'local.'
762020-03-02 10:49:50.145868 N  Registering Internal as a media playback plugin.
772020-03-02 10:49:50.155815 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
782020-03-02 10:49:50.156799 W  MMUnix:UDisks2 service found. Media Monitor does not support this yet!
792020-03-02 10:49:50.163158 W  No plugins directory /usr/lib/mythtv/plugins
802020-03-02 10:49:50.213989 N  Found mainmenu.xml for theme 'Functionality'
812020-03-02 10:49:50.234911 I  Checking for theme updates every hour
822020-03-02 10:49:50.235091 I  Registering HouseKeeperTask 'HardwareProfiler'.
832020-03-02 10:49:50.256971 I  Queueing HouseKeeperTask 'HardwareProfiler'.
842020-03-02 10:49:50.256981 I  Starting HouseKeeper.
852020-03-02 10:49:50.375326 I  Bonjour: Service registration complete: name 'Mythfrontend on jarnos-OptiPlex-9010' type '_mythfrontend._tcp.' domain: 'local.'
86
87
88
89
90
91
922020-03-02 10:50:50.301654 I  Running HouseKeeperTask 'HardwareProfiler'.
932020-03-02 10:50:50.323210 I  HouseKeeperTask 'HardwareProfiler' Failed.
942020-03-02 10:50:57.380556 I  TV::TV(): Creating TV object
952020-03-02 10:50:57.424274 N  Suspending idle timer
962020-03-02 10:50:57.447935 I  TV::Init(): Created TvPlayWindow.
972020-03-02 10:50:57.547097 I  TV::HandleStateChange(): Attempting to change from None to WatchingLiveTV
982020-03-02 10:50:57.547124 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.100.51:6543 (try 1 of 1)
992020-03-02 10:50:58.267687 I  TV::HandleStateChange(): playbackURL(myth://mythbook/10024_20200302085058.ts) inputtype(DUMMY)
1002020-03-02 10:50:58.432797 I  ALSA: OpenDevice pulse
1012020-03-02 10:50:58.436433 I  AOS: Sample rate 5512 is supported
1022020-03-02 10:50:58.436444 I  AOS: Sample rate 8000 is supported
1032020-03-02 10:50:58.436449 I  AOS: Sample rate 11025 is supported
1042020-03-02 10:50:58.436453 I  AOS: Sample rate 16000 is supported
1052020-03-02 10:50:58.436457 I  AOS: Sample rate 22050 is supported
1062020-03-02 10:50:58.436461 I  AOS: Sample rate 32000 is supported
1072020-03-02 10:50:58.436464 I  AOS: Sample rate 44100 is supported
1082020-03-02 10:50:58.436468 I  AOS: Sample rate 48000 is supported
1092020-03-02 10:50:58.436472 I  AOS: Sample rate 88200 is supported
1102020-03-02 10:50:58.436476 I  AOS: Sample rate 96000 is supported
1112020-03-02 10:50:58.436479 I  AOS: Sample rate 176400 is supported
1122020-03-02 10:50:58.436482 I  AOS: Sample rate 192000 is supported
1132020-03-02 10:50:58.436496 I  AOS: Format unsigned 8 bit is supported
1142020-03-02 10:50:58.436516 I  AOS: Format signed 16 bit is supported
1152020-03-02 10:50:58.436522 I  AOS: Format signed 24 bit LSB is supported
1162020-03-02 10:50:58.436525 I  AOS: Format signed 24 bit MSB is supported
1172020-03-02 10:50:58.436527 I  AOS: Format signed 32 bit is supported
1182020-03-02 10:50:58.436530 I  AOS: Format 32 bit floating point is supported
1192020-03-02 10:50:58.436539 I  AOS: 1 channel(s) are supported
1202020-03-02 10:50:58.436544 I  AOS: 2 channel(s) are supported
1212020-03-02 10:50:58.436549 I  AOS: 3 channel(s) are supported
1222020-03-02 10:50:58.436553 I  AOS: 4 channel(s) are supported
1232020-03-02 10:50:58.436558 I  AOS: 5 channel(s) are supported
1242020-03-02 10:50:58.436561 I  AOS: 6 channel(s) are supported
1252020-03-02 10:50:58.436565 I  AOS: 7 channel(s) are supported
1262020-03-02 10:50:58.436568 I  AOS: 8 channel(s) are supported
1272020-03-02 10:50:58.436585 E  ALSA: snd_pcm_info_get_card: Operation not permitted
1282020-03-02 10:50:58.436588 I  ALSA: Can't get card and device number
1292020-03-02 10:50:58.464623 N  AudioPlayer: Enabling Audio
1302020-03-02 10:50:58.748748 I  Player(0): Video timing method: DRM
1312020-03-02 10:50:58.754593 I  TV::StartPlayer(): Created player.
1322020-03-02 10:50:58.754646 I  TV::HandleStateChange(): Changing from None to WatchingLiveTV
1332020-03-02 10:50:58.754652 I  TV::HandleStateChange(): State is LiveTV & mctx == ctx
1342020-03-02 10:50:58.759389 I  TV::HandleStateChange(): UpdateOSDInput done
1352020-03-02 10:50:58.759404 I  TV::HandleStateChange(): UpdateLCD done
1362020-03-02 10:50:58.763201 I  TV::HandleStateChange(): ITVRestart done
1372020-03-02 10:50:58.765801 I  TV::HandleStateChange(): Main UI disabled.
1382020-03-02 10:50:58.765827 I  TV::StartTV(): Entering main playback loop.
1392020-03-02 10:50:58.854762 I  ScreenSaverX11Private: DPMS Deactivated 1
1402020-03-02 10:50:59.488388 I  AOBase: Pause 1
1412020-03-02 10:51:02.283239 I  AFD: codec MP2 has 2 channels
1422020-03-02 10:51:02.283305 E  AFD: Could not open codec 0x55c80746d380, id(MP2) type(Audio) ignoring. reason Invalid argument
1432020-03-02 10:51:02.283310 I  AFD: codec MP2 has 2 channels
1442020-03-02 10:51:02.284054 I  AFD: Opened codec 0x55c809596680, id(MP2) type(Audio)
1452020-03-02 10:51:02.284336 I  AFD: Audio Track #1, of type (Audio Description) is A/V stream #2 (id=0x22b) and has 2 channels in the Dutch; Flemish language(6583668).
1462020-03-02 10:51:02.368196 I  GLVid: Using 'opengl' for OpenGL video type
1472020-03-02 10:51:02.376505 I  AFD: Using vaapi2 for video decoding
1482020-03-02 10:51:02.376906 I  AFD: Opened codec 0x55c80959adc0, id(H264) type(Video)
1492020-03-02 10:51:02.437134 W  Enabling buffering optimisations for low bitrate stream.
1502020-03-02 10:51:02.439327 W  Enabling buffering optimisations for low bitrate stream.
1512020-03-02 10:51:02.439380 I  AFD: Selected track 1: Dutch; Flemish (Audio Description) (A/V Stream #2)
1522020-03-02 10:51:02.439399 I  AFD: Audio data is planar
1532020-03-02 10:51:02.439428 I  AFD: Initializing audio parms from audio track #1
1542020-03-02 10:51:02.439441 I  AFD: Audio format changed
155                        from id(NONE)     -1Hz -1ch -1bps     (profile 0) to id( MP2)  48000Hz  2ch 16bps     (profile 0)
1562020-03-02 10:51:02.439461 I  AOBase: Killing AudioOutputDSP
1572020-03-02 10:51:02.439470 I  AOBase: Original codec was MP2, signed 16 bit, 48 kHz, 2 channels
1582020-03-02 10:51:02.439479 I  AOBase: enc(0), passthru(0), features (LPCM) configured_channels(2), 2 channels supported(1) max_channels(2)
1592020-03-02 10:51:02.439487 I  AOBase: Opening audio device 'pulse' ch 2(2) sr 48000 sf signed 16 bit reenc 0
1602020-03-02 10:51:02.439490 I  ALSA: OpenDevice pulse
1612020-03-02 10:51:02.449515 I  ALSA: SetParameters(format=2, channels=2, rate=48000, buffer_time=500000, period_time=4)
1622020-03-02 10:51:02.449567 I  ALSA: Buffer size range from 96 to 1048576
1632020-03-02 10:51:02.449570 I  ALSA: Period size range from 32 to 349526
1642020-03-02 10:51:02.449578 I  ALSA: Buffer time = 500000 us
1652020-03-02 10:51:02.449584 I  ALSA: Period time = 4 periods
1662020-03-02 10:51:02.451737 I  ALSA: Buffer size = 24000 | Period size = 6000
1672020-03-02 10:51:02.452752 I  AOBase: Audio fragment size: 12000
1682020-03-02 10:51:02.452769 I  AOBase: Audio Stretch Factor: 1
1692020-03-02 10:51:02.452830 I  AOBase: Ending Reconfigure()
1702020-03-02 10:51:02.452981 I  AOBase: kickoffOutputAudioLoop: pid = 4558
1712020-03-02 10:51:02.452993 I  AOBase: OutputAudioLoop: Play Event
1722020-03-02 10:51:02.557408 W  Enabling buffering optimisations for low bitrate stream.
1732020-03-02 10:51:02.558101 I  AOBase: Pause 0
1742020-03-02 10:51:02.562156 I  AOBase: Pause 1
1752020-03-02 10:51:02.563689 I  AOBase: OutputAudioLoop: audio paused
1762020-03-02 10:51:02.573923 I  AOBase: Pause 1
1772020-03-02 10:51:02.593815 I  AOBase: Pause 1
1782020-03-02 10:51:02.613822 I  AOBase: Pause 1
1792020-03-02 10:51:02.633817 I  AOBase: Pause 1
1802020-03-02 10:51:02.636447 I  AOBase: OutputAudioLoop: audio paused
1812020-03-02 10:51:02.653859 I  AOBase: Pause 1
1822020-03-02 10:51:02.674116 I  MythCodecContext: Enabled hardware decoder based deinterlace filter 'vaapi2doubleratedefault': <deinterlace_vaapi=mode=default:rate=field:auto=1>.
1832020-03-02 10:51:02.674116 I  Player(0): Unable to enable Video Output based deinterlacing
1842020-03-02 10:51:02.674201 I  AOBase: Pause 1
1852020-03-02 10:51:02.679421 N  Player(0): Waited 121ms for video buffers AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
1862020-03-02 10:51:02.693902 I  AOBase: Pause 1
1872020-03-02 10:51:02.713952 I  AOBase: Pause 1
1882020-03-02 10:51:02.717269 I  AOBase: OutputAudioLoop: audio paused
1892020-03-02 10:51:02.733833 I  AOBase: Pause 1
1902020-03-02 10:51:02.753854 I  AOBase: Pause 1
1912020-03-02 10:51:02.773872 I  AOBase: Pause 1
1922020-03-02 10:51:02.793920 I  AOBase: Pause 1
1932020-03-02 10:51:02.796579 N  Player(0): Waited 238ms for video buffers UUUULAAAAAAAAAAAAAAAAAAAAAAAAAAP
1942020-03-02 10:51:02.813953 I  AOBase: Pause 1
1952020-03-02 10:51:02.824720 I  AOBase: OutputAudioLoop: audio paused
1962020-03-02 10:51:02.833886 I  AOBase: Pause 1
1972020-03-02 10:51:02.853886 I  AOBase: Pause 1
1982020-03-02 10:51:02.873900 I  AOBase: Pause 1
1992020-03-02 10:51:02.893897 I  AOBase: Pause 1
2002020-03-02 10:51:02.913953 I  AOBase: Pause 1
2012020-03-02 10:51:02.916656 N  Player(0): Waited 358ms for video buffers UUUUUUUUULAAAAAAAAAAAAAAAAAAAAAP
2022020-03-02 10:51:02.933968 I  AOBase: Pause 1
2032020-03-02 10:51:02.953976 I  AOBase: Pause 1
2042020-03-02 10:51:02.980079 I  AOBase: Pause 0
2052020-03-02 10:51:02.984695 I  AOBase: OutputAudioLoop: Play Event
2062020-03-02 10:51:08.514516 I  AOBase: Pause 1
2072020-03-02 10:51:08.514553 I  AOBase: Pause 1
2082020-03-02 10:51:08.514581 I  AOBase: Pause 1
2092020-03-02 10:51:08.524020 I  AOBase: OutputAudioLoop: audio paused
2102020-03-02 10:51:08.534269 I  AOBase: Pause 0
2112020-03-02 10:51:08.595815 I  AOBase: OutputAudioLoop: Play Event
2122020-03-02 10:51:09.734887 I  AOBase: Pause 1
2132020-03-02 10:51:09.744249 I  AOBase: OutputAudioLoop: audio paused
2142020-03-02 10:51:09.754432 I  AOBase: Pause 1
2152020-03-02 10:51:09.774534 I  AOBase: Pause 0
2162020-03-02 10:51:09.815985 I  AOBase: OutputAudioLoop: Play Event
2172020-03-02 10:51:10.854501 I  AOBase: Pause 1
2182020-03-02 10:51:10.862000 I  AOBase: OutputAudioLoop: audio paused
2192020-03-02 10:51:10.874425 I  AOBase: Pause 1
2202020-03-02 10:51:10.914461 I  AOBase: Pause 0
2212020-03-02 10:51:10.970128 I  AOBase: OutputAudioLoop: Play Event
2222020-03-02 10:51:11.754740 I  AOBase: Pause 1
2232020-03-02 10:51:11.762362 I  AOBase: OutputAudioLoop: audio paused
2242020-03-02 10:51:11.774592 I  AOBase: Pause 1
2252020-03-02 10:51:11.794533 I  AOBase: Pause 0
2262020-03-02 10:51:11.870615 I  AOBase: OutputAudioLoop: Play Event
2272020-03-02 10:51:12.714824 I  AOBase: Pause 1
2282020-03-02 10:51:12.723491 I  AOBase: OutputAudioLoop: audio paused
2292020-03-02 10:51:12.754638 I  AOBase: Pause 1
2302020-03-02 10:51:12.774590 I  AOBase: Pause 0
2312020-03-02 10:51:12.831256 I  AOBase: OutputAudioLoop: Play Event
2322020-03-02 10:51:13.734918 I  AOBase: Pause 1
2332020-03-02 10:51:13.744645 I  AOBase: OutputAudioLoop: audio paused
2342020-03-02 10:51:13.774697 I  AOBase: Pause 1
2352020-03-02 10:51:13.794644 I  AOBase: Pause 0
2362020-03-02 10:51:13.947017 I  AOBase: OutputAudioLoop: Play Event
2372020-03-02 10:51:14.714729 I  AOBase: Pause 1
2382020-03-02 10:51:14.719412 I  AOBase: OutputAudioLoop: audio paused
2392020-03-02 10:51:14.734915 I  AOBase: Pause 1
2402020-03-02 10:51:14.774932 I  AOBase: Pause 0
2412020-03-02 10:51:14.828224 I  AOBase: OutputAudioLoop: Play Event
2422020-03-02 10:51:15.694911 I  AOBase: Pause 1
2432020-03-02 10:51:15.701131 I  AOBase: OutputAudioLoop: audio paused
2442020-03-02 10:51:15.734896 I  AOBase: Pause 1
2452020-03-02 10:51:15.734955 I  AOBase: Pause 0
2462020-03-02 10:51:15.779169 I  AOBase: OutputAudioLoop: Play Event
2472020-03-02 10:51:16.614817 I  AOBase: Pause 1
2482020-03-02 10:51:16.618376 I  AOBase: OutputAudioLoop: audio paused
2492020-03-02 10:51:16.634832 I  AOBase: Pause 1
2502020-03-02 10:51:16.654830 I  AOBase: Pause 0
2512020-03-02 10:51:16.833983 I  AOBase: OutputAudioLoop: Play Event
2522020-03-02 10:51:17.814877 I  AOBase: Pause 1
2532020-03-02 10:51:17.818034 I  AOBase: OutputAudioLoop: audio paused
2542020-03-02 10:51:17.834854 I  AOBase: Pause 1
2552020-03-02 10:51:17.854870 I  AOBase: Pause 0
2562020-03-02 10:51:17.925836 I  AOBase: OutputAudioLoop: Play Event
2572020-03-02 10:51:19.435215 I  AOBase: Pause 1
2582020-03-02 10:51:19.435265 I  AOBase: Pause 1
2592020-03-02 10:51:19.440254 I  AOBase: OutputAudioLoop: audio paused
2602020-03-02 10:51:19.454978 I  AOBase: Pause 1
2612020-03-02 10:51:19.475185 I  AOBase: Pause 0
2622020-03-02 10:51:19.519553 I  AOBase: OutputAudioLoop: Play Event
2632020-03-02 10:51:20.835347 I  AOBase: Pause 1
2642020-03-02 10:51:20.840049 I  AOBase: OutputAudioLoop: audio paused
2652020-03-02 10:51:20.861811 I  AOBase: Pause 1
2662020-03-02 10:51:20.861872 I  AOBase: Pause 0
2672020-03-02 10:51:20.948193 I  AOBase: OutputAudioLoop: Play Event
2682020-03-02 10:51:21.835309 I  AOBase: Pause 1
2692020-03-02 10:51:21.841101 I  AOBase: OutputAudioLoop: audio paused
2702020-03-02 10:51:21.855074 I  AOBase: Pause 1
2712020-03-02 10:51:21.855114 I  AOBase: Pause 0
2722020-03-02 10:51:21.949223 I  AOBase: OutputAudioLoop: Play Event
2732020-03-02 10:51:22.895677 I  AOBase: Pause 1
2742020-03-02 10:51:22.895710 I  AOBase: Pause 0
2752020-03-02 10:51:22.904897 I  AOBase: OutputAudioLoop: Play Event
2762020-03-02 10:51:23.235255 I  AOBase: Pause 1
2772020-03-02 10:51:23.235306 I  AOBase: Pause 1
2782020-03-02 10:51:23.237527 I  AOBase: OutputAudioLoop: audio paused
2792020-03-02 10:51:23.255346 I  AOBase: Pause 1
2802020-03-02 10:51:23.255424 I  AOBase: Pause 0
2812020-03-02 10:51:23.403874 I  AOBase: OutputAudioLoop: Play Event
2822020-03-02 10:51:24.515388 I  AOBase: Pause 1
2832020-03-02 10:51:24.525398 I  AOBase: OutputAudioLoop: audio paused
2842020-03-02 10:51:24.535242 I  AOBase: Pause 1
2852020-03-02 10:51:24.555272 I  AOBase: Pause 0
2862020-03-02 10:51:24.593820 I  AOBase: OutputAudioLoop: Play Event
2872020-03-02 10:51:25.355707 I  AOBase: Pause 1
2882020-03-02 10:51:25.363747 I  AOBase: OutputAudioLoop: audio paused
2892020-03-02 10:51:25.415321 I  AOBase: Pause 1
2902020-03-02 10:51:25.436041 I  AOBase: Pause 0
2912020-03-02 10:51:25.466710 I  AOBase: OutputAudioLoop: Play Event
2922020-03-02 10:51:26.095460 I  AOBase: Pause 1
2932020-03-02 10:51:26.101796 I  AOBase: OutputAudioLoop: audio paused
2942020-03-02 10:51:26.115416 I  AOBase: Pause 1
2952020-03-02 10:51:26.174139 I  AOBase: OutputAudioLoop: audio paused
2962020-03-02 10:51:26.175297 I  AOBase: Pause 0
2972020-03-02 10:51:26.361625 I  AOBase: OutputAudioLoop: Play Event
2982020-03-02 10:51:27.295549 I  AOBase: Pause 1
2992020-03-02 10:51:27.304275 I  AOBase: OutputAudioLoop: audio paused
3002020-03-02 10:51:27.315386 I  AOBase: Pause 1
3012020-03-02 10:51:27.335413 I  AOBase: Pause 0
3022020-03-02 10:51:27.376538 I  AOBase: OutputAudioLoop: Play Event
3032020-03-02 10:51:27.656962 I  TV::HandleStateChange(): Attempting to change from WatchingLiveTV to None
3042020-03-02 10:51:27.683505 I  AOBase: Killing AudioOutputDSP
3052020-03-02 10:51:27.696136 I  AOBase: OutputAudioLoop: Stop Event
3062020-03-02 10:51:27.696144 I  AOBase: kickoffOutputAudioLoop exiting
3072020-03-02 10:51:28.446667 I  TV::HandleStateChange(): Changing from WatchingLiveTV to None
3082020-03-02 10:51:28.448463 I  TV::StartTV(): Exiting main playback loop.
3092020-03-02 10:51:28.448821 I  ScreenSaverX11Private: DPMS Reactivated 1
3102020-03-02 10:51:28.505881 N  Resuming idle timer