[Miscellaneous Android Development] awaldram:
From when I press play till I press

20:44:32 T:1092055040 DEBUG: OnKey: return (f00d) pressed, action is Select
20:44:32 T:1092055040 DEBUG: ExecuteXBMCAction : Translating PlayMedia($ESCINFO[Window.Property(LatestEpisode.2.Path)])
20:44:32 T:1092055040 DEBUG: ExecuteXBMCAction : To PlayMedia("smb://TARL/mythtv/recordings/XBMC/Dads Army/Dads Army.S01E6.Shooting Pains.ts")
20:44:32 T:1092055040 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
20:44:32 T:1092055040 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
20:44:32 T:1092055040 DEBUG: CPlayerCoreFactory::GetPlayers(smb://TARL/mythtv/recordings/XBMC/Dads Army/Dads Army.S01E6.Shooting Pains.ts)
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: myth/mms
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: ifo
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rm/rmvb
20:44:32 T:1092055040 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: udp
20:44:32 T:1092055040 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
20:44:32 T:1092055040 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
20:44:32 T:1092055040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
20:44:32 T:1092055040 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
20:44:32 T:1092055040 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
20:44:32 T:1092055040 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: AMLPlayer (4)
20:44:32 T:1092055040 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
20:44:32 T:1092055040 NOTICE: DVDPlayer: Opening: smb://TARL/mythtv/recordings/XBMC/Dads Army/Dads Army.S01E6.Shooting Pains.ts
20:44:32 T:1092055040 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
20:44:32 T:1092055040 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
20:44:32 T:1092055040 DEBUG: LinuxRendererGL: Cleaning up GL resources
20:44:32 T:1092055040 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swscale-2-arm.so)
20:44:32 T:1092055040 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swscale-2-arm.so
20:44:32 T:1370477664 NOTICE: Thread CDVDPlayer start, auto delete: false
20:44:32 T:1370477664 NOTICE: Creating InputStream
20:44:32 T:1370477664 DEBUG: CSmbFile::eek:pen - opened mythtv/recordings/XBMC/Dads Army/Dads Army.S01E6.Shooting Pains.ts, fd=10000
20:44:32 T:1370477664 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
20:44:32 T:1370477664 DEBUG: OpenDir - Using authentication url smb://TARL/mythtv/recordings/XBMC/Dads%20Army
20:44:32 T:1370477664 DEBUG: ScanForExternalSubtitles: END (total time: 55 ms)
20:44:32 T:1370477664 NOTICE: Creating Demuxer
20:44:32 T:1370477664 DEBUG: Open - probing detected format [mpegts]
20:44:32 T:1370477664 DEBUG: Open - avformat_find_stream_info starting
20:44:32 T:1092055040 DEBUG: ------ Window Init (DialogBusy.xml) ------
20:44:32 T:1092055040 DEBUG: Window DialogBusy.xml was already loaded
20:44:32 T:1092055040 DEBUG: Alloc resources: 0.02m
20:44:32 T:1092055040 ERROR: convert_checked iconv_open() failed from UTF-8 to CP1252, errno=22(Invalid argument)
20:44:33 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:33 T:1370477664 INFO: ffmpeg[51AFD460]: [aac_latm] audio config changed
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] mmco: unref short failure
20:44:33 T:1370477664 ERROR: Previous line repeats 3 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:33 T:1092055040 ERROR: Previous line repeats 4 times.
20:44:33 T:1092055040 INFO: CheckIdle - Closing session to http://thetvdb.com (easy=0x19cd620, multi=0x19eb1a0)
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:33 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:33 T:1370477664 ERROR: Previous line repeats 2 times.
20:44:33 T:1370477664 DEBUG: ffmpeg[51AFD460]: [h264] Increasing reorder buffer to 1
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:33 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:33 T:1370477664 ERROR: Previous line repeats 2 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:33 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:33 T:1370477664 ERROR: Previous line repeats 2 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:33 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:33 T:1370477664 ERROR: Previous line repeats 2 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:33 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:33 T:1370477664 ERROR: Previous line repeats 2 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:33 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:33 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:33 T:1092055040 ERROR: Previous line repeats 2 times.
20:44:33 T:1092055040 ERROR: convert_checked iconv_open() failed from UTF-8 to CP1252, errno=22(Invalid argument)
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 DEBUG: ffmpeg[51AFD460]: [h264] Increasing reorder buffer to 2
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] reference picture missing during reorder
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] Missing reference picture
20:44:34 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:34 T:1370477664 DEBUG: ffmpeg[51AFD460]: [h264] Increasing reorder buffer to 3
20:44:34 T:1370477664 ERROR: ffmpeg[51AFD460]: [h264] mmco: unref short failure
20:44:34 T:1092055040 ERROR: Previous line repeats 1 times.
20:44:34 T:1092055040 ERROR: convert_checked iconv_open() failed from UTF-8 to CP1252, errno=22(Invalid argument)
20:44:35 T:1370477664 ERROR: Previous line repeats 1 times.
20:44:35 T:1370477664 INFO: ffmpeg[51AFD460]: [aac_latm] audio config changed
20:44:36 T:1092055040 ERROR: convert_checked iconv_open() failed from UTF-8 to CP1252, errno=22(Invalid argument)
20:44:38 T:1343300704 ERROR: Previous line repeats 11 times.
20:44:38 T:1343300704 ERROR: AddOnLog: MythTV cmyth PVR Client: Process Failed to read file: local: /root/.xbmc/userdata/addon_data/pvr.mythtv.cmyth/cache/channels/s4c.jpg, remote: http://www.lyngsat-logo.com/logo/tv/ss/s4c.jpg, type:
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [mpegts] decoding for stream 4 failed
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [mpegts] Could not find codec parameters (Unknown: none ([11][0][0][0] / 0x000B))
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [mpegts] decoding for stream 5 failed
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [mpegts] Could not find codec parameters (Unknown: none ([11][0][0][0] / 0x000B))
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [mpegts] decoding for stream 6 failed
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [mpegts] Could not find codec parameters (Unknown: none ([5][0][0][0] / 0x0005))
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [mpegts] decoding for stream 7 failed
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [mpegts] Could not find codec parameters (Unknown: none ([11][0][0][0] / 0x000B))
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [NULL] start time is not set in estimate_timings_from_pts
20:44:38 T:1370477664 DEBUG: Previous line repeats 3 times.
20:44:38 T:1370477664 DEBUG: ffmpeg[51AFD460]: [mpegts] PES packet size mismatch
20:44:38 T:1370477664 DEBUG: Open - av_find_stream_info finished
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Input #0, mpegts, from 'smb://TARL/mythtv/recordings/XBMC/Dads Army/Dads Army.S01E6.Shooting Pains.ts':
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Duration: 00:32:50.20, start: 27450.177556, bitrate: 5666 kb/s
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Program 1
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Stream #0:0[0x65]: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 50 fps, 50 tbr, 90k tbn, 50 tbc
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Stream #0:1[0x66](eng): Audio: aac_latm ([17][0][0][0] / 0x0011), 48000 Hz, stereo, s16
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Stream #0:2[0x6a](eng): Audio: aac_latm ([17][0][0][0] / 0x0011), 48000 Hz, 2 channels (FC), s16 (visual impaired)
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Stream #0:3[0x69](eng): Subtitle: dvb_subtitle ([6][0][0][0] / 0x0006)
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Stream #0:4[0x6e]: Unknown: none ([11][0][0][0] / 0x000B)
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Stream #0:5[0x6f]: Unknown: none ([11][0][0][0] / 0x000B)
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Stream #0:6[0x96]: Unknown: none ([5][0][0][0] / 0x0005)
20:44:38 T:1370477664 INFO: ffmpeg[51AFD460]: Stream #0:7[0x82]: Unknown: none ([11][0][0][0] / 0x000B)
20:44:38 T:1370477664 DEBUG: CDVDInputStreamFile::SetReadRate - Read rate set to 1016424 bytes per second
20:44:38 T:1370477664 NOTICE: Opening video stream: 0 source: 256
20:44:38 T:1370477664 NOTICE: Creating video codec with codec id: 28
20:44:38 T:1370477664 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no AMCodec:yes OpenMax:no VDPAU:no VAAPI:no
20:44:38 T:1370477664 INFO: Amlogic Video Decoder...
20:44:38 T:1370477664 DEBUG: FactoryCodec - Video: amcodec - Opening
20:44:38 T:1370477664 DEBUG: SECTION:LoadDLL(libamplayer.so)
20:44:38 T:1370477664 DEBUG: Loading: libamplayer.so
20:44:38 T:1370477664 INFO: DVDVideoCodecAmlogic: Opened Amlogic Codec
20:44:38 T:1370477664 DEBUG: FactoryCodec - Video: am-h264 - Opened
20:44:38 T:1370477664 NOTICE: Creating video thread
20:44:38 T:1427190880 NOTICE: Thread CDVDPlayerVideo start, auto delete: false
20:44:38 T:1370477664 NOTICE: Opening audio stream: 1 source: 256
20:44:38 T:1370477664 NOTICE: Finding audio codec for: 86068
20:44:38 T:1370477664 DEBUG: FactoryCodec - Audio: passthrough - Opening
20:44:38 T:1370477664 DEBUG: FactoryCodec - Audio: passthrough - Failed
20:44:38 T:1370477664 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
20:44:38 T:1370477664 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/swresample-0-arm.so)
20:44:38 T:1370477664 DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/swresample-0-arm.so
20:44:38 T:1427190880 NOTICE: running thread: video_thread
20:44:38 T:1427190880 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
20:44:38 T:1370477664 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
20:44:38 T:1370477664 NOTICE: Creating audio thread
20:44:38 T:1435579488 NOTICE: Thread CDVDPlayerAudio start, auto delete: false
20:44:38 T:1370477664 NOTICE: Opening Subtitle stream: 3 source: 256
20:44:38 T:1370477664 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening
20:44:38 T:1370477664 DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened
20:44:38 T:1370477664 DEBUG: ReadEditDecisionLists - Assuming 1080i interlaced content. Adjusted frames per second from 50.000 to 25.000
20:44:38 T:1370477664 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://TARL/mythtv/recordings/XBMC/Dads Army/Dads Army.S01E6.Shooting Pains.ts
20:44:38 T:1370477664 DEBUG: CDVDPlayer::SetCaching - caching state 3
20:44:38 T:1427190880 DEBUG: CAMLCodec::SetSpeed, speed(0)
20:44:38 T:1435579488 NOTICE: running thread: CDVDPlayerAudio::Process()
20:44:38 T:1435579488 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(0.000000, 1)
20:44:38 T:1435579488 NOTICE: Creating audio stream (codec id: 86068, channels: 2, sample rate: 48000, no pass-through)
20:44:38 T:1435579488 INFO: CSoftAE::MakeStream - AE_FMT_S16NE, 48000, FL,FR
20:44:38 T:1100444768 DEBUG: CSoftAE::Run - Sink restart flagged
20:44:38 T:1100444768 INFO: CSoftAE::LoadSettings - Stereo upmix is enabled
20:44:38 T:1100444768 INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
20:44:39 T:1427190880 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(961466.222223, 0)
20:44:39 T:1427190880 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
20:44:39 T:1427190880 DEBUG: CAMLCodec::eek:penDecoder
20:44:39 T:1427190880 DEBUG: CAMLCodec::eek:penDecoder video_rate exception
20:44:39 T:1427190880 DEBUG: codecid_to_vformat, id(28) -> vformat(2)
20:44:39 T:1427190880 DEBUG: codec_tag_to_vdec_type, codec_tag(27) -> vdec_type(0)
20:44:39 T:1427190880 DEBUG: CAMLCodec::eek:penDecoder hints.fpsrate(50), hints.fpsscale(1), hints.rfpsrate(50), hints.rfpsscale(1), video_rate(3844)
20:44:39 T:1427190880 DEBUG: CAMLCodec::eek:penDecoder hints.aspect(1.777778), video_ratio.num(1), video_ratio.den(1)
20:44:39 T:1427190880 DEBUG: CAMLCodec::eek:penDecoder hints.orientation(0), hints.forced_aspect(0), hints.extrasize(119)
20:44:39 T:1427190880 DEBUG: Unknown avcC version 0
20:44:39 T:1427190880 DEBUG: CAMLCodec::SetSpeed, speed(1000)
20:44:39 T:1450177632 NOTICE: Thread CAMLCodec start, auto delete: false
20:44:39 T:1450177632 DEBUG: CAMLCodec::Process Started
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 0, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 65536, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 131072, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 196608, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 262144, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 327680, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 393216, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 458752, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 524288, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 589824, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 655360, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 720896, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 786432, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 851968, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 917504, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 983040, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1048576, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1114112, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1179648, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1245184, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1310720, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1376256, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1441792, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1507328, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1572864, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1638400, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1703936, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1769472, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1835008, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1900544, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 1966080, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2031616, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2097152, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2162688, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2228224, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2293760, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2359296, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2424832, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2490368, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2555904, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2621440, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2686976, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2752512, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2818048, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2883584, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 2949120, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3014656, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3080192, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3145728, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3211264, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3276800, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3342336, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3407872, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3473408, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3538944, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3604480, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3670016, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3735552, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3801088, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3866624, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3932160, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 3997696, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: -1, len: 4063232, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: ERROR:write header failed!
20:44:39 T:1427190880 DEBUG: [write_av_packet]write header failed!
20:44:39 T:1100444768 INFO: CAESinkALSA::Initialize - Attempting to open device "default"
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 0, pkt->hdr->size: 27149136
20:44:39 T:1100444768 INFO: CAESinkALSA::Initialize - Opened device "default"
20:44:39 T:1100444768 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
20:44:39 T:1100444768 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
20:44:39 T:1100444768 DEBUG: CAESinkALSA::InitializeHW - Min: periodSize 16, periods 2, bufferSize 32
20:44:39 T:1100444768 DEBUG: CAESinkALSA::InitializeHW - Max: periodSize 2048, periods 1024, bufferSize 32768
20:44:39 T:1100444768 DEBUG: CAESinkALSA::InitializeHW - Req: periodSize 1024, periods 8, bufferSize 8192
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 65536, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 131072, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 196608, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 262144, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 327680, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 393216, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 458752, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 524288, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 589824, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 655360, pkt->hdr->size: 27149136
20:44:39 T:1100444768 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 1024, bufferSize 8192
20:44:39 T:1100444768 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 171 ms
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 720896, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 786432, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 851968, pkt->hdr->size: 27149136
20:44:39 T:1427190880 DEBUG: [write_header] write_bytes: 65536, len: 917504, pkt->hdr->size: 27149136
20:44:39 T:1100444768 DEBUG: CSoftAE::InternalOpenSink - ALSA Initialized: