Odroid n2 video crahes

2g4y1 · 17 August 2019 08:37

Hi guys,

i have the following issue when i play videofiles on my n2 with latest nightli/stable.
x264 & x265 are booth affected.
in the logfile i goth this warning:

    2019-08-17 10:20:21.487 T:3563004800   DEBUG: CVideoPlayer::ProcessVideoData size:508 dts:623.213 pts:623.255 dur:41.000ms, clock:637.401 level:90
2019-08-17 10:20:21.488 T:3563004800   DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2019-08-17 10:20:21.493 T:3385156480   DEBUG: Previous line repeats 2 times.
2019-08-17 10:20:21.493 T:3385156480   DEBUG: CDVDAudio::Pause - pausing audio stream
2019-08-17 10:20:21.493 T:3385156480   DEBUG: CDVDAudio::Resume - resume audio stream
2019-08-17 10:20:21.494 T:4039996288 WARNING: ActiveAE - large audio sync error: -21946.032097
2019-08-17 10:20:21.494 T:4039996288   DEBUG: ActiveAE - start sync of audio stream
2019-08-17 10:20:21.494 T:4039996288 WARNING: ActiveAE - large audio sync error: -21946.031930
2019-08-17 10:20:21.495 T:4039996288 WARNING: ActiveAE - large audio sync error: -21946.031431
2019-08-17 10:20:21.495 T:4039996288 WARNING: ActiveAE - large audio sync error: -21946.031348

when i get this error, the video stream stucks for 10-30 seconds and repeats after this, OR crash the entire CE. sometimes the issue appears after 2 minutes, sometimes after an hour.

i uploaded a full debug log here: https://drive.google.com/file/d/1N0h3KFL9ntFn7RuwOW-adFZYeYJnB1US/view?usp=sharing

thanks for helping
2g.

afl1 · 17 August 2019 10:15

It looks like USB power issue. You have connected 4 large USB disk and one wifi dongle.

[    3.982574@0] sd 0:0:0:0: [sda] 732566646 4096-byte logical blocks: (3.00 TB/2.73 TiB)
[    3.982857@0] sd 0:0:0:0: [sda] Write Protect is off
[    3.982861@0] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
[    3.983170@0] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    4.069119@0]  sda: sda1 sda2
[    4.070635@0] sd 0:0:0:0: [sda] Attached SCSI disk
[    4.276198@0] usb 1-1.3.4: New USB device found, idVendor=174c, idProduct=5106
[    4.276203@0] usb 1-1.3.4: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    4.276206@0] usb 1-1.3.4: Product: AS2105
[    4.276208@0] usb 1-1.3.4: Manufacturer: ASMedia
[    4.276210@0] usb 1-1.3.4: SerialNumber: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
[    4.277014@0] usb-storage 1-1.3.4:1.0: USB Mass Storage device detected
[    4.277237@0] scsi host3: usb-storage 1-1.3.4:1.0
[    4.651623@0] scsi 1:0:0:0: Direct-Access     Intenso  External USB 3.0 1402 PQ: 0 ANSI: 6
[    4.652853@0] sd 1:0:0:0: Attached scsi generic sg1 type 0
[    4.653139@1] sd 1:0:0:0: [sdb] 976754646 4096-byte logical blocks: (4.00 TB/3.64 TiB)
[    4.653441@1] sd 1:0:0:0: [sdb] Write Protect is off
[    4.653447@1] sd 1:0:0:0: [sdb] Mode Sense: 47 00 00 08
[    4.653735@1] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    4.687982@1]  sdb: sdb1
[    4.689281@1] sd 1:0:0:0: [sdb] Attached SCSI disk
[    4.879721@1] scsi 2:0:0:0: Direct-Access     asmedia  ASM1053          0    PQ: 0 ANSI: 6
[    4.884708@1] sd 2:0:0:0: Attached scsi generic sg2 type 0
[    5.058526@0] sd 2:0:0:0: [sdc] 732566646 4096-byte logical blocks: (3.00 TB/2.73 TiB)
[    5.058909@0] sd 2:0:0:0: [sdc] Write Protect is off
[    5.058913@0] sd 2:0:0:0: [sdc] Mode Sense: 43 00 00 00
[    5.059268@0] sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    5.135027@0]  sdc: sdc1 sdc2
[    5.136869@1] sd 2:0:0:0: [sdc] Attached SCSI disk
[    5.298794@1] scsi 3:0:0:0: Direct-Access     WDC WD20 EARX-00PASB0     51.0 PQ: 0 ANSI: 0
[    5.303825@0] sd 3:0:0:0: Attached scsi generic sg3 type 0
[    5.304288@1] sd 3:0:0:0: [sdd] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[    5.304663@1] sd 3:0:0:0: [sdd] Write Protect is off
[    5.304670@1] sd 3:0:0:0: [sdd] Mode Sense: 23 00 00 00
[    5.305022@1] sd 3:0:0:0: [sdd] No Caching mode page found
[    5.305027@1] sd 3:0:0:0: [sdd] Assuming drive cache: write through

Try to connect only single disk for test. For connecting >1 usb disks is recommended powered USB hub.

tomti · 17 August 2019 10:16

Hi 2g4y1, AFL1

similar issues for me and a friend…but to us it is mainly an issue with USB devices and 1080. I had no issue with 2160p (maybe by chance but it is weird). No issues with playback from smb (would confirm usb power issues ?)

According to us, this issue appeared with nightly from august the 15th maybe. Back to stable version, everything is fine unlike you ???

@afl1 : i only have 1 dongle wifi and only one drive (Samsung SSD in external usb3 case)…I’m having this devices for months. Never had any issues, even with nightly builds. Back to stable for now, no more issues.

Regds

TheCoolest · 17 August 2019 10:29

N2 has had some USB related problems, better check their forums about it.

tomti · 17 August 2019 10:31

Hi the coolest

according to me and a friend (both with N2s) this issue is new from these days (nightly builds). Back to stable version, no issues. I’ve been with nightly builds for months. No hardware changes, just one wifi dongle for the remote and one usb drive (ssd). Don’t know why, but unlike 1080, i was able to playback 2160p movies when having issues with 1080. I understand this does not make sense if it is only a power issue. No issues at all with stable version. Hope this helps.

Regds

2g4y1 · 17 August 2019 10:46

hi guys,

thanks for the quick reply.
i can try to disconnect 3 of th 4 hdds, but i think it will not change anything in the power consumation.
all my hdds are connected with a power cord, not over usb

@tomti
issue appears at least since 7 days for me.
i dont tested a older build then august 10th
will try to switch back to stable again

greetings 2g

tomti · 17 August 2019 10:48

2G4Y1 : you may be right as for the right date…anyway it is not long ago…

If you can, it would have been interesting (before switching back to stable version) to test 2160p while you’re sure to have issues with 1080p…Don’t know why but i had no issues with 2160p

2g4y1 · 17 August 2019 10:53

only have a 1080p display to test this.
but i think it shouldn´t be a problem to test it only if it stucks, alright?

tomti · 17 August 2019 10:57

i guess odroid will display 1080 even if it is 2160p…you should see if it is freezing or not

2g4y1 · 17 August 2019 10:58

ok, ty
i will try with some of these 4k hevc files
http://jell.yfish.us/

TheCoolest · 17 August 2019 11:00

Can you check with what build exactly this problem started?

2g4y1 · 17 August 2019 11:01

sure,
will report the first build, where i can found the issue

edit: is there a way to get older builds than from last day?
https://relkai.coreelec.org/?dir=archive

my latest downloaded tar archive is from yesterday, so i cant downgrade before yesterday

2g4y1 · 17 August 2019 11:28

x265 is affected too here.
cant reproduce on every play, but happend at first play.
looks like its buffered after this and dont happend.

after reboot same issue.
now i have a video from the issue.

btw @TheCoolest, is there a way to get older builds as tar for downgrading?

TheCoolest · 17 August 2019 11:44

http://coreelec.pokey.de/

2g4y1 · 17 August 2019 11:47

perfect, will try with this version. 20190803
if issue exists, will downgrade some days till issue isnt there anymore

tomti · 17 August 2019 11:54

we thought it was around august 14th…we’ll try to have a look on our side too

i’m sure i had no issue at the beginning of august…20190803 seems a bit too old to me

2g4y1 · 17 August 2019 12:00

this was the version i flashed initialy after i changed from stable:
CoreELEC-Amlogic-ng.arm-9.1-nightly_20190810-Odroid_N2.img.gz
and if i remember, the issue already exists here.

issue exists here too for me: CoreELEC (official): nightly_20190803 (Amlogic-ng.arm)

next test: CoreELEC-Amlogic-ng.arm-9.1-nightly_20190711.tar

tomti · 17 August 2019 12:06

maybe the issue is different for you having more usb devices than i have…i will try to report the version with which a friend and i had the issue…but for us it was these days

2g4y1 · 17 August 2019 12:53

i tried now with only 1 hdd connected. stable 9.0.3

2019-08-17 14:31:35.484 T:4064739344   ERROR: Window Translator: Can't find window videolibrary
2019-08-17 14:31:35.507 T:4064739344   ERROR: Control 1000 in window 13001 has been asked to focus, but it can't
2019-08-17 14:39:17.147 T:4064739344  NOTICE: UDev: Added /var/media/Anderes
2019-08-17 14:39:53.289 T:4064739344  NOTICE: VideoPlayer::OpenFile: /var/media/Anderes/Movies/Pacific Rim Uprising (2018).mkv
2019-08-17 14:39:53.292 T:3635397488  NOTICE: Creating InputStream
2019-08-17 14:39:53.383 T:3635397488  NOTICE: Creating Demuxer
2019-08-17 14:39:53.675 T:3635397488  NOTICE: Opening stream: 0 source: 256
2019-08-17 14:39:53.675 T:3635397488  NOTICE: CVideoPlayerVideo::OpenStream - Creating codec: 27
2019-08-17 14:39:53.677 T:3635397488  NOTICE: Creating video thread
2019-08-17 14:39:53.677 T:3502846832  NOTICE: running thread: video_thread
2019-08-17 14:39:53.677 T:3635397488  NOTICE: Opening stream: 1 source: 256
2019-08-17 14:39:53.677 T:3635397488  NOTICE: Finding audio codec for: 86020
2019-08-17 14:39:53.677 T:3635397488  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder dca
2019-08-17 14:39:53.677 T:3635397488  NOTICE: Creating audio thread
2019-08-17 14:39:53.678 T:3433034608  NOTICE: running thread: CVideoPlayerAudio::Process()
2019-08-17 14:39:53.678 T:3635397488  NOTICE: Opening stream: 3 source: 256
2019-08-17 14:39:53.693 T:3433034608  NOTICE: Creating audio stream (codec id: 86020, channels: 6, sample rate: 48000, no pass-through)
2019-08-17 14:39:53.789 T:4064739344  NOTICE: Whitelist search for: width: 1920, height: 800, fps: 23.976, 3D: false
2019-08-17 14:39:53.790 T:4064739344  NOTICE: Display resolution ADJUST : 1920x1080 @ 23.98 - Full Screen (35) (weight: 0.000)
2019-08-17 14:39:54.014 T:4064739344  NOTICE: VideoPlayer: OnLostDisplay received
2019-08-17 14:39:54.306 T:4064739344  NOTICE: VideoPlayer: OnResetDisplay received
2019-08-17 14:40:51.553 T:3815752560  NOTICE: ES: Client  from ::ffff:192.168.1.114 timed out
2019-08-17 14:47:30.183 T:3433034608  NOTICE: CVideoPlayerAudio::Process - stream stalled
2019-08-17 14:47:52.873 T:3635397488  NOTICE: CVideoPlayer::OnExit()
2019-08-17 14:47:52.873 T:3635397488  NOTICE: VideoPlayer: eof, waiting for queues to empty
2019-08-17 14:47:52.873 T:3635397488  NOTICE: Closing stream player 1
2019-08-17 14:47:52.873 T:3635397488  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
2019-08-17 14:47:52.873 T:3635397488  NOTICE: Waiting for audio thread to exit
2019-08-17 14:47:52.873 T:3433034608   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
2019-08-17 14:47:52.873 T:3433034608  NOTICE: thread end: CVideoPlayerAudio::OnExit()
2019-08-17 14:47:52.874 T:3635397488  NOTICE: Closing audio device
2019-08-17 14:47:52.874 T:4042257264 WARNING: ActiveAE - large audio sync error: -22507.372385
2019-08-17 14:47:52.875 T:3635397488  NOTICE: Deleting audio codec
2019-08-17 14:47:52.875 T:3635397488  NOTICE: Closing stream player 2
2019-08-17 14:47:52.875 T:3635397488  NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
2019-08-17 14:47:53.601 T:3635397488  NOTICE: waiting for video thread to exit
2019-08-17 14:47:53.601 T:3502846832   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
2019-08-17 14:47:53.601 T:3502846832  NOTICE: thread end: video_thread
2019-08-17 14:47:53.601 T:3635397488  NOTICE: deleting video codec
2019-08-17 14:47:53.623 T:3635397488  NOTICE: Closing stream player 3
2019-08-17 14:47:53.682 T:4064739344  NOTICE: CVideoPlayer::CloseFile()
2019-08-17 14:47:53.682 T:4064739344  NOTICE: VideoPlayer: waiting for threads to exit
2019-08-17 14:47:53.682 T:4064739344  NOTICE: VideoPlayer: finished waiting
2019-08-17 14:47:53.687 T:4064739344  NOTICE: VideoPlayer: OnLostDisplay received
2019-08-17 14:47:53.687 T:4064739344 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
2019-08-17 14:47:53.687 T:4064739344 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
2019-08-17 14:47:53.911 T:4064739344  NOTICE: VideoPlayer: OnResetDisplay received
2019-08-17 14:47:53.911 T:4064739344 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
2019-08-17 14:47:53.911 T:4064739344 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
2019-08-17 14:47:54.204 T:4064739344  NOTICE: CVideoPlayer::CloseFile()
2019-08-17 14:47:54.204 T:4064739344  NOTICE: VideoPlayer: waiting for threads to exit
2019-08-17 14:47:54.204 T:4064739344  NOTICE: VideoPlayer: finished waiting

################################################################################
# ... output of cat /storage/.kodi/temp/kodi.old.log
# CoreELEC release: Amlogic-ng.arm-9.0.3

same error as before:

2019-08-17 14:47:52.873 T:3433034608   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
2019-08-17 14:47:52.873 T:3433034608  NOTICE: thread end: CVideoPlayerAudio::OnExit()
2019-08-17 14:47:52.874 T:3635397488  NOTICE: Closing audio device
2019-08-17 14:47:52.874 T:4042257264 WARNING: ActiveAE - large audio sync error: -22507.372385

boot2k3 · 17 August 2019 13:14

dmesg | grep reset
I think you will see usb related event
Check odroid forum, there are some topics with related issue. I don’t know if they have some working workaround
I don’t have this issue on one HDD connected