maemo.org - Talk

maemo.org - Talk (https://talk.maemo.org/index.php)
-   Nokia N900 (https://talk.maemo.org/forumdisplay.php?f=44)
-   -   spontaneous shutdowns - why? (https://talk.maemo.org/showthread.php?t=73367)

cct 2011-05-23 19:50

spontaneous shutdowns - why?
 
Still in the middle of the process of getting closer to my N900, I have had suddden crashes lately that I cannot understand happening. The battery is in good capacity, so that should not have been the reason. Today it switched itself off in the middle of a call and I kept talking to thin air, because I did not realize it was off, then during a file transfer later today it took a break as well.

I checked in the syslog, but am not clear abbout why this is happening.

Maybe one of you weathered experts have a hint?

Here's some unsolicited quotes from the book of horrors:

Code:

May 23 21:04:17 Nokia-N900 DSME: shutdown request received from pid 832: /sbin/mce
May 23 21:04:17 Nokia-N900 DSME: Actdead (charger: on, alarm: not set)
May 23 21:04:17 Nokia-N900 DSME: state change request: DSME_STATE_USER -> DSME_STATE_ACTDEAD
May 23 21:04:17 Nokia-N900 DSME: go to actdead via reboot
May 23 21:04:17 Nokia-N900 DSME: new state: DSME_STATE_REBOOT
May 23 21:04:17 Nokia-N900 DSME: Reboot for actdead in 2 seconds
May 23 21:04:17 Nokia-N900 maemo-launcher[1060]: no child 1440 found in the kindergarten.
May 23 21:04:17 Nokia-N900 maemo-launcher[1060]: child (pid=1440) terminated due to exit()=0
May 23 21:04:17 Nokia-N900 ke_recv[1396]: sig_handler:767: device unlocked signal
May 23 21:04:17 Nokia-N900 systemui-tklock[1069]: systemui: shutdown_ind from DSME, quitting
May 23 21:04:17 Nokia-N900 systemui-tklock[1069]: splash: shutdown_ind from DSME, running splashscreen-util
May 23 21:04:17 Nokia-N900 DSME: Process '/usr/bin/hildon-input-method' with pid 1315 exited with return value 0
May 23 21:04:17 Nokia-N900 systemui-tklock[1069]: Received signal to quit, quitting
May 23 21:04:17 Nokia-N900 systemui-tklock[1069]: Unloading all plugins
May 23 21:04:17 Nokia-N900 hildon-desktop[1172]: GLIB WARNING ** default - hd_dbus_system_bus_signal_handler: shutdown_ind from DSME
May 23 21:04:17 Nokia-N900 DSME: Process '/usr/bin/hildon-home' with pid 1166 exited with return value 0
May 23 21:04:17 Nokia-N900 maemo-launcher[1060]: child (pid=1171) terminated due to exit()=0
May 23 21:04:17 Nokia-N900 DSME: Process '/usr/bin/systemui' with pid 1069 exited with return value 0
May 23 21:04:17 Nokia-N900 iphbd: client with socket 6 disappeared
May 23 21:04:17 Nokia-N900 maemo-launcher[1060]: child (pid=1168) terminated due to exit()=0
May 23 21:04:17 Nokia-N900 DSME: Process '/usr/bin/hildon-status-menu' with pid 1162 exited with return value 0
May 23 21:04:17 Nokia-N900 kernel: [  90.827453] pvr: hildon-desktop: cleaning up 552 unfreed resources
May 23 21:04:17 Nokia-N900 maemo-launcher[1060]: child (pid=1172) terminated due to exit()=0
May 23 21:04:17 Nokia-N900 DSME: Process '/usr/bin/hildon-desktop' with pid 1169 exited with return value 0
May 23 21:04:17 Nokia-N900 splashscreen-util[1610]: splash: type '2' (SHUTDOWN), sound '1' (YES), logo type '1' (BACKGROUND)
May 23 21:04:17 Nokia-N900 splashscreen-util[1610]: splash: setting root window background to Nokia logo
May 23 21:04:18 Nokia-N900 splashscreen-util[1610]: splash: root window bg set to show Nokia logo
May 23 21:04:18 Nokia-N900 splashscreen-util[1610]: splash: ca_context_play: Success
May 23 21:04:19 Nokia-N900 DSME: Issuing telinit 5
May 23 21:04:19 Nokia-N900 wlancond[1215]: Signal received: Terminated.
May 23 21:04:19 Nokia-N900 wlancond[1215]: Exiting.
May 23 21:04:19 Nokia-N900 dnsmasq[1228]: exiting on receipt of SIGTERM
May 23 21:04:19 Nokia-N900 clockd: shutting down


tushyd 2011-05-23 20:06

Re: spontaneous shutdowns - why?
 
Have you modified anything? Overclocked, CSSU, power kernel?

retsaw 2011-05-23 20:28

Re: spontaneous shutdowns - why?
 
Is there anything in the syslog from just before what you just posted? From the first line it appears that mce told dsme to shutdown and the rest of the log you posted is the process of shutting down.

peppino 2011-05-23 20:30

Re: spontaneous shutdowns - why?
 
Have you changed the file /etc/mce/mce.ini ?
In the log I see "DSME: shutdown request received from pid 832: /sbin/mce" maybe something wrong in the file /etc/mce/mce.ini, mce go down and the dsmetool request a reboot for resolve the problem.
I hate dsme, it is too much windows like.

cct 2011-05-24 09:23

Re: spontaneous shutdowns - why?
 
Quote:

Originally Posted by tushyd (Post 1013871)
Have you modified anything? Overclocked, CSSU, power kernel?

Yes, guilty on all charges: overclocked to 805, CSSU installed, power kernel wl (courtesy lxp) installed...

cct 2011-05-24 09:30

Re: spontaneous shutdowns - why?
 
Quote:

Originally Posted by retsaw (Post 1013894)
Is there anything in the syslog from just before what you just posted? From the first line it appears that mce told dsme to shutdown and the rest of the log you posted is the process of shutting down.

Hi, retsaw

here are some lines up from syslog.old - if you see anything there that might help finding out, it would be great:

Code:

May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: connection status change
May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: install new path: /org/freedesktop/Telepathy/Connection/sofiasip_vox/sip/_xxx_40sip_2evoxcorp_2enet
May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: setup_signal_handlers
May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: setup_signal_handlers path: /org/freedesktop/Telepathy/Connection/sofiasip_vox/sip/_xxx_40sip_2evoxcorp_2enet/mwi
May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: setup_signal_handlers add_signal
May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: setup_signal_handlers connect_signal
May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: message_counts_table insert.
May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: update_msg_counts
May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: Trying to update message counts for connection /org/freedesktop/Telepathy/Connection/sofiasip_vox/sip/_xxx_40sip_2evoxcorp_2enet/mwi
May 23 21:03:46 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: update_display
May 23 21:03:46 Nokia-N900 telepathy-gabble[1560]: GLIB MESSAGE default - lm-ssl-openssl.c: Issued for CN: gmail.com
May 23 21:03:47 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: connection status change
May 23 21:03:47 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: install new path: /org/freedesktop/Telepathy/Connection/gabble/jabber/xxx_40gmail_2ecom_2fMaemo
May 23 21:03:47 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: Refusing to watch a non-vox account.
May 23 21:03:49 Nokia-N900 maemo-launcher[1578]: invoking '/usr/bin/modest.launch'
May 23 21:03:50 Nokia-N900 maemo-launcher[1578]: opening of /usr/bin/modest.launch took 136078 usec
May 23 21:03:50 Nokia-N900 [1578]: activesync: AS-COMMON-UTILS Version: libas-common-utils (0.0.4-12+0m5) unstable; urgency=low
May 23 21:03:51 Nokia-N900 [1578]: GLIB MESSAGE default - Hello! modest-nokiamessaging-plugin has been loaded
May 23 21:03:51 Nokia-N900 [1578]: GLIB MESSAGE default - CIMSDbusServer::InitDbus acquired connection to dbus
May 23 21:03:51 Nokia-N900 [1578]: GLIB MESSAGE default - CIMSDbusServer::InitDbus dbus_bus_request_name succeeded
May 23 21:03:51 Nokia-N900 [1578]: GLIB MESSAGE default - CIMSDbusServer::InitDbus dbus_bus_add_match matches=: 2
May 23 21:03:51 Nokia-N900 [1578]: GLIB MESSAGE default - CIMSDbusServer::InitDbus complete
May 23 21:03:51 Nokia-N900 [1578]: GLIB MESSAGE default - Loaded!
May 23 21:03:51 Nokia-N900 [1578]: GLIB WARNING ** default - Skipped check account due to low diskspace, or no nms account exists in intellisyncd or modest
May 23 21:03:52 Nokia-N900 [1578]: activesync: AS-COMMON-UTILS Version: libas-common-utils (0.0.4-12+0m5) unstable; urgency=low
May 23 21:03:52 Nokia-N900 [1578]: activesync: AS-CAMEL: Version=camel-as-provider (0.0.4-12+0m5) unstable; urgency=low
May 23 21:03:53 Nokia-N900 systemui-tklock[1069]: Method call received from: :1.40, iface: com.nokia.system_ui.request, method: alarm_close
May 23 21:03:53 Nokia-N900 [1578]: GLIB WARNING ** mc-client - Unrecognized presence `online'
May 23 21:03:54 Nokia-N900 trackerd[1301]: GLIB WARNING ** Tracker - Could not add monitor for path:'/home/user/MyDocs/.camera'
May 23 21:03:55 Nokia-N900 trackerd[1301]: GLIB CRITICAL ** Tracker - Could not open directory 'file:///home/user/MyDocs/.camera': No such file or directory
May 23 21:03:56 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: connection status change
May 23 21:03:56 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: install new path: /org/freedesktop/Telepathy/Connection/spirit/skype/xxx
May 23 21:03:56 Nokia-N900 [1168]: GLIB DEBUG tpsip - MWI: Refusing to watch a non-vox account.
May 23 21:03:57 Nokia-N900 kernel: [  70.129974] twl4030_usb twl4030_usb: HW_CONDITIONS 0xd0/208; link 2
May 23 21:03:57 Nokia-N900 ke_recv[1396]: prop_modified:1889: udi /org/freedesktop/Hal/devices/usb_device_1d6b_2_musb_hdrc modified usb_device.vbus
May 23 21:03:57 Nokia-N900 [1578]: GLIB CRITICAL ** GLib-GObject - g_type_interface_add_prerequisite: assertion `G_TYPE_IS_INTERFACE (interface_type)' failed
May 23 21:03:57 Nokia-N900 [1578]: GLIB CRITICAL ** GLib-GObject - g_param_spec_enum: assertion `G_TYPE_IS_ENUM (enum_type)' failed
May 23 21:03:57 Nokia-N900 [1578]: GLIB CRITICAL ** GLib-GObject - g_object_class_install_property: assertion `G_IS_PARAM_SPEC (pspec)' failed
May 23 21:03:57 Nokia-N900 [1578]: GLIB CRITICAL ** GLib-GObject - Object class ModestHildonPannableAreaScrollable doesn't implement property 'movement-mode' from interface 'ModestScrollable'
May 23 21:04:03 Nokia-N900 [1168]: GLIB CRITICAL ** hildon-1 - hildon_pannable_area_jump_to: assertion `GTK_WIDGET_REALIZED (area)' failed
May 23 21:04:03 Nokia-N900 e-addressbook-factory[1186]: GLIB WARNING ** eds/tp - presences_changed_cb at e-book-backend-tp-cl.c:1171: mismatched contact and presence
May 23 21:04:04 Nokia-N900 [1578]: GLIB CRITICAL ** GLib-GObject - g_type_interface_add_prerequisite: assertion `G_TYPE_IS_INTERFACE (interface_type)' failed
May 23 21:04:04 Nokia-N900 [1578]: GLIB WARNING ** Gtk - SaveToDraftsMenu: missing action ActionsSaveToDrafts
May 23 21:04:04 Nokia-N900 e-addressbook-factory[1186]: GLIB WARNING ** eds/tp - presences_changed_cb at e-book-backend-tp-cl.c:1171: mismatched contact and presence
May 23 21:04:04 Nokia-N900 last message repeated 4 times
May 23 21:04:14 Nokia-N900 systemui-tklock[1069]: Method call received from: :1.11, iface: com.nokia.system_ui.request, method: powerkeymenu_open
May 23 21:04:15 Nokia-N900 cellular: csd[823]: ISI_SMS .292755> isiclient_sms_timeout(): TIMEOUT in SMS_STATE_START
May 23 21:04:15 Nokia-N900 cellular: csd[823]: ISI_SMS .293121> isiclient_sms_run(): SMS_STATE_START (sms_pp_routing_req, sms_gsm_cb_routing_req, [sms_storage_status_update_req], sim_sms_req_read_parameter)
May 23 21:04:15 Nokia-N900 cellular: csd[823]: ISI_SMS .294189> resp_sim_get_parameters(): rc:0 tr_id:0x00, validity:0xe1
May 23 21:04:15 Nokia-N900 cellular: csd[823]: ISI_SMS .294372> resp_sim_get_parameters(): Service centre:'+491710760000'
May 23 21:04:15 Nokia-N900 cellular: csd[823]: ISI_SMS .294860> resp_sim_get_parameters(): Alpha:'+'
May 23 21:04:15 Nokia-N900 cellular: csd[823]: ISI_SMS .294982> resp_sim_get_parameters(): Service centre:'+491710760000' read from the sim
May 23 21:04:15 Nokia-N900 cellular: csd[823]: ISI_SMS .295135> resp_cell_broadcast_subscription(): Incoming cell broadcast receive status:0 OK
May 23 21:04:15 Nokia-N900 cellular: csd[823]: ISI_SMS .295288> resp_sms_subscription(): Incoming sms receive status:0 OK
May 23 21:04:15 Nokia-N900 cellular: csd[823]: ISI_SMS .295440> resp_sms_resume(): ok internal resume request
May 23 21:04:15 Nokia-N900 mce[832]: Error sending with reply to com.nokia.system_ui.request.powerkeymenu_open: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
May 23 21:04:16 Nokia-N900 mce[832]: Requesting shutdown from powerkey.c: systemui_device_menu_dbus_cb(); result: 5
May 23 21:04:16 Nokia-N900 systemui-tklock[1069]: Method call received from: :1.11, iface: com.nokia.system_ui.request, method: tklock_open

cheers!

cct 2011-05-24 09:36

Re: spontaneous shutdowns - why?
 
Quote:

Originally Posted by peppino (Post 1013897)
Have you changed the file /etc/mce/mce.ini ?
In the log I see "DSME: shutdown request received from pid 832: /sbin/mce" maybe something wrong in the file /etc/mce/mce.ini, mce go down and the dsmetool request a reboot for resolve the problem.
I hate dsme, it is too much windows like.

Actually, before I did not. Now I have, as I was searching frantically for something mentioned here in the forum about a "power-off-mode set to 0 instead of 1 in order to remove spontaneous rebooting issues in ancient N900s. But I wasn't able to find it.

Nevertheless, while at it, I changed some of the things moderately that were slightly annoying before, and now the dimming and behaviour when the N900 is connected to the charger are more to my taste.

Maybe not the best way of going about troubleshooting (I should have left the stuff alone in order to find out what it really is that causes the spontaneous reboots, but can always revert to a saved copy of mce.ini).

I am thinking it could be a battery related topic, and am waiting for the replacement batteries I ordered last week.

And yes, I can dimly remember "config.sys" :D

retsaw 2011-05-24 10:38

Re: spontaneous shutdowns - why?
 
If I'm reading the syslog right it says the shutdown was from you pressing the power key. I think the default settings are that a long press just turns it off, are you sure you weren't holding the powerkey down before it turned off?

Although it seems this occasion was caused by you pressing the power button, another cause can be if the contacts on the battery aren't tight enough it can lose contact with the ones on the N900, if this is a problem you can bend the contacts on the battery closer together using something like a needle.

cct 2011-05-24 12:22

Re: spontaneous shutdowns - why?
 
Quote:

Originally Posted by retsaw (Post 1014286)
If I'm reading the syslog right it says the shutdown was from you pressing the power key. I think the default settings are that a long press just turns it off, are you sure you weren't holding the powerkey down before it turned off?

Although it seems this occasion was caused by you pressing the power button, another cause can be if the contacts on the battery aren't tight enough it can lose contact with the ones on the N900, if this is a problem you can bend the contacts on the battery closer together using something like a needle.

Good thing I just came back from picking up two new Polarcell 1400mAh batteries I bought last week. One of them is now being charged and I will see what difference it will make.

I am pretty sure I did not push the power-button, so maybe there is a disconnect somewhere in the battery. I'll check back and report.

Thanks again everybody for your support!

cheers.

cct 2011-05-24 21:47

Re: spontaneous shutdowns - why?
 
Quote:

Originally Posted by tushyd (Post 1013871)
Have you modified anything? Overclocked, CSSU, power kernel?

Taking this as a hint to remove potential hazards, I uninstalled CSSU and removed the overclocking. I kept the power-kernel for the moment. This resulted in an even less usable N900. Trying to restore Optfs with Backupmenu 0.65 that I had installed proved to be tricky as well. Let's see what else the day brings...

cct 2011-05-26 06:29

Re: spontaneous shutdowns - why?
 
For the records: overclocking does not seem to be the culprit, as I am getting spontaneous reboots in certain moments still, with conky showing me 250 MHz. Loading the bleeding edge drivers sometimes leads to my N900 giving up, from time to time. Getting the N900 to access my home WiFi when coming back from 3G/3.5G to the home-office always requires a restart frenzy (sometimes I just give up).

I have not been able to return to my home WiFi to be encrypted yet, which gives me a headache (There is some equipment in the WDS setup that disallows WEP, and WPA/2 does not work at all yet with my N900).

I do realize that a WDS, comprised out of several APs might not be the preferred terrain for the N900, but unfortunately, that's how things are here.

So I am struggling on with my spontaneous reboots.

Mentalist Traceur 2011-08-04 18:36

Re: spontaneous shutdowns - why?
 
Thread revival time: Overclocking is definitely not the culprit here (unless your N900 is used and was heavily overclocked before). Prolonged overclocking CAN be the culprit of spontaneous CPU damage if the overclocking is taxing enough on the CPU's electronics to damage it, BUT, I for one, have never overclocked the N900 and I have a nice history with spontaneous reboots. Well, nice in that spontaneous reboots just love me, and my N900 was and still is plagued with them since a LOOOOONG time ago.

I find R&D mode turned on, with watchdogs and lifeguard reset flags turned on to disable those 'features', helps tremendously with spontaneous reboots (if you're willing to occasionally have something crash in the background, that would normally cause a spontaneous reboot by something killing something else, and wait with bated breath to see if it'll restart on its own, or if you'll have to manually shut down the device.) But, for example, just yesterday morning my N900 had either hildon-desktop or x.org or something crash, and not everything restarted. (I suspect it was x.org that crashed because for a moment I saw the kernel's framebuffer console - which normally gets covered - assuming it's enabled in the first place - when x.org starts during boot.)

Some of the stuff restarted, and I got my current window re-displayed. But keyboard input crashed and didn't come back, as did whatever part of hildon lets you switch windows. ANYWAY, the virtual keyboard remained working and I had the chance to finish a PM I was writing on here, before shutting the device off.

So R&D mode can alleviate some of the problems (if you edit /sbin/preinit to disable the keyboard flickering when screen is locked, to keep battery consumption the same) of spontaneous reboots, although, honestly, it depends on the exact nature of the spontaneous reboots.

Worth a try, at any rate.

Megaltariak 2011-08-04 19:24

Re: spontaneous shutdowns - why?
 
I'm in rd-mode with the watchdogs and dsme reboots disabled and I have no problems. (I never had many problem with spontaneous reboot but I prefer to not have the phone rebooting if I kill some process too rapidly.)

Modify sleep_ind 1 to sleep_ind 0 in /etc/pmconfig to avoid keyboard flickering (no need to mess with /sbin/preinit)

Mentalist Traceur 2011-08-05 19:38

Re: spontaneous shutdowns - why?
 
Quote:

Originally Posted by Megaltariak (Post 1064239)
I'm in rd-mode with the watchdogs and dsme reboots disabled and I have no problems. (I never had many problem with spontaneous reboot but I prefer to not have the phone rebooting if I kill some process too rapidly.)

Modify sleep_ind 1 to sleep_ind 0 in /etc/pmconfig to avoid keyboard flickering (no need to mess with /sbin/preinit)

*Shrug* That works too. I change it in /sbin/preinit to keep my modified-system-files minimal (less restoring time after a reflash) - and more importantly because that's where I originally figured out how to disable it. (Since I was messing in /sbin/preinit to get my boot-shell script to launch as early as possible.)


All times are GMT. The time now is 11:53.

vBulletin® Version 3.8.8