Author Topic: Have just shown to my friends how my LMCE can freeze during avi movie play  (Read 3585 times)

bulek

  • Administrator
  • wants to work for LinuxMCE
  • *****
  • Posts: 909
  • Living with LMCE
    • View Profile
Hi,

I've gained a bunch of friends and we all agreed to watch some older funny movies. They all reside on Core (internal HDD), MD is diskless client reading over nfs. I got two freezes (and then have used my windows laptop to show the movie till the end), the first one seems to be nfs related.

HAve you got any similar or other problems with NFS ? Is it stable for you ? What could be the cause of so frequent freezes.... ?

1. it seems that nfs server dies for some time and then media player implementation is not so robust that it would handle nfs shortages more gracefully. MD hardlocked, so only reboot of the whole system could be applied:

Quote
Dec 25 17:36:25 moon42 kernel: [  424.247580] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:36:32 moon42 kernel: [  424.247601] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:36:49 moon42 kernel: [  424.252792] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:36:49 moon42 kernel: [  424.686114] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:36:49 moon42 kernel: [  424.993745] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:36:49 moon42 kernel: [  425.799462] nfs: server 192.168.0.1 OK
Dec 25 17:36:49 moon42 kernel: [  425.799511] nfs: server 192.168.0.1 OK
Dec 25 17:37:20 moon42 kernel: [  425.799591] nfs: server 192.168.0.1 OK
Dec 25 17:37:20 moon42 kernel: [  427.571458] nfs: server 192.168.0.1 OK
Dec 25 17:37:20 moon42 kernel: [  427.571714] nfs: server 192.168.0.1 OK
Dec 25 17:37:20 moon42 kernel: [  430.363649] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:37:20 moon42 kernel: [  432.134679] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:37:23 moon42 kernel: [  432.136812] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:37:23 moon42 kernel: [  434.504500] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:37:23 moon42 kernel: [  434.922565] nfs: server 192.168.0.1 OK
Dec 25 17:37:23 moon42 kernel: [  434.922988] nfs: server 192.168.0.1 OK
Dec 25 17:37:23 moon42 kernel: [  434.922594] nfs: server 192.168.0.1 OK
Dec 25 17:37:23 moon42 kernel: [  434.922676] nfs: server 192.168.0.1 OK
Dec 25 17:37:23 moon42 kernel: [  442.440270] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:37:23 moon42 kernel: [  443.719319] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:37:23 moon42 kernel: [  443.774946] nfs: server 192.168.0.1 not responding, still trying
Dec 25 17:37:23 moon42 kernel: [  444.361117] nfs: server 192.168.0.1 OK
Dec 25 17:37:23 moon42 kernel: [  444.361373] nfs: server 192.168.0.1 OK
Dec 25 17:37:23 moon42 kernel: [  444.362716] nfs: server 192.168.0.1 OK


Quote
******************************************** Xine Player log
05   12/25/08 17:36:13.210      [ID: 1009]  CHAPTER:0 POS:571760 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4680c950>
05   12/25/08 17:36:14.304      [ID: 1009]  CHAPTER:0 POS:572840 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4680c950>
05   12/25/08 17:36:15.410      [ID: 1009]  CHAPTER:0 POS:573960 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4680c950>
05   12/25/08 17:36:16.494      [ID: 1009]  CHAPTER:0 POS:575040 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4680c950>
05   12/25/08 17:36:32.928      DisplayOSDText() : Attempting to display 0x     9:35 <0x4680c950>
05   12/25/08 17:36:29.253      Xine_Player::CMD_Pause_Media() with corresponding stream 0x82d770. <0x41001950>
05   12/25/08 17:36:32.939      Xine_Stream::changePlaybackSpeed no running seekers found <0x41001950>
05   12/25/08 17:36:33.149      Xine_Player::CMD_Change_Playback_Speed() with corresponding stream 0x82d770. <0x41001950>
05   12/25/08 17:36:33.150      Xine_Stream::changePlaybackSpeed no running seekers found <0x41001950>
05   12/25/08 17:36:34.009      [ID: 1009]  CHAPTER:0 POS:575640 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4680c950>
05   12/25/08 17:36:35.085      [ID: 1009]  CHAPTER:0 POS:575640 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4680c950>
05   12/25/08 17:36:35.329      Xine_Player::CMD_Stop_Media() with corresponding stream id 1009 0x82d770. <0x41001950>
05   12/25/08 17:36:35.329      Xine_Stream::changePlaybackSpeed no running seekers found <0x41001950>
05   12/25/08 17:36:35.367      Xine_Player::EVENT_Playback_Completed(streamID=1009) <0x41001950>
05   12/25/08 17:36:17.082      Xine_Stream::changePlaybackSpeed no running seekers found <0x4600b950>
05   12/25/08 17:36:56.641      DisplayOSDText called on non-initialized stream - aborting command <0x4600b950>
05   12/25/08 17:36:56.641      ReportTimecode called on non-initialized stream - aborting command <0x4600b950>
05   12/25/08 17:36:56.641      playbackCompleted called on non-initialized stream - aborting command <0x4600b950>
05   12/25/08 17:36:56.641      XineStreamEventListener called on non-initialized stream - aborting command <0x4600b950>
05   12/25/08 17:36:57.014      Closed stream AV with ID=1009 <0x41001950>
05   12/25/08 17:36:57.015      Socket::ReceiveData 0x6a3680 failed, bytes left 0 start: 50120000 1: 0 1b: 0 2: 0 2b: 0 m_Socket: 6 Command_Impl1 Dev #47 <0x41001950>
05   12/25/08 17:36:57.015      Socket::ReceiveString2 ReceiveData failed m_Socket: -1 Command_Impl1 Dev #47 <0x41001950>
01   12/25/08 17:36:57.015      Receive string failed in HandleRequestSocket 18:ReceiveData failed Command_Impl1 Dev #47 <0x41001950>

2. next problem was just a momentarily freeze and then media was stopped. The main problem is cause at both situations, internal HDD disappears, so you cannot select back video that was playing :

Quote
******************************************** syslog na MDju in na Core-u nic posebnega
******************************************** Xine Player log

05   12/25/08 18:02:47.616      [ID: 1001]  CHAPTER:0 POS:1323160 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4600b950>
05   12/25/08 18:02:48.676      [ID: 1001]  CHAPTER:0 POS:1324200 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4600b950>
05   12/25/08 18:02:49.736      [ID: 1001]  CHAPTER:0 POS:1325280 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4600b950>
05   12/25/08 18:02:50.800      [ID: 1001]  CHAPTER:0 POS:1326320 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4600b950>
05   12/25/08 18:02:51.864      [ID: 1001]  CHAPTER:0 POS:1327400 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1, <0x4600b950>
05   12/25/08 18:02:52.258      Xine_Stream::changePlaybackSpeed no running seekers found <0x4580a950>
05   12/25/08 18:02:52.365      Xine_Player::EVENT_Playback_Completed(streamID=1001) <0x4580a950>
05   12/25/08 18:02:52.438      DisplayOSDText() : Attempting to display 0x     22:07 <0x4600b950>
05   12/25/08 18:02:52.445      Xine_Player::CMD_Stop_Media() with corresponding stream id 1001 0x82d730. <0x41001950>
05   12/25/08 18:02:52.445      Xine_Stream::changePlaybackSpeed no running seekers found <0x41001950>
05   12/25/08 18:02:52.570      Xine_Player::EVENT_Playback_Completed(streamID=1001) <0x41001950>
05   12/25/08 18:02:52.646      XineStreamEventListener called on non-initialized stream - aborting command <0x4580a950>
05   12/25/08 18:02:52.984      Closed stream AV with ID=1001 <0x41001950>
== ATTEMPT FRESH START ==

Quote
******************************************** DCERouter log
07   12/25/08 18:02:51.236      Received Message from 639 (BM_meter_electpow_00 / ) to -1000 (unknown / ), type 2 id 25 Event:Temperature Changed, retry none, parameters: <0x6f0f8b90>
07   12/25/08 18:02:51.236        Parameter 30(Value): 3166 <0x6f0f8b90>
05   12/25/08 18:02:52.344      HaveMoreInQueue: position 0, size: 1, result 0 <0x5d8d5b90>
05   12/25/08 18:02:52.344      HaveMoreInQueue: position 0, size: 1, result 0 <0x5d8d5b90>
08   12/25/08 18:02:52.398      Received Message from 23 (Xine Plug-in / Living Room/Family Room) to 47 (Xine Player / Home Theater), type 1 id 38 Command:Stop Media, retry none, parameters: <0x9eaeab90>
08   12/25/08 18:02:52.398        Parameter 41(StreamID): 1001 <0x9eaeab90>
08   12/25/08 18:02:52.399        Parameter 42(MediaPosition):  <0x9eaeab90>
08   12/25/08 18:02:52.575      Received Message from 44 (OnScreen Orbiter / Home Theater) to 10 (Media Plug-in / Living Room/Family Room), type 1 id 74 Command:Bind to Media Remote, retry none, parameters: <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 2(PK_Device):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 3(PK_DesignObj):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 8(On/Off): 0 <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 25(PK_Text):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 39(Options):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 45(PK_EntertainArea): 3 <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 63(PK_Text_Synopsis):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 159(PK_Screen):  <0x540c2b90>
07   12/25/08 18:02:53.180      Event #25 has no handlers <0x6f0f8b90>
01   12/25/08 18:02:53.180      Climate_Plugin: EVENT_Temperature_Changed_CONST ! <0x6f0f8b90>
01   12/25/08 18:02:53.333      debug_stream_end Media_Plugin::StreamEnded ID 1001/0x9c62290 delete 1 auto resume 1 resume:  <0x5d8d5b90>
05   12/25/08 18:02:53.333      Media_Plugin::StreamEnded() no auto resume EK_Users=1 AND Description<>'START' AND FK_File=9887 AND IsAutoResume=1 <0x5d8d5b90>

Quote
******************************************** DCERouter log
07   12/25/08 18:02:51.236      Received Message from 639 (BM_meter_electpow_00 / ) to -1000 (unknown / ), type 2 id 25 Event:Temperature Changed, retry none, parameters: <0x6f0f8b90>
07   12/25/08 18:02:51.236        Parameter 30(Value): 3166 <0x6f0f8b90>
05   12/25/08 18:02:52.344      HaveMoreInQueue: position 0, size: 1, result 0 <0x5d8d5b90>
05   12/25/08 18:02:52.344      HaveMoreInQueue: position 0, size: 1, result 0 <0x5d8d5b90>
08   12/25/08 18:02:52.398      Received Message from 23 (Xine Plug-in / Living Room/Family Room) to 47 (Xine Player / Home Theater), type 1 id 38 Command:Stop Media, retry none, parameters: <0x9eaeab90>
08   12/25/08 18:02:52.398        Parameter 41(StreamID): 1001 <0x9eaeab90>
08   12/25/08 18:02:52.399        Parameter 42(MediaPosition):  <0x9eaeab90>
08   12/25/08 18:02:52.575      Received Message from 44 (OnScreen Orbiter / Home Theater) to 10 (Media Plug-in / Living Room/Family Room), type 1 id 74 Command:Bind to Media Remote, retry none, parameters: <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 2(PK_Device):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 3(PK_DesignObj):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 8(On/Off): 0 <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 25(PK_Text):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 39(Options):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 45(PK_EntertainArea): 3 <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 63(PK_Text_Synopsis):  <0x540c2b90>
08   12/25/08 18:02:52.575        Parameter 159(PK_Screen):  <0x540c2b90>
07   12/25/08 18:02:53.180      Event #25 has no handlers <0x6f0f8b90>
01   12/25/08 18:02:53.180      Climate_Plugin: EVENT_Temperature_Changed_CONST ! <0x6f0f8b90>
01   12/25/08 18:02:53.333      debug_stream_end Media_Plugin::StreamEnded ID 1001/0x9c62290 delete 1 auto resume 1 resume:  <0x5d8d5b90>
05   12/25/08 18:02:53.333      Media_Plugin::StreamEnded() no auto resume EK_Users=1 AND Description<>'START' AND FK_File=9887 AND IsAutoResume=1 <0x5d8d5b90>
07   12/25/08 18:02:53.912      Received Message from 639 (BM_meter_electpow_00 / ) to -1000 (unknown / ), type 2 id 25 Event:Temperature Changed, retry none, parameters: <0x6f0f8b90>
07   12/25/08 18:02:53.912        Parameter 30(Value): 3325 <0x6f0f8b90>
06   12/25/08 18:02:53.913      Evaluated Event Handler: 8 to: 1 once per: 0 last fired 0 (time is 1230224573) <0xa9cf8b90>
07   12/25/08 18:02:53.913      Received Message from 10 (Media Plug-in / Living Room/Family Room) to -1001 (unknown / ), type 2 id 20 Event:Stopped Watching Media, retry none, parameters: <0xa9cf8b90>
07   12/25/08 18:02:53.913        Parameter 27(PK_Room): 1 <0xa9cf8b90>
08   12/25/08 18:02:53.950      Received Message from 10 (Media Plug-in / Living Room/Family Room) to 44 (OnScreen Orbiter / Home Theater), type 1 id 242 Command:Set Now Playing, retry none, parameters: <0xa9cf8b90>
08   12/25/08 18:02:53.951        Parameter 3(PK_DesignObj):  <0xa9cf8b90>
08   12/25/08 18:02:53.951        Parameter 5(Value To Assign):  <0xa9cf8b90>
08   12/25/08 18:02:53.951        Parameter 9(Text):  <0xa9cf8b90>
08   12/25/08 18:02:53.951        Parameter 29(PK_MediaType): 0 <0xa9cf8b90>
08   12/25/08 18:02:53.951        Parameter 41(StreamID): 0 <0xa9cf8b90>
08   12/25/08 18:02:53.951        Parameter 48(Value): 0 <0xa9cf8b90>
08   12/25/08 18:02:53.951        Parameter 50(Name):  <0xa9cf8b90>
08   12/25/08 18:02:53.951        Parameter 103(List PK Device):  <0xa9cf8b90>
08   12/25/08 18:02:53.951        Parameter 120(Retransmit): 0 <0xa9cf8b90>


Regards,

bulek.

Thanks in advance,

regards,

Bulek.

itspac

  • Veteran
  • ***
  • Posts: 136
    • View Profile
Re: Have just shown to my friends how my LMCE can freeze during avi movie play
« Reply #1 on: December 26, 2008, 05:18:35 pm »
I have had problems with my nfs shares and have since went back to smb. i didnt spend too much time investigating the problem so I couldnt tell you if it was exactly the same, but i would get some freezing too

Zaerc

  • Alumni
  • LinuxMCE God
  • *
  • Posts: 2256
  • Department of Redundancy Department.
    • View Profile
Re: Have just shown to my friends how my LMCE can freeze during avi movie play
« Reply #2 on: December 26, 2008, 07:33:41 pm »
This does not have anything to do with development, so please post in the appropriate section of the forum.
"Change is inevitable. Progress is optional."
-- Anonymous