[TM-NANO-3T COMBO] Unresponsive box after installing mgcamd & update 55

[abu baniaz]

May be worth checking all cables on motherboard are seated firmly.

 

[Rob van der Does]

Did you restart the box after enabling debug logs?
If the issue persists please disconnect any storage devices ((internal)HDD, USB-stick) and indeed LAN-cable as well.

 

[chris16v]

right then, i enabled debugging and rebooted the box, and waited. so some time in the night it locked up... yippee..
but after powering the box back up there is only one log in home/root/logs and it looks to me that the log data prior to the lockup has been overwritten because the only log data i have in the /logs folder is pretty short... posted below:

(out of interest, looking at the log name, if after a reboot the log file starts again with the same time/date stamp, i am guessing this is a problem?)
log name Enigma2-01-01-1970_1-00-16.log

+ (1) Background File Eraser
+ (5) Tuxtxt
+ (8) graphics acceleration manager
+ (9) GFBDC
10800k video mem
- double buffering available!
7560kB available for acceleration surfaces.
resolution: 720 x 576 x 32 (stride: 2880)
+ (9) gLCDDC
found OLED display!
lcd buffer 0x779ca8 8448 bytes, stride 132
LCD resolution: 132 x 64 x 8 (stride: 132)
+ (9) Font Render Class
[FONT] initializing lib...
[FONT] loading fonts...
PYTHONPATH: /usr/lib/enigma2/python
[FONT] Intializing font cache, using max. 4MB...
+ (10) gRC
RC thread created successfully
+ (15) eWindowStyleManager
+ (20) DVB-CI UI
+ (20) UHF Modulator
couldnt open /dev/rfmod0!!!!
+ (20) RC Input layer
+ (20) misc options
+ (20) AVSwitch Driver
+ (21) input device driver
Input device "FORCE1-PLUS remote control (native)" is a remotecontrol
Found 1 input devices.
+ (22) Hdmi CEC driver
eHdmiCEC: detected physical address change: 1000 --> 4000
eHdmiCEC: send message 84 40 00 03
+ (30) eActionMap
+ (35) CI Slots
scanning for common interfaces..
cannot open /proc/stb/tsmux/input2
eDVBCIInterfaces->setInputSource(0, 0)
eDVBCIInterfaces->setInputSource(1, 1)
done, found 1 common interface slots
+ (35) CA handler
+ (40) eServiceCenter
settings instance.
+ (41) eServiceFactoryDVD
+ (41) eServiceFactoryHDMI
+ (41) eServiceFactoryM2TS
+ (41) eServiceFactoryMP3
+ (41) eServiceFactoryFS
+ (41) eServiceFactoryDVB
+ (41) Stream server
reached rl 70
---- opening lame channel db
reading services (version 4)
loaded 130 channels/transponders and 1120 services
scanning for frontends..
opening frontend 0
close frontend 0
opening frontend 1
close frontend 1
opening frontend 2
close frontend 2
found 1 adapter, 3 frontends(3 sim) and 9 demux, boxtype 4
RTC not ready... wait for transponder time
[EPGC] Initialized EPGCache (wait for setCacheFile call now)
Loading spinners...
[ePNG] couldn't open /usr/share/enigma2/spinner/wait20.png
found 19 spinner!
setIoPrio best-effort level 3 ok
couldn't open /proc/stb/misc/12V_output
[Harddisk] enumerating block devices...
[Harddisk] enumerating network mounts...
Reading satellites.xml
main thread is non-idle! display spinner!
Reading cables.xml
Reading terrestrial.xml
removing internal link on frontend id 1
sec config cleared
setSlotInfo for dvb frontend 0 to slotid 0, descr BCM7356 (internal), need rotorworkaround No, enabled Yes, DVB-S2 Yes
setSlotInfo for dvb frontend 1 to slotid 1, descr BCM7356 (internal), need rotorworkaround No, enabled Yes, DVB-S2 Yes
setSlotInfo for dvb frontend 2 to slotid 2, descr Panasonic SP9680PVB (external), need rotorworkaround No, enabled Yes, DVB-S2 No
slot: 0 configmode: simple
diseqcmode: diseqc_a_b_c_d
slot: 1 configmode: simple
diseqcmode: single
sec config completed
[Time By]: Transponder
[EPGC] time updated.. but cache file not set yet.. dont start epg!!
[Ipkg] Added to OPKG destinations: /
[FONT] adding font /usr/share/fonts/Roboto-Bold.ttf...executing main
OK (Regular)
[FONT] adding font /usr/share/fonts/Roboto-BoldItalic.ttf...OK (Italic)
[FONT] adding font /usr/share/fonts/Roboto-Black.ttf...OK (Bold)
[FONT] adding font /usr/share/fonts/Roboto-BlackItalic.ttf...OK (Boldit)
[FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (Subtitlefont)
[FONT] adding font /usr/share/fonts/lcd.ttf...OK (LCD)
[FONT] adding font /usr/share/fonts/ae_AlMateen.ttf...OK (Replacement)
[FONT] adding font /usr/share/fonts/tuxtxt.ttf...OK (Console)
- double buffering available!
3600kB available for acceleration surfaces.
resolution: 1280 x 720 x 32 (stride: 5120)
[SKIN] loading include: /usr/share/enigma2/ViX-Common/CommonSkin.xml
[FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (Subs)
[FONT] adding font /usr/share/fonts/nmsbd.ttf...OK (FdLcD)
[iInputDevices] getInputDevices <ERROR: ioctl(EVIOCGNAME): [Errno 25] Inappropriate ioctl for device >
Activating keymap: Default (US)
Activating language English (UK)
[VideoMode] setting aspect: 16:9
[VideoMode] setting wss: auto
[VideoMode] setting policy: panscan
[VideoMode] setting policy2: letterbox
[VideoMode] setting scaler_sharpness to: 0000000D
[VideoMode] setMode - port: HDMI, mode: 1080p, rate: multi
[EPGC Load] Poller disabled.
[EPGC Save] Poller disabled.
[EPGC] setCacheFile read/write epg data from/to '/etc/enigma2/epg.dat'
setLCDBrightness 25
setLCDBrightness 127
setLCDScrollspeed 150
setLCDRepeat 3
setLCDMode 1
Setting OSD position: 27 667 22 534
Setting OSD alpha: 255
Setting 3D mode: auto
Setting 3D depth: 50
[EPGC Save] Poller disabled.
[EPGC Load] Poller disabled.
FIXME: request for unknown slot
FIXME: request for unknown slot
FIXME: request for unknown slot
Plugin Extensions/WebInterface failed to load: No module named plugin
[XMLTVImport] autostart (0) occured at 1413443626.76
[AutoTimer] Auto Poll Enabled
[SoftcamManager] AutoStart Enabled
[CI_Assignment] activating ci configs:
[CI_Activate] FOUND 1 CI Slots
[CI_Activate_Config_CI0] no config file found
[CI_Activate_Config_CI0] error parsing xml...
starting hotplug handler
[CrossEPG_Auto] AutoStart Enabled
[CrossEPG_Auto] Schedule Disabled at Thu 16 Oct 2014 08:13:46 IST
[ImageManager] AutoStart Enabled
[ImageManager] Backup Schedule Disabled at (now=Thu 16 Oct 2014 08:13:46 IST)
[BackupManager] AutoStart Enabled
[BackupManager] Backup Schedule Disabled at (now=Thu 16 Oct 2014 08:13:46 IST)
[AutoBouquetsMaker] AutoStart Enabled
[AutoBouquetsMaker] AutoBouquetsMaker Schedule Disabled at Thu 16 Oct 2014 08:13:46 IST
[XMLTVImport] autostart (0) occured at 1413443626.91
[XMLTVImport] WakeUpTime now set to -1 (now=1413443626)
warning, skin is missing element state in <class 'Screens.PVRState.TimeshiftState'>
RemovePopup, id = ZapError
[Skin] Attribute not implemented: alphatest value: blend
setValue 100
Setvolume: 100 100 (raw)
Setvolume: 0 0 (-1db)
Setvolume: 100 100 (raw)
Setvolume: 0 0 (-1db)
[LogManager] Trim Poll Started
[LogManager] Trash Poll Started
[LogManager] probing folders
[LogManager] found following log's: ['/home/root/logs']
[LogManager] looking in: /home/root/logs
[LogManager] /home/root/logs: bytesToRemove -13601739
[NTP]: setting E2 time: 1413443628.96
[EPGC] time updated.. start EPG Mainloop
playing 1:0:1:517:7DB:2:11A0000:0:0:0:
[EPGC] 339 events read from /etc/enigma2/epg.dat
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
RemovePopup, id = ZapError
allocate channel.. 07db:0002
opening frontend 0
(0)tune
RotorCmd ffffffff, lastRotorCmd ffffffff
prepare_sat System 0 Freq 11914500 Pol 0 SR 27500000 INV 2 FEC 2 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0
tuning to 1314 mhz
OURSTATE: tuning
allocate Channel: res 0
[eDVBCIInterfaces] addPMTHandler 1:0:1:517:7DB:2:11A0000:0:0:0:
allocate demux
CI Slot 0: reset requested
ci flush
ci inserted in slot 0
poll: unhandled POLLERR/HUP/NVAL for fd 31(16)
poll: unhandled POLLERR/HUP/NVAL for fd 35(16)
job Components.Task.Job name=LogManager #tasks=1 completed with [] in None
[SEC] set static current limiting
0.0.0.0
192.168
ER plugin not found
[OpenWebif] no plugins to load
[OpenWebif] started on 80
executing opkg --add-dest /:/ update
[OpenWebif] started on 443
poll: unhandled POLLERR/HUP/NVAL for fd 34(16)
job Components.Task.Job name=OnlineVersionCheck #tasks=1 completed with [] in None
set sequence pos 3
set sequence pos 4
[SEC] setVoltage 2
[SEC] sleep 200ms
[SEC] invalidate current switch params
[SEC] sendDiseqc: e00000(DiSEqC reset)
[SEC] sleep 50ms
[SEC] sendDiseqc: e00003(DiSEqC peripherial power on)
[SEC] sleep 150ms
[SEC] sendDiseqc: e01038ff(?)
[SEC] sleep 50ms
set sequence pos 3
[SEC] setTone 1
[SEC] sleep 10ms
[SEC] update current switch params
[SEC] startTuneTimeout 5000
[SEC] setFrontend 1
setting frontend 0
(0)fe event: status 0, inversion off, m_tuning 1
(0)fe event: status 19, inversion off, m_tuning 2
OURSTATE: ok
[eDVBLocalTimerHandler] channel 0x1ea31b0 running
[eEPGCache] channel 0x1ea31b0 running
stop release channel timer
ok ... now we start!!
[EPGC] next update in 2 sec
RemovePopup, id = ZapError
[SEC] sleep 500ms
PATready
use pmtpid 0102 for service_id 0517
[Dish] tuning failed
eventNewProgramInfo 0 0
have 1 video stream(s) (0202), and 2 audio stream(s) (0287, 0282), and the pcr pid is 1ffe, and the text pid is 0244
allocate demux
decoder state: play, vpid=514, apid=647
DMX_SET_PES_FILTER(0x1ffe) - pcr - ok
DEMUX_START - pcr - ok
DMX_SET_PES_FILTER(0x287) - audio - ok
DEMUX_START - audio - ok
AUDIO_SET_BYPASS(1) - ok
AUDIO_PAUSE - ok
AUDIO_PLAY - ok
Video Device: /dev/dvb/adapter0/video0
demux device: /dev/dvb/adapter0/demux0
VIDEO_SET_STREAMTYPE 0 - ok
DMX_SET_PES_FILTER(0x202) - video - ok
DEMUX_START - video - ok
VIDEO_FREEZE - ok
VIDEO_PLAY - ok
DMX_SET_PES_FILTER(0x244) - ttx - ok
DEMUX_START - ttx - ok
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - [EPGC] start caching events(1413443632)
ok
AUDIO_CONTINUE - ok
AUDIO_CHANNEL_SELECT(0) - ok
disable teletext subtitles page ffffffffffffffff (und)
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
[eDVBCAService] new service 1:0:1:517:7DB:2:11A0000:0:0:0:
[eDVBCAService] add demux 0 to slot 0 service 1:0:1:517:7DB:2:11A0000:0:0:0:
[eDVBCIInterfaces] gotPMT
[SEC] set dynamic current limiting
executing opkg --add-dest /:/ list-upgradable
poll: unhandled POLLERR/HUP/NVAL for fd 35(16)
not enough diskspace for timeshift! (less than 1GB)
not enough diskspace for timeshift! (less than 1GB)
[OnlineVersionCheck] 57 Updates available
poll: unhandled POLLERR/HUP/NVAL for fd 70(16)
sdt update done!
[SoftcamManager] Couldn't find oscam running, Starting oscam
poll: unhandled POLLERR/HUP/NVAL for fd 68(16)
[eDVBLocalTimerHandler] dont have correction.. set Transponder Diff
[eDVBLocalTimerHandler] set RTC Time
[eDVBLocalTimerHandler] update RTC
[eDVBLocalTimerHandler] time update to 08:13:58
[eDVBLocalTimerHandler] m_time_difference is 0
poll: unhandled POLLERR/HUP/NVAL for fd 57(16)
[EPGC] abort non avail nownext reading
[EPGC] abort non avail schedule reading
[EPGC] abort non avail netmed schedule reading
[EPGC] abort non avail netmed schedule other reading
[EPGC] abort non avail FreeSat schedule_other reading
[EPGC] abort non avail viasat reading
VIDEO_GET_EVENT - ok
[VideoMode] setting aspect: 16:9
[VideoMode] setting wss: auto
[VideoMode] setting policy: panscan
[VideoMode] setting policy2: letterbox
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None

 

[Rob van der Does]

1- Please post logs as attachement, not as text. This is unworkable.
2- The log name shows your box has an issue getting the time. What is your time settings? Getting time happens once after a reboot.

 

[chris16v]

sorry Rob van der does, i couldnt find the Go advanced button as suggested by the Judge.
The log didnt seem too long in order to post its total contents.

My image is as default out of the box, nothing has been configured apart from oscam so if it looks like the time is an issue then i will take a look and see if i can edit this, and start the debugging process again.

Where is the go advanced button for posting/inserting files? thanks

 

[Rob van der Does]

'Go advanced' is right beneath the 'Post Quick Reply' button.

Regarding time: is an LNB connected? And is Internet available to your box?

 

[chris16v]

'Go advanced' is right beneath the 'Post Quick Reply' button.

Regarding time: is an LNB connected? And is Internet available to your box?

i can see why you might ask this, the answer to both is yes. Although the lnb is reached via a diseq switch but that shouldnt make a difference.

 

[chris16v]

ah, found it and now the log file is attached.
thanks

 

[Rob van der Does]

First of all the get-time issue should be taken care off.
Please restart Enigma (so not a reboot), and see if the new log gets the correct time.

 

[chris16v]

finally i have a log from the latest lockup

ok guys, i have a log that was live when the machine locked up.
After checking that the time from the lnb was right, i restarted enigma and could see the log being written to, so hopefully there are some clues in the attached log.

cheers

 

[judge]

Have you timeshift enabled with no HDD or USB attached?
Also, saving EPG to internal flash?

 

[chris16v]

normally i would have all these features set up and configured but as the box is a new purchase and with this lockup issue i havent sorted the HDD, timeshift, usb pen drive, epg etc yet until i resolve this.

The image is in a "default" state from flashed (apart from oscam softcam). So if the default image settings after a reflash have these features switched on then maybe there is an issue there? I cant check till i get home tonight.

Does the log suggest the features you have listed to be the root cause of the lockups? For the lock up tests i am not using the box, i am leaving it switched on and dormant.

 

[Rob van der Does]

The only (for me) clear issue I found is "not enough diskspace for timeshift! (less than 1GB)".
1- Did you enable Timeshift? If so, please disable.
2- What plugins have you installed, apart from Oscam?
3- What service settings do you use?
4- Did you restore settings?

 

[chris16v]

The only (for me) clear issue I found is "not enough diskspace for timeshift! (less than 1GB)".

1- Did you enable Timeshift? If so, please disable.
I havent touched timeshift settings at all since flashing the image, so whatever settings are set as the default in the image is what i have. But i dont yet have a usb or hdd mounted either. its bog standard default, no other devices plugged in. i cant confirm what those settings actually are but can post them tonight


2- What plugins have you installed, apart from Oscam?
Nothing at all. totally untouched and default apart from oscam.

3- What service settings do you use?
do u mean how are my tuners set up? ok her we go
Tuner A) simple, set to DiSEqC a/b/c/d. only port d is configured to 28e so that i can get out past my 4 port switch to a multi lnb. 9this configuration worked well with my vusolo.
Tuner B) not configured
Tuner C) Active ( i will need to check later for specific settings used but its working fine)

