2012-11-27 22:07:01.499902 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): OPT2 rbrpos: 7870946 sba: -33634 2012-11-27 22:07:01.501265 I AO: GetAudiotime audt=274 atc=384 mb=21120 sb=0 tb=21120 sr=48000 obpf=4 bpf=4 sf=1 2112000000 110 2012-11-27 22:07:01.501294 I WriteAudio: Preparing 12000 bytes (3000 frames) 2012-11-27 22:07:01.501309 I ALSA: WriteAudio: buffer underrun 2012-11-27 22:07:01.504248 I MythSocket(b8095a0:83): write -> 83 50 QUERY_FILETRANSFER 84[]:[]REQUEST_BLOCK[]:[]294912 2012-11-27 22:07:01.505395 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.515543 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.525825 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.535963 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.537420 N Player(1): Waited 1781ms for video buffers UUUuuuuLAAAAAAAAAAAAAAAAAAAAAAUP 2012-11-27 22:07:01.546141 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.556485 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.566610 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.576706 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.586849 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.596999 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.607165 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.617306 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.627452 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.637663 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.639266 N Player(1): Waited 1883ms for video buffers UUUuuuuLAAAAAAAAAAAAAAAAAAAAAAUP 2012-11-27 22:07:01.647810 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.657934 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.668270 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.678505 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.688594 I AO: audio waiting for buffer to fill: have 9120 want 12000 2012-11-27 22:07:01.690947 I MythSocket(b8095a0:83): read <- 83 6 294912 2012-11-27 22:07:01.691133 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): safe_read(...@12713984, 294912) -> 294912, took 187 ms (12.6166Mbps) 2012-11-27 22:07:01.691248 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(16788,SEEK_SET,unlocked) 2012-11-27 22:07:01.691261 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StopReads() 2012-11-27 22:07:01.691590 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StartReads() 2012-11-27 22:07:01.691608 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): rbrpos: 7903714 rbwpos: 13008896 readpos: 115190 internalreadpos: 5220372 2012-11-27 22:07:01.691622 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): internal_backbuf: 7903714 sba: 98402 2012-11-27 22:07:01.691640 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): OPT1 rbrpos: 7805312 rbwpos: 13008896 readpos: 16788 internalreadpos: 5220372 2012-11-27 22:07:01.691904 I AO: AddData frames=1152, bytes=4608, used=9121, free=3062879, timecode=408 needsupmix=0 2012-11-27 22:07:01.691925 I AO: SetAudiotime atc=408 tc=408 f=1152 pfu=0 pfs=0 2012-11-27 22:07:01.691938 I AFD: audio timecode 17 17 408 432 2012-11-27 22:07:01.693691 I AFD: video timecode 9 359 359 320 2012-11-27 22:07:01.693949 I AO: AddData frames=1152, bytes=4608, used=13729, free=3058271, timecode=432 needsupmix=0 2012-11-27 22:07:01.693970 I AO: SetAudiotime atc=432 tc=432 f=1152 pfu=0 pfs=0 2012-11-27 22:07:01.693984 I AFD: audio timecode 18 18 432 456 2012-11-27 22:07:01.694005 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(87776,SEEK_SET,unlocked) 2012-11-27 22:07:01.694016 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StopReads() 2012-11-27 22:07:01.694028 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StartReads() 2012-11-27 22:07:01.694046 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): rbrpos: 7838080 rbwpos: 13008896 readpos: 49556 internalreadpos: 5220372 2012-11-27 22:07:01.694062 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): OPT2 rbrpos: 7876300 sba: -38220 2012-11-27 22:07:01.698737 I AO: GetAudiotime audt=337 atc=432 mb=18336 sb=0 tb=18336 sr=48000 obpf=4 bpf=4 sf=1 1833600000 95 2012-11-27 22:07:01.698767 I WriteAudio: Preparing 12000 bytes (3000 frames) 2012-11-27 22:07:01.700252 I MythSocket(b8095a0:83): write -> 83 50 QUERY_FILETRANSFER 84[]:[]REQUEST_BLOCK[]:[]294912 2012-11-27 22:07:01.702163 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.712295 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.722518 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.732673 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.742797 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.742803 N Player(1): Waited 1986ms for video buffers UUUUuuuuLAAAAAAAAAAAAAAAAAAAAAUP 2012-11-27 22:07:01.752935 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.763054 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.773370 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.783469 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.793575 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.803755 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.813884 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.824086 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.834230 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.844395 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.846262 N Player(1): Waited 2090ms for video buffers UUUUuuuuLAAAAAAAAAAAAAAAAAAAAAUP 2012-11-27 22:07:01.854559 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.864724 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.874833 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.884969 I AO: audio waiting for buffer to fill: have 6336 want 12000 2012-11-27 22:07:01.887352 I MythSocket(b8095a0:83): read <- 83 6 294912 2012-11-27 22:07:01.887476 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): safe_read(...@13008896, 294912) -> 294912, took 187 ms (12.6166Mbps) 2012-11-27 22:07:01.887685 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(17556,SEEK_SET,unlocked) 2012-11-27 22:07:01.887698 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StopReads() 2012-11-27 22:07:01.887708 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StartReads() 2012-11-27 22:07:01.887726 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): rbrpos: 7909068 rbwpos: 13303808 readpos: 120544 internalreadpos: 5515284 2012-11-27 22:07:01.887741 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): internal_backbuf: 7909068 sba: 102988 2012-11-27 22:07:01.887757 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): OPT1 rbrpos: 7806080 rbwpos: 13303808 readpos: 17556 internalreadpos: 5515284 2012-11-27 22:07:01.888015 I AO: AddData frames=1152, bytes=4608, used=6337, free=3065663, timecode=456 needsupmix=0 2012-11-27 22:07:01.888035 I AO: SetAudiotime atc=456 tc=456 f=1152 pfu=0 pfs=0 2012-11-27 22:07:01.888049 I AFD: audio timecode 19 19 456 480 2012-11-27 22:07:01.888469 I AFD: video timecode 10 399 399 359 2012-11-27 22:07:01.888496 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(92660,SEEK_SET,unlocked) 2012-11-27 22:07:01.888508 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StopReads() 2012-11-27 22:07:01.888519 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StartReads() 2012-11-27 22:07:01.888538 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): rbrpos: 7838848 rbwpos: 13303808 readpos: 50324 internalreadpos: 5515284 2012-11-27 22:07:01.888553 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): OPT2 rbrpos: 7881184 sba: -42336 2012-11-27 22:07:01.892709 I MythSocket(b8095a0:83): write -> 83 50 QUERY_FILETRANSFER 84[]:[]REQUEST_BLOCK[]:[]294912 2012-11-27 22:07:01.895722 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.905878 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.915999 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.926156 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.936495 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.946657 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.951388 N Player(1): Waited 2195ms for video buffers UUUUUuuuuLAAAAAAAAAAAAAAAAAAAAUP 2012-11-27 22:07:01.956813 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.966958 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.977079 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.987185 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:01.997315 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:02.007483 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:02.017611 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:02.027788 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:02.037939 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:02.048071 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:02.055566 N Player(1): Waited 2299ms for video buffers UUUUUuuuuLAAAAAAAAAAAAAAAAAAAAUP 2012-11-27 22:07:02.058385 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:02.068521 I AO: audio waiting for buffer to fill: have 10944 want 12000 2012-11-27 22:07:02.076076 I MythSocket(b8095a0:83): read <- 83 6 294912 2012-11-27 22:07:02.076366 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): safe_read(...@13303808, 294912) -> 294912, took 184 ms (12.8223Mbps) 2012-11-27 22:07:02.076527 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(17940,SEEK_SET,unlocked) 2012-11-27 22:07:02.076549 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StopReads() 2012-11-27 22:07:02.076929 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StartReads() 2012-11-27 22:07:02.076965 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): rbrpos: 7913952 rbwpos: 13598720 readpos: 125428 internalreadpos: 5810196 2012-11-27 22:07:02.076990 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): internal_backbuf: 7913952 sba: 107488 2012-11-27 22:07:02.077020 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): OPT1 rbrpos: 7806464 rbwpos: 13598720 readpos: 17940 internalreadpos: 5810196 2012-11-27 22:07:02.077531 I AO: AddData frames=1152, bytes=4608, used=10945, free=3061055, timecode=480 needsupmix=0 2012-11-27 22:07:02.077567 I AO: SetAudiotime atc=480 tc=480 f=1152 pfu=0 pfs=0 2012-11-27 22:07:02.077592 I AFD: audio timecode 20 20 480 504 2012-11-27 22:07:02.079200 I AO: GetAudiotime audt=399 atc=480 mb=15552 sb=0 tb=15552 sr=48000 obpf=4 bpf=4 sf=1 1555200000 81 2012-11-27 22:07:02.079228 I WriteAudio: Preparing 12000 bytes (3000 frames) 2012-11-27 22:07:02.079238 I ALSA: WriteAudio: buffer underrun 2012-11-27 22:07:02.079447 I AFD: video timecode 11 440 440 399 2012-11-27 22:07:02.081596 I AO: AddData frames=1152, bytes=4608, used=15553, free=3056447, timecode=503 needsupmix=0 2012-11-27 22:07:02.081623 I AO: SetAudiotime atc=503 tc=503 f=1152 pfu=0 pfs=0 2012-11-27 22:07:02.081641 I AFD: audio timecode 21 21 503 527 2012-11-27 22:07:02.081679 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(96308,SEEK_SET,unlocked) 2012-11-27 22:07:02.081693 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StopReads() 2012-11-27 22:07:02.081705 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StartReads() 2012-11-27 22:07:02.081725 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): rbrpos: 7839232 rbwpos: 13598720 readpos: 50708 internalreadpos: 5810196 2012-11-27 22:07:02.081741 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): OPT2 rbrpos: 7884832 sba: -45600 2012-11-27 22:07:02.084210 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.086964 I MythSocket(b8095a0:83): write -> 83 50 QUERY_FILETRANSFER 84[]:[]REQUEST_BLOCK[]:[]294912 2012-11-27 22:07:02.094348 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.102398 I (0xffffffffacc0c7e8)::IncrRef() -> 2 2012-11-27 22:07:02.102423 I (0xffffffffacc0c7e8)::DecrRef() -> 1 2012-11-27 22:07:02.102440 I (0xffffffffacc18fb8)::IncrRef() -> 2 2012-11-27 22:07:02.102458 I (0xffffffffacc18fb8)::DecrRef() -> 1 2012-11-27 22:07:02.102480 I (0xffffffffacc272a0)::IncrRef() -> 2 2012-11-27 22:07:02.102497 I (0xffffffffacc272a0)::DecrRef() -> 1 2012-11-27 22:07:02.102514 I (0xffffffffacc267e0)::IncrRef() -> 2 2012-11-27 22:07:02.102531 I (0xffffffffacc267e0)::DecrRef() -> 1 2012-11-27 22:07:02.102548 I (0xffffffffacc16bd8)::IncrRef() -> 2 2012-11-27 22:07:02.102565 I (0xffffffffacc16bd8)::DecrRef() -> 1 2012-11-27 22:07:02.102581 I (0xffffffffacc26920)::IncrRef() -> 2 2012-11-27 22:07:02.102598 I (0xffffffffacc26920)::DecrRef() -> 1 2012-11-27 22:07:02.102615 I (0xffffffffacc16a28)::IncrRef() -> 2 2012-11-27 22:07:02.102632 I (0xffffffffacc16a28)::DecrRef() -> 1 2012-11-27 22:07:02.102649 I (0xffffffffacc16ac8)::IncrRef() -> 2 2012-11-27 22:07:02.102665 I (0xffffffffacc16ac8)::DecrRef() -> 1 2012-11-27 22:07:02.102685 I (0xffffffffacc00ee8)::IncrRef() -> 2 2012-11-27 22:07:02.102695 I (0xffffffffacc00ee8)::DecrRef() -> 1 2012-11-27 22:07:02.102704 I (0xffffffffacc1c108)::IncrRef() -> 2 2012-11-27 22:07:02.102714 I (0xffffffffacc1c108)::DecrRef() -> 1 2012-11-27 22:07:02.102724 I (0xffffffffacc1ae40)::IncrRef() -> 2 2012-11-27 22:07:02.102733 I (0xffffffffacc1ae40)::DecrRef() -> 1 2012-11-27 22:07:02.102742 I (0xffffffffacc012c8)::IncrRef() -> 2 2012-11-27 22:07:02.102752 I (0xffffffffacc012c8)::DecrRef() -> 1 2012-11-27 22:07:02.102761 I (0xffffffffacc16940)::IncrRef() -> 2 2012-11-27 22:07:02.102771 I (0xffffffffacc16940)::DecrRef() -> 1 2012-11-27 22:07:02.102780 I (0xffffffffacc24c28)::IncrRef() -> 2 2012-11-27 22:07:02.102790 I (0xffffffffacc24c28)::DecrRef() -> 1 2012-11-27 22:07:02.102799 I (0xffffffffacc26760)::IncrRef() -> 2 2012-11-27 22:07:02.102808 I (0xffffffffacc26760)::DecrRef() -> 1 2012-11-27 22:07:02.102817 I (0xffffffffacc2d8c0)::IncrRef() -> 2 2012-11-27 22:07:02.102826 I (0xffffffffacc2d8c0)::DecrRef() -> 1 2012-11-27 22:07:02.102835 I (0xffffffffacc2d6d8)::IncrRef() -> 2 2012-11-27 22:07:02.102844 I (0xffffffffacc2d6d8)::DecrRef() -> 1 2012-11-27 22:07:02.102853 I (0xffffffffacc31f08)::IncrRef() -> 2 2012-11-27 22:07:02.102862 I (0xffffffffacc31f08)::DecrRef() -> 1 2012-11-27 22:07:02.102871 I (0xffffffffacc21f90)::IncrRef() -> 2 2012-11-27 22:07:02.102880 I (0xffffffffacc21f90)::DecrRef() -> 1 2012-11-27 22:07:02.102889 I (0xffffffffacc2d6c0)::IncrRef() -> 2 2012-11-27 22:07:02.102898 I (0xffffffffacc2d6c0)::DecrRef() -> 1 2012-11-27 22:07:02.102907 I (0xffffffffacc3a2e0)::IncrRef() -> 2 2012-11-27 22:07:02.102916 I (0xffffffffacc3a2e0)::DecrRef() -> 1 2012-11-27 22:07:02.102925 I (0xffffffffacc33330)::IncrRef() -> 2 2012-11-27 22:07:02.102933 I (0xffffffffacc33330)::DecrRef() -> 1 2012-11-27 22:07:02.102942 I (0xffffffffacc27720)::IncrRef() -> 2 2012-11-27 22:07:02.102951 I (0xffffffffacc27720)::DecrRef() -> 1 2012-11-27 22:07:02.102960 I (0xffffffffacc00ea0)::IncrRef() -> 2 2012-11-27 22:07:02.102969 I (0xffffffffacc00ea0)::DecrRef() -> 1 2012-11-27 22:07:02.102981 I (0xffffffffacc157a0)::IncrRef() -> 2 2012-11-27 22:07:02.102991 I (0xffffffffacc157a0)::DecrRef() -> 1 2012-11-27 22:07:02.104494 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.114608 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.124736 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.134858 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.144957 I AO: audio waiting for buffer to fill: have 8160 want 12000 ^C2012-11-27 22:07:02.155106 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.159202 N Player(1): Waited 2403ms for video buffers UUUUUUuuuuLAAAAAAAAAAAAAAAAAAAUP 2012-11-27 22:07:02.165217 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.175351 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.186264 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.196376 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.206478 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.216572 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.226666 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.236762 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.241566 C Received Interruzione: Code 128, PID 0, UID 0, Value 0x5d0000005c 2012-11-27 22:07:02.241598 I TV: Exiting main playback loop. 2012-11-27 22:07:02.241609 I TV: StartTV -- process events 2 begin 2012-11-27 22:07:02.241643 I TV: StartTV -- process events 2 end 2012-11-27 22:07:02.241657 I TV::~TV() -- begin 2012-11-27 22:07:02.247024 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.253991 I TV::~TV() -- lock 2012-11-27 22:07:02.254037 I Player(1): StopPlaying - begin 2012-11-27 22:07:02.257175 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.267289 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.277400 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.287597 I AO: audio waiting for buffer to fill: have 8160 want 12000 2012-11-27 22:07:02.293261 I MythSocket(b8095a0:83): read <- 83 6 294912 2012-11-27 22:07:02.293331 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): safe_read(...@13598720, 294912) -> 294912, took 207 ms (11.3976Mbps) 2012-11-27 22:07:02.293473 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(50366,SEEK_SET,unlocked) 2012-11-27 22:07:02.293488 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StopReads() 2012-11-27 22:07:02.293499 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StartReads() 2012-11-27 22:07:02.293517 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): rbrpos: 7917600 rbwpos: 13893632 readpos: 129076 internalreadpos: 6105108 2012-11-27 22:07:02.293532 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): internal_backbuf: 7917600 sba: 78710 2012-11-27 22:07:02.293549 I FileRingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): Seek(): OPT1 rbrpos: 7838890 rbwpos: 13893632 readpos: 50366 internalreadpos: 6105108 2012-11-27 22:07:02.293816 I AO: AddData frames=1152, bytes=4608, used=8161, free=3063839, timecode=528 needsupmix=0 2012-11-27 22:07:02.293837 I AO: SetAudiotime atc=528 tc=528 f=1152 pfu=0 pfs=0 2012-11-27 22:07:02.293851 I AFD: audio timecode 22 22 528 552 2012-11-27 22:07:02.294999 I Player(1): Decoder thread exiting. 2012-11-27 22:07:02.295158 I Player(1): Exited decoder loop. 2012-11-27 22:07:02.295645 I (0xffffffffae09072c)::DecrRef() -> 1 2012-11-27 22:07:02.295726 I (0xffffffffa123141c)::DecrRef() -> 1 2012-11-27 22:07:02.295835 I (0xffffffffae064324)::DecrRef() -> 1 2012-11-27 22:07:02.295857 I (0xffffffffae0b0e14)::DecrRef() -> 1 2012-11-27 22:07:02.295875 I (0xffffffffa0e4871c)::DecrRef() -> 1 2012-11-27 22:07:02.295895 I (0xffffffffae082f8c)::DecrRef() -> 1 2012-11-27 22:07:02.295913 I (0xffffffffae0b0b7c)::DecrRef() -> 1 2012-11-27 22:07:02.295932 I (0xffffffffa118608c)::DecrRef() -> 1 2012-11-27 22:07:02.295964 I (0xffffffffad62adbc)::DecrRef() -> 1 2012-11-27 22:07:02.295982 I (0xffffffffa1185fd4)::DecrRef() -> 1 2012-11-27 22:07:02.296001 I (0xffffffffae03e834)::DecrRef() -> 1 2012-11-27 22:07:02.296019 I (0xffffffffae09e86c)::DecrRef() -> 1 2012-11-27 22:07:02.296038 I (0xffffffffad680c4c)::DecrRef() -> 1 2012-11-27 22:07:02.296056 I (0xffffffffad66918c)::DecrRef() -> 1 2012-11-27 22:07:02.296074 I (0xffffffffae0d65fc)::DecrRef() -> 1 2012-11-27 22:07:02.296092 I (0xffffffffae0f123c)::DecrRef() -> 1 2012-11-27 22:07:02.296110 I (0xffffffffa07869ac)::DecrRef() -> 1 2012-11-27 22:07:02.296131 I (0xffffffffa07399ec)::DecrRef() -> 1 2012-11-27 22:07:02.296153 I (0xffffffffa070e724)::DecrRef() -> 1 2012-11-27 22:07:02.296180 I (0xffffffffa10e230c)::DecrRef() -> 1 2012-11-27 22:07:02.296203 I (0xffffffffa127379c)::DecrRef() -> 1 2012-11-27 22:07:02.296221 I (0xffffffffa10b0214)::DecrRef() -> 1 2012-11-27 22:07:02.296241 I (0xffffffffa11f8a24)::DecrRef() -> 1 2012-11-27 22:07:02.296262 I (0xffffffffad67628c)::DecrRef() -> 1 2012-11-27 22:07:02.296282 I (0xffffffffad648e0c)::DecrRef() -> 1 2012-11-27 22:07:02.296308 I (0xffffffffa12da874)::DecrRef() -> 1 2012-11-27 22:07:02.296327 I (0xffffffffa119805c)::DecrRef() -> 1 2012-11-27 22:07:02.296346 I (0xffffffffae034c34)::DecrRef() -> 1 2012-11-27 22:07:02.296363 I (0xffffffffae0e3b14)::DecrRef() -> 1 2012-11-27 22:07:02.296380 I (0xffffffffae036194)::DecrRef() -> 1 2012-11-27 22:07:02.296397 I (0xffffffffae054f0c)::DecrRef() -> 1 2012-11-27 22:07:02.296413 I (0xffffffffa12da954)::DecrRef() -> 1 2012-11-27 22:07:02.296438 I (0xffffffffae065a3c)::DecrRef() -> 1 2012-11-27 22:07:02.296456 I (0xffffffffae090c54)::DecrRef() -> 1 2012-11-27 22:07:02.296476 I (0xffffffffad671db4)::DecrRef() -> 1 2012-11-27 22:07:02.296494 I (0xffffffffae04610c)::DecrRef() -> 1 2012-11-27 22:07:02.296557 I VideoBuffers::DiscardFrames(1): UUUUUUuuuuLAAAAAAAAAAAAAAAAAAAUP 2012-11-27 22:07:02.296608 I VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done 2012-11-27 22:07:02.297757 I AO: GetAudiotime audt=462 atc=528 mb=12768 sb=0 tb=12768 sr=48000 obpf=4 bpf=4 sf=1 1276800000 66 2012-11-27 22:07:02.297783 I WriteAudio: Preparing 12000 bytes (3000 frames) 2012-11-27 22:07:02.298492 I MythSocket(b8095a0:83): write -> 83 50 QUERY_FILETRANSFER 84[]:[]REQUEST_BLOCK[]:[]294912 2012-11-27 22:07:02.299001 I AO: audio waiting for buffer to fill: have 768 want 12000 2012-11-27 22:07:02.309122 I AO: audio waiting for buffer to fill: have 768 want 12000 2012-11-27 22:07:02.318447 I GLVid: Removing master filter 2012-11-27 22:07:02.318508 I GLVid: Removing resize filter 2012-11-27 22:07:02.318729 I (0x9fb77c0)::DecrRef() -> 1 2012-11-27 22:07:02.318853 I AO: Killing AudioOutputDSP 2012-11-27 22:07:02.319230 I AO: OutputAudioLoop: Stop Event 2012-11-27 22:07:02.319242 I AO: kickoffOutputAudioLoop exiting 2012-11-27 22:07:02.322679 I Player(1): StopPlaying - end 2012-11-27 22:07:02.482264 I MythSocket(b8095a0:83): read <- 83 6 294912 2012-11-27 22:07:02.482428 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): safe_read(...@13893632, 294912) -> 294912, took 184 ms (12.8223Mbps) 2012-11-27 22:07:02.482571 I MythSocket(b8095a0:83): write -> 83 30 QUERY_FILETRANSFER 84[]:[]DONE 2012-11-27 22:07:02.484534 I MythSocket(b8095a0:83): read <- 83 2 OK 2012-11-27 22:07:02.484570 I (0xb7e4a50)::DecrRef() -> 0 2012-11-27 22:07:02.484652 I (0xb8095c8)::DecrRef() -> 0 2012-11-27 22:07:02.484724 I RingBuf(myth://Videos@192.168.99.12:6543/Cartoni Animati/Peppa Pig/1x02 - Il signor dinosauro si e' perso.avi): StopReads() 2012-11-27 22:07:02.487566 I MythSocket(ffffffffacc188a0:78): read <- 78 93 BACKEND_MESSAGE[]:[]SYSTEM_EVENT CLIENT_DISCONNECTED HOSTNAME shi... 2012-11-27 22:07:02.487602 I MythEvent: SYSTEM_EVENT CLIENT_DISCONNECTED HOSTNAME shiryu SENDER storage 2012-11-27 22:07:02.491075 I TV::~TV() -- end 2012-11-27 22:07:02.491219 I MythSocket(ffffffffacc276c0:77): write -> 77 107 MESSAGE[]:[]SYSTEM_EVENT PLAY_STOPPED HOSTNAME shiryu CHANID 0 ST... 2012-11-27 22:07:02.493966 I MythSocket(ffffffffacc188a0:78): read <- 78 125 BACKEND_MESSAGE[]:[]SYSTEM_EVENT PLAY_STOPPED HOSTNAME shiryu CHA... 2012-11-27 22:07:02.494004 I MythEvent: SYSTEM_EVENT PLAY_STOPPED HOSTNAME shiryu CHANID 0 STARTTIME 2012-11-27T20:59:55Z SENDER shiryu 2012-11-27 22:07:02.494293 I MythSocket(ffffffffacc276c0:77): read <- 77 2 OK 2012-11-27 22:07:02.494410 I MythEvent: PLAYBACK_END shiryu 2012-11-27 22:07:02.494462 N Resuming idle timer 2012-11-27 22:07:02.494482 I TV: StartTV -- end 2012-11-27 22:07:02.496953 N Resuming idle timer 2012-11-27 22:07:02.497017 N Resuming idle timer 2012-11-27 22:07:02.589735 I Clearing Settings Cache for 'playmode'. 2012-11-27 22:07:02.596161 I Clearing Settings Cache for 'repeatmode'. 2012-11-27 22:07:02.603776 I Clearing Settings Cache for 'musicautoshowplayer'. 2012-11-27 22:07:02.603933 I Bonjour: De-registering service '_mythfrontend._tcp.' on 'Mythfrontend on shiryu' 2012-11-27 22:07:02.606892 I RAOP Device: Cleaning up. 2012-11-27 22:07:02.606909 I AirPlay: Cleaning up. 2012-11-27 22:07:02.606929 I Deleting UPnP client... 2012-11-27 22:07:02.607573 I UPnp::CleanUp() - disabling SSDP notifications 2012-11-27 22:07:02.607676 I UPnpNotifyTask::SendNotifyMsg : 239.255.255.250:1900 : upnp:rootdevice : uuid:c7f25f31-b8f5-4888-aab8-a21e9923596d::upnp:rootdevice 2012-11-27 22:07:02.625276 I UPnpNotifyTask::SendNotifyMsg : 239.255.255.250:1900 : uuid:c7f25f31-b8f5-4888-aab8-a21e9923596d : uuid:c7f25f31-b8f5-4888-aab8-a21e9923596d 2012-11-27 22:07:02.809402 I UPnpNotifyTask::SendNotifyMsg : 239.255.255.250:1900 : urn:schemas-upnp-org:device:MediaRenderer:1 : uuid:c7f25f31-b8f5-4888-aab8-a21e9923596d::urn:schemas-upnp-org:device:MediaRenderer:1