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:
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
******************************************** Xine Player log
05 12/25/08 17:36:13.210 [33;1m[ID: 1009] CHAPTER:0 POS:571760 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4680c950>
05 12/25/08 17:36:14.304 [33;1m[ID: 1009] CHAPTER:0 POS:572840 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4680c950>
05 12/25/08 17:36:15.410 [33;1m[ID: 1009] CHAPTER:0 POS:573960 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4680c950>
05 12/25/08 17:36:16.494 [33;1m[ID: 1009] CHAPTER:0 POS:575040 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4680c950>
05 12/25/08 17:36:32.928 [33;1mDisplayOSDText() : Attempting to display 0x 9:35[0m <0x4680c950>
05 12/25/08 17:36:29.253 [33;1mXine_Player::CMD_Pause_Media() with corresponding stream 0x82d770.[0m <0x41001950>
05 12/25/08 17:36:32.939 [33;1mXine_Stream::changePlaybackSpeed no running seekers found[0m <0x41001950>
05 12/25/08 17:36:33.149 [33;1mXine_Player::CMD_Change_Playback_Speed() with corresponding stream 0x82d770.[0m <0x41001950>
05 12/25/08 17:36:33.150 [33;1mXine_Stream::changePlaybackSpeed no running seekers found[0m <0x41001950>
05 12/25/08 17:36:34.009 [33;1m[ID: 1009] CHAPTER:0 POS:575640 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4680c950>
05 12/25/08 17:36:35.085 [33;1m[ID: 1009] CHAPTER:0 POS:575640 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4680c950>
05 12/25/08 17:36:35.329 [33;1mXine_Player::CMD_Stop_Media() with corresponding stream id 1009 0x82d770.[0m <0x41001950>
05 12/25/08 17:36:35.329 [33;1mXine_Stream::changePlaybackSpeed no running seekers found[0m <0x41001950>
05 12/25/08 17:36:35.367 [33;1mXine_Player::EVENT_Playback_Completed(streamID=1009)[0m <0x41001950>
05 12/25/08 17:36:17.082 [33;1mXine_Stream::changePlaybackSpeed no running seekers found[0m <0x4600b950>
05 12/25/08 17:36:56.641 [33;1mDisplayOSDText called on non-initialized stream - aborting command[0m <0x4600b950>
05 12/25/08 17:36:56.641 [33;1mReportTimecode called on non-initialized stream - aborting command[0m <0x4600b950>
05 12/25/08 17:36:56.641 [33;1mplaybackCompleted called on non-initialized stream - aborting command[0m <0x4600b950>
05 12/25/08 17:36:56.641 [33;1mXineStreamEventListener called on non-initialized stream - aborting command[0m <0x4600b950>
05 12/25/08 17:36:57.014 [33;1mClosed stream AV with ID=1009[0m <0x41001950>
05 12/25/08 17:36:57.015 [33;1mSocket::ReceiveData 0x6a3680 failed, bytes left 0 start: 50120000 1: 0 1b: 0 2: 0 2b: 0 m_Socket: 6 Command_Impl1 Dev #47[0m <0x41001950>
05 12/25/08 17:36:57.015 [33;1mSocket::ReceiveString2 ReceiveData failed m_Socket: -1 Command_Impl1 Dev #47[0m <0x41001950>
01 12/25/08 17:36:57.015 [31;1mReceive string failed in HandleRequestSocket 18:ReceiveData failed Command_Impl1 Dev #47[0m <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 :
******************************************** syslog na MDju in na Core-u nic posebnega
******************************************** Xine Player log
05 12/25/08 18:02:47.616 [33;1m[ID: 1001] CHAPTER:0 POS:1323160 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4600b950>
05 12/25/08 18:02:48.676 [33;1m[ID: 1001] CHAPTER:0 POS:1324200 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4600b950>
05 12/25/08 18:02:49.736 [33;1m[ID: 1001] CHAPTER:0 POS:1325280 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4600b950>
05 12/25/08 18:02:50.800 [33;1m[ID: 1001] CHAPTER:0 POS:1326320 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4600b950>
05 12/25/08 18:02:51.864 [33;1m[ID: 1001] CHAPTER:0 POS:1327400 TITLE:0 SUBTITLE:-1 AUDIO:-1 TOTAL:2966200 (seek 0) t.c. ctr 1 freq 1,[0m <0x4600b950>
05 12/25/08 18:02:52.258 [33;1mXine_Stream::changePlaybackSpeed no running seekers found[0m <0x4580a950>
05 12/25/08 18:02:52.365 [33;1mXine_Player::EVENT_Playback_Completed(streamID=1001)[0m <0x4580a950>
05 12/25/08 18:02:52.438 [33;1mDisplayOSDText() : Attempting to display 0x 22:07[0m <0x4600b950>
05 12/25/08 18:02:52.445 [33;1mXine_Player::CMD_Stop_Media() with corresponding stream id 1001 0x82d730.[0m <0x41001950>
05 12/25/08 18:02:52.445 [33;1mXine_Stream::changePlaybackSpeed no running seekers found[0m <0x41001950>
05 12/25/08 18:02:52.570 [33;1mXine_Player::EVENT_Playback_Completed(streamID=1001)[0m <0x41001950>
05 12/25/08 18:02:52.646 [33;1mXineStreamEventListener called on non-initialized stream - aborting command[0m <0x4580a950>
05 12/25/08 18:02:52.984 [33;1mClosed stream AV with ID=1001[0m <0x41001950>
== ATTEMPT FRESH START ==
******************************************** DCERouter log
07 12/25/08 18:02:51.236 Received Message from 639 ([36;1mBM_meter_electpow_00 / [0m) to -1000 ([36;1munknown / [0m), type 2 id 25 Event:[32;1mTemperature Changed[0m, 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 [33;1mHaveMoreInQueue: position 0, size: 1, result 0[0m <0x5d8d5b90>
05 12/25/08 18:02:52.344 [33;1mHaveMoreInQueue: position 0, size: 1, result 0[0m <0x5d8d5b90>
08 12/25/08 18:02:52.398 Received Message from 23 ([36;1mXine Plug-in / Living Room/Family Room[0m) to 47 ([36;1mXine Player / Home Theater[0m), type 1 id 38 Command:[35;1mStop Media[0m, 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 ([36;1mOnScreen Orbiter / Home Theater[0m) to 10 ([36;1mMedia Plug-in / Living Room/Family Room[0m), type 1 id 74 Command:[35;1mBind to Media Remote[0m, 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 [31;1mClimate_Plugin: EVENT_Temperature_Changed_CONST ![0m <0x6f0f8b90>
01 12/25/08 18:02:53.333 [31;1mdebug_stream_end Media_Plugin::StreamEnded ID 1001/0x9c62290 delete 1 auto resume 1 resume: [0m <0x5d8d5b90>
05 12/25/08 18:02:53.333 [33;1mMedia_Plugin::StreamEnded() no auto resume EK_Users=1 AND Description<>'START' AND FK_File=9887 AND IsAutoResume=1[0m <0x5d8d5b90>
******************************************** DCERouter log
07 12/25/08 18:02:51.236 Received Message from 639 ([36;1mBM_meter_electpow_00 / [0m) to -1000 ([36;1munknown / [0m), type 2 id 25 Event:[32;1mTemperature Changed[0m, 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 [33;1mHaveMoreInQueue: position 0, size: 1, result 0[0m <0x5d8d5b90>
05 12/25/08 18:02:52.344 [33;1mHaveMoreInQueue: position 0, size: 1, result 0[0m <0x5d8d5b90>
08 12/25/08 18:02:52.398 Received Message from 23 ([36;1mXine Plug-in / Living Room/Family Room[0m) to 47 ([36;1mXine Player / Home Theater[0m), type 1 id 38 Command:[35;1mStop Media[0m, 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 ([36;1mOnScreen Orbiter / Home Theater[0m) to 10 ([36;1mMedia Plug-in / Living Room/Family Room[0m), type 1 id 74 Command:[35;1mBind to Media Remote[0m, 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 [31;1mClimate_Plugin: EVENT_Temperature_Changed_CONST ![0m <0x6f0f8b90>
01 12/25/08 18:02:53.333 [31;1mdebug_stream_end Media_Plugin::StreamEnded ID 1001/0x9c62290 delete 1 auto resume 1 resume: [0m <0x5d8d5b90>
05 12/25/08 18:02:53.333 [33;1mMedia_Plugin::StreamEnded() no auto resume EK_Users=1 AND Description<>'START' AND FK_File=9887 AND IsAutoResume=1[0m <0x5d8d5b90>
07 12/25/08 18:02:53.912 Received Message from 639 ([36;1mBM_meter_electpow_00 / [0m) to -1000 ([36;1munknown / [0m), type 2 id 25 Event:[32;1mTemperature Changed[0m, 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 ([36;1mMedia Plug-in / Living Room/Family Room[0m) to -1001 ([36;1munknown / [0m), type 2 id 20 Event:[32;1mStopped Watching Media[0m, 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 ([36;1mMedia Plug-in / Living Room/Family Room[0m) to 44 ([36;1mOnScreen Orbiter / Home Theater[0m), type 1 id 242 Command:[35;1mSet Now Playing[0m, 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.