4- Did you restore settings?
no, defo not.

hope that helps

 

[DaMacFunkin]

As everybody says you need to make sure that time shift is disabled, it should be by default but I would still toggle it and save the setting, time shift can cause crashes in certain circumstances and your log is full of incomplete time shift requests.

 

[chris16v]

DaMacFunkin yes i agree it should be disabled by defaut and i can confirm if it is by defaul tonight. However the log file is littered with:
"not enough diskspace for timeshift! (less than 1GB)"
and when there are several of them in a row there is always the following error:
RemovePopup, id = ZapError

But i think this might be a red herring because if timeshift is the offender then why doesnt the box lock up sooner when the timeshift lines appear in the log?

 

[Rob van der Does]

Timeshift is disabled by default.
And as I said: apart from that no clue from the log.
With settings I actually meant your service settings (channel list); how did you obtain it?

 

[chris16v]

confirmed: timeshift is set to disabled

my services/bouquets have been created solely by the autobouquetsmaker, both 28e and uk cable provider. both appear to be correct and work fine.

so what do we think guys, dodgy reciever or flakey image? ( i can always download an earlier build revisions if anyone can confirm which one to download, 41,43,47,54,57?)
My day job is a windows regression tester, so i am happy to follow any suggestions, but my gut feeling is its the box especially if no one else is reporting similar problems.

 

[DaMacFunkin]

But did you toggle disabled to something else, save it then toggle it back to disabled, reason i ask is if i don't do this on a fresh flash with no settings restore, i can sometimes get a enigma2 reboot which i can trace to auto resolution, even though auto resolution is disabled, turning it on, saving settings, then turning it off again and saving solves the problem.

 

[chris16v]

But did you toggle disabled to something else, save it then toggle it back to disabled, reason i ask is if i don't do this on a fresh flash with no settings restore, i can sometimes get a enigma2 reboot which i can trace to auto resolution, even though auto resolution is disabled, turning it on, saving settings, then turning it off again and saving solves the problem.

thanks for the suggestion but thats news to me mate cos I have always done fresh flashes without settings restores. I was gonna say you lost me there because i have never known that to be a linked issue that i have ever seen. Personally i think if the image has timeshift set as disabled by default and i havent touched it then it shouldnt be an issue should it?. Has what you are describing been raised as a known bug cos i cant see many people knowing about what you have described.

Apart from DaMacFunkins idea (i will try it), do we have any other suggstions? older image,, or maybe send the box back as faulty?