Message app crash when sending SMS

asked 2020-01-09 11:57:17 +0300

atlochowski gravatar image

Since 3.2.1.20 Message app crash randomly when sending SMS.

How to reproduce:

  1. Open Messages app
  2. Write message
  3. Press Send
  4. app crashes
  5. Open Messages app again
  6. There is notification that has problem with sending
  7. Press sent to send message written before crash
  8. Then message is send correctly

Some logs:

Jan 09 10:37:01 Sailfish dbus-daemon[1454]: invoker: Invoking execution: '/usr/bin/jolla-messages'
Jan 09 10:37:34 Sailfish kernel: [BAT] 80( 80, 80.9, 73.6( 74.0)  0.0)v3942(4033)( 413, 413)t343,c   41356,  106(15200)r222,L4130,s10
Jan 09 10:37:34 Sailfish kernel: [BAT]## SOC= 80( 80, 80),3969(3942),A=0730,T=342,On=0,0,(0),Unknown 000(100),vd=4370(0,0),ib=1000,0,F03,R08,W10
Jan 09 10:37:34 Sailfish kernel: [BAT] Internal R 424 mOhm, I1:677079uA I2:413726uA V1:3857784uV V2:3969660uV
Jan 09 10:37:34 Sailfish mce[716]: modules/battery-udev.c: mcebat_update(): battery_level : 81 -> 80
Jan 09 10:38:39 Sailfish mce[716]: modules/proximity.c: mp_datapipe_set_proximity_sensor_actual(): state: OPEN -> CLOSED
Jan 09 10:38:39 Sailfish kernel: [TSL277X_P][Before] raw=1023, status=1, far=367, near=833
Jan 09 10:38:39 Sailfish kernel: [TSL277X_P][After] far=500, near=833, from=500, to=65535
Jan 09 10:38:39 Sailfish mce[716]: modules/proximity.c: mp_datapipe_set_proximity_sensor_actual(): state: CLOSED -> OPEN
Jan 09 10:38:39 Sailfish kernel: [TSL277X_P][Before] raw=110, status=0, far=500, near=833
Jan 09 10:38:39 Sailfish kernel: [TSL277X_P][After] far=367, near=833, from=0, to=833
Jan 09 10:38:42 Sailfish lipstick[1601]: JavaScript error: resource://gre/modules/LoginManagerParent.jsm, line 88: TypeError: this._recipeManager is null
Jan 09 10:38:42 Sailfish mce[716]: modules/proximity.c: mp_datapipe_set_proximity_sensor_actual(): state: OPEN -> CLOSED
Jan 09 10:38:42 Sailfish kernel: [TSL277X_P][Before] raw=861, status=1, far=367, near=833
Jan 09 10:38:42 Sailfish kernel: [TSL277X_P][After] far=367, near=833, from=367, to=1000
Jan 09 10:38:43 Sailfish mce[716]: modules/proximity.c: mp_datapipe_set_proximity_sensor_actual(): state: CLOSED -> OPEN
Jan 09 10:38:43 Sailfish kernel: [TSL277X_P][Before] raw=186, status=0, far=367, near=833
Jan 09 10:38:43 Sailfish kernel: [TSL277X_P][After] far=367, near=833, from=0, to=833
Jan 09 10:39:04 Sailfish lipstick[1601]: [D] onDestruction:267 - coverActionIndicators destroyed sailfish-browser.desktop
Jan 09 10:39:04 Sailfish lipstick[1601]: [D] onCompleted:263 - coverActionIndicators created sailfish-browser.desktop
Jan 09 10:39:05 Sailfish lipstick[1601]: EmbedLiteExt void EmbedTouchManager::WindowDestroyed(nsIDOMWindow*):115: WindowClosed: 0x5e22dd40
Jan 09 10:39:05 Sailfish lipstick[1601]: EmbedLiteExt void EmbedChromeManager::WindowDestroyed(nsIDOMWindow*):113: WindowClosed: 0x5e22dd40
Jan 09 10:39:05 Sailfish lipstick[1601]: [W] unknown:0 - WindowPixmapItem does not have a source texture, cover will be dropped..
Jan 09 10:39:06 Sailfish lipstick[1601]: [D] onDestruction:267 - coverActionIndicators destroyed sailfish-browser.desktop
Jan 09 10:39:07 Sailfish [13708]: [W] unknown:0 - "There are still \"1\" items in the process of being created at engine destruction."
Jan 09 10:39:07 Sailfish mapplauncherd[1760]: Boosted process (pid=13708) exited with status 0
Jan 09 10:39:07 Sailfish lipstick[1601]: invoker: Invoking execution: '/usr/bin/sailfish-browser'
Jan 09 10:39:07 Sailfish dbus-daemon[1454]: dbus-daemon[1454]: [session uid=100000 pid=1454] Activating service name='org.nemomobile.qmlmessages' requested by ':1.207' (uid=100000 pid=16182 comm="/usr/libexec/mliteremoteaction org.nemomobile.qmlm")
Jan 09 10:39:08 Sailfish dbus-daemon[1454]: dbus-daemon[1454]: [session uid=100000 pid=1454] Successfully activated service 'org.nemomobile.qmlmessages'
Jan 09 10:39:08 Sailfish kernel: dbus-daemon(1454) send signal 9 to dbus-daemon(16184)
Jan 09 10:39:08 Sailfish estart[15479]: [W] CommHistoryDatabase::open:303 - Opened commhistory database: "/home/nemo/.local/share/commhistory/commhistory.db"
Jan 09 10:39:08 Sailfish estart[15479]: [D] unknown:0 - Starting favorites query at 302 ms
Jan 09 10:39:08 Sailfish booster-silica-qt5[16186]: [D] unknown:0 - Using Wayland-EGL
Jan 09 10:39:09 Sailfish mapplauncherd[16186]: SilicaBooster: Initiate asynchronous preload.
Jan 09 10:39:10 Sailfish estart[15479]: [D] unknown:0 - Favorites queried in 2054 ms
Jan 09 10:39:11 Sailfish invoker[1695]: WARNING: An inactive plugin is misbehaving - tried to show a window!
Jan 09 10:39:11 Sailfish invoker[1695]: WARNING: requestActivate() called for  QQuickView(0xbbebd8)  which has Qt::WindowDoesNotAcceptFocus set.
Jan 09 10:39:11 Sailfish estart[15479]: [D] unknown:0 - Starting metadata query at 2858 ms
Jan 09 10:39:11 Sailfish invoker[1695]: WARNING: requestActivate() called for  QQuickView(0xbbebd8)  which has Qt::WindowDoesNotAcceptFocus set.
Jan 09 10:39:11 Sailfish estart[15479]: [D] unknown:0 - All queried in 3182 ms
Jan 09 10:39:11 Sailfish lipstick[1601]: [W] unknown:0 - WindowPixmapItem does not have a source texture, cover will be dropped..
Jan 09 10:39:12 Sailfish estart[15479]: [D] unknown:0 - Starting online  query at 3717 ms
Jan 09 10:39:12 Sailfish estart[15479]: [D] unknown:0 - Online queried in 3740 ms
Jan 09 10:39:17 Sailfish dbus-daemon[1454]: invoker: Invoking execution: '/usr/bin/jolla-messages'
Jan 09 10:39:24 Sailfish kernel: [BAT] 79( 79, 79.9, 74.6( 73.7)  0.0)v3970(4043)( 337, 337)t354,c   54710,   78(15200)r216,L4130,s10
Jan 09 10:39:24 Sailfish kernel: [BAT]## SOC= 79( 79, 79),3975(3970),A=0554,T=352,On=0,0,(0),Unknown 000(100),vd=4370(0,0),ib=1000,0,F03,R08,W10
Jan 09 10:39:25 Sailfish mce[716]: modules/battery-udev.c: mcebat_update(): battery_level : 80 -> 79
Jan 09 10:39:26 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.
Jan 09 10:39:26 Sailfish dbus-daemon[754]: [system] Rejected send message, 3 matched rules; type="method_return", sender=":1.13" (uid=0 pid=1135 comm="/usr/sbin/ofonod -n --nobacktrace --noplugin=he910") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.15" (uid=0 pid=1140 comm="/usr/bin/fastdormancyd")
Jan 09 10:39:26 Sailfish dbus-daemon[754]: dbus-daemon[754]: [system] Rejected send message, 3 matched rules; type="method_return", sender=":1.13" (uid=0 pid=1135 comm="/usr/sbin/ofonod -n --nobacktrace --noplugin=he910") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.15" (uid=0 pid=1140 comm="/usr/bin/fastdormancyd")
Jan 09 10:39:26 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.
Jan 09 10:39:27 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.
Jan 09 10:39:27 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.
Jan 09 10:40:06 Sailfish invoker[1695]: WARNING: requestActivate() called for  QQuickView(0xbbebd8)  which has Qt::WindowDoesNotAcceptFocus set.
Jan 09 10:40:06 Sailfish estart[15479]: [D] unknown:0 - void ConversationChannel::sendMessage(const MessagePartList&, int, bool) Buffering message until channel is ready for: "+48-----------------"
Jan 09 10:40:06 Sailfish kernel: jolla-messages: unhandled page fault (11) at 0xb8f6bd20, code 0x005
Jan 09 10:40:06 Sailfish kernel: pgd = c3940000
Jan 09 10:40:06 Sailfish kernel: [b8f6bd20] *pgd=00000000
Jan 09 10:40:06 Sailfish kernel: 
Jan 09 10:40:06 Sailfish kernel: Pid: 15479, comm:       jolla-messages
Jan 09 10:40:06 Sailfish kernel: CPU: 0    Tainted: P        W  O  (3.4.108.20190506.1 #1)
Jan 09 10:40:06 Sailfish kernel: PC is at 0x41457724
Jan 09 10:40:06 Sailfish kernel: LR is at 0x41457afd
Jan 09 10:40:06 Sailfish kernel: pc : [<41457724>]    lr : [<41457afd>]    psr: 400f0030
                                 sp : bee28540  ip : 415be4c8  fp : 46701d48
Jan 09 10:40:06 Sailfish kernel: r10: 45ccbac0  r9 : 7ffa0000  r8 : 4ede86c0
Jan 09 10:40:06 Sailfish kernel: r7 : 00000000  r6 : 00000000  r5 : 000000cf  r4 : 4daaf5f4
Jan 09 10:40:06 Sailfish kernel: r3 : 4f08eba4  r2 : b8f6bd00  r1 : 40af6000  r0 : 45ce6a48
Jan 09 10:40:06 Sailfish kernel: Flags: nZcv  IRQs on  FIQs on  Mode USER_32  ISA Thumb  Segment user
Jan 09 10:40:06 Sailfish kernel: Control: 10c5787d  Table: 83b4006a  DAC: 00000015
Jan 09 10:40:06 Sailfish kernel: [<c010b75c>] (unwind_backtrace+0x0/0x118) from [<c010f684>] (__do_user_fault+0x7c/0xc4)
Jan 09 10:40:06 Sailfish kernel: [<c010f684>] (__do_user_fault+0x7c/0xc4) from [<c089b7ec>] (do_page_fault+0x354/0x3dc)
Jan 09 10:40:06 Sailfish kernel: [<c089b7ec>] (do_page_fault+0x354/0x3dc) from [<c01002dc>] (do_DataAbort+0x134/0x1a8)
Jan 09 10:40:06 Sailfish kernel: [<c01002dc>] (do_DataAbort+0x134/0x1a8) from [<c089a094>] (__dabt_usr+0x34/0x40)
Jan 09 10:40:06 Sailfish kernel: Exception stack(0xc5085fb0 to 0xc5085ff8)
Jan 09 10:40:06 Sailfish kernel: 5fa0:                                     45ce6a48 40af6000 b8f6bd00 4f08eba4
Jan 09 10:40:06 Sailfish kernel: 5fc0: 4daaf5f4 000000cf 00000000 00000000 4ede86c0 7ffa0000 45ccbac0 46701d48
Jan 09 10:40:06 Sailfish kernel: 5fe0: 415be4c8 bee28540 41457afd 41457724 400f0030 ffffffff
Jan 09 10:40:06 Sailfish kernel: jolla-messages(15479) send signal 11 to jolla-messages(15479)
Jan 09 10:40:08 Sailfish dbus-daemon[1454]: dbus-daemon[1454]: [session uid=100000 pid=1454] Activating service name='org.nemomobile.qmlmessages' requested by ':1.211' (uid=100000 pid=16479 comm="/usr/libexec/mliteremoteaction org.nemomobile.qmlm")
Jan 09 10:40:09 Sailfish dbus-daemon[1454]: dbus-daemon[1454]: [session uid=100000 pid=1454] Successfully activated service 'org.nemomobile.qmlmessages'
Jan 09 10:40:09 Sailfish estart[16186]: [W] CommHistoryDatabase::open:303 - Opened commhistory database: "/home/nemo/.local/share/commhistory/commhistory.db"
Jan 09 10:40:09 Sailfish kernel: dbus-daemon(1454) send signal 9 to dbus-daemon(16486)
Jan 09 10:40:09 Sailfish estart[16186]: [D] unknown:0 - Starting favorites query at 316 ms
Jan 09 10:40:09 Sailfish booster-silica-qt5[16488]: [D] unknown:0 - Using Wayland-EGL
Jan 09 10:40:10 Sailfish estart[16186]: [D] unknown:0 - Favorites queried in 735 ms
Jan 09 10:40:10 Sailfish mapplauncherd[16488]: SilicaBooster: Initiate asynchronous preload.
Jan 09 10:40:10 Sailfish invoker[1695]: WARNING: requestActivate() called for  QQuickView(0xbbebd8)  which has Qt::WindowDoesNotAcceptFocus set.
Jan 09 10:40:10 Sailfish estart[16186]: [D] unknown:0 - Starting metadata query at 911 ms
Jan 09 10:40:10 Sailfish estart[16186]: [D] unknown:0 - All queried in 1423 ms
Jan 09 10:40:11 Sailfish estart[16186]: [D] unknown:0 - Starting online  query at 1606 ms
Jan 09 10:40:11 Sailfish estart[16186]: [D] unknown:0 - Online queried in 1633 ms
Jan 09 10:40:11 Sailfish lipstick[1601]: [W] unknown:0 - WindowPixmapItem does not have a source texture, cover will be dropped..
Jan 09 10:40:11 Sailfish lipstick[1601]: [D] onCompleted:263 - coverActionIndicators created jolla-messages.desktop
Jan 09 10:40:12 Sailfish lipstick[1601]: [D] onDestruction:267 - coverActionIndicators destroyed jolla-messages.desktop
Jan 09 10:40:13 Sailfish invoker[1695]: WARNING: requestActivate() called for  QQuickView(0xbbebd8)  which has Qt::WindowDoesNotAcceptFocus set.
Jan 09 10:40:17 Sailfish invoker[1695]: WARNING: requestActivate() called for  QQuickView(0xbbebd8)  which has Qt::WindowDoesNotAcceptFocus set.
Jan 09 10:40:17 Sailfish estart[16186]: [D] unknown:0 - void ConversationChannel::sendMessage(const MessagePartList&, int, bool) Buffering message until channel is ready for: "+48---------------"
Jan 09 10:40:17 Sailfish estart[16186]: [D] unknown:0 - void ConversationChannel::channelRequestCreated(const ChannelRequestPtr&)
Jan 09 10:40:17 Sailfish estart[16186]: [D] unknown:0 - void ConversationChannel::addChannel(const ChannelPtr&) "/org/freedesktop/Telepathy/Connection/ring/tel/_0x16b40a0/text17"
Jan 09 10:40:17 Sailfish estart[16186]: [D] unknown:0 - void ConversationChannel::channelReady() Sending 1 buffered messages to: "+48----------------"
Jan 09 10:40:18 Sailfish dbus-daemon[1454]: invoker: Invoking execution: '/usr/bin/jolla-messages'
Jan 09 10:40:21 Sailfish ofonod[1135]: sms received, smsc_len is 8
Jan 09 10:40:33 Sailfish kernel: [BAT]usbin_valid_irq_handler
Jan 09 10:40:33 Sailfish kernel: [BAT]notify_usb_of_the_plugin_event = 1, USB_PLUGIN_WAKE_LOCK = 1
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_set_vbus_state online=1 init=1
Jan 09 10:40:33 Sailfish kernel: [BAT]handle_usb_insertion_removal, unplug_check_work, Start
Jan 09 10:40:33 Sailfish kernel: msm_otg msm_otg: USB exited from low power mode
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_sm_work b_idle work
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_chg_detect_work chg_state=0
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_chg_detect_work chg_state=1
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_chg_detect_work chg_state=2
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_chg_detect_work chg_state=5
Jan 09 10:40:33 Sailfish kernel: USB::otg cable_detect_get_adc chan=799638714, adc_code=24821, measurement=14508 physical=14508
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_chg_detect_work cable adc value=14 general cable
Jan 09 10:40:33 Sailfish kernel: USB::android android_set_usb_serialno general cable to use user serialno
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_chg_detect_work call sdp check
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_sm_work b_idle work
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_notify_chg_type chg_type=USB_SDP_CHARGER(1) curernt chg_type=0
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_notify_chg_type end :: current charger type=POWER_SUPPLY_TYPE_USB cur_chg_type=1
Jan 09 10:40:33 Sailfish kernel: [BAT]pm8921_set_usb_power_supply_type = USB
Jan 09 10:40:33 Sailfish kernel: msm_otg msm_otg: Avail curr from USB = 100
Jan 09 10:40:33 Sailfish kernel: ****USB::otg msm_otg_notify_charger check following condition****
Jan 09 10:40:33 Sailfish kernel: ****USB::speed 0 chg_type USB_SDP_CHARGER****
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_notify_charger Avail Curr form USB = 100
Jan 09 10:40:33 Sailfish kernel: [BAT]pm8921_charger_vbus_draw =  100 mA
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_start_peripheral on=1
Jan 09 10:40:33 Sailfish kernel: msm_hsusb msm_hsusb: CI13XXX_CONTROLLER_RESET_EVENT received
Jan 09 10:40:33 Sailfish kernel: USB::udc udc_irq set enumeration true
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_sm_work b_peripheral work
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_notify_chg_type chg_type=USB_SDP_CHARGER(1) curernt chg_type=1
Jan 09 10:40:33 Sailfish kernel: msm_otg msm_otg: Avail curr from USB = 0
Jan 09 10:40:33 Sailfish kernel: ****USB::otg msm_otg_notify_charger check following condition****
Jan 09 10:40:33 Sailfish kernel: ****USB::speed 0 chg_type USB_SDP_CHARGER****
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_notify_charger Avail Curr form USB = 0
Jan 09 10:40:33 Sailfish kernel: [BAT]pm8921_charger_vbus_draw =    0 mA
Jan 09 10:40:33 Sailfish kernel: USB::composite composite_disconnect
Jan 09 10:40:33 Sailfish kernel: android_work: android_work: did not send uevent (0 0   (null))
Jan 09 10:40:33 Sailfish kernel: USB::udc udc_irq speed detection handshake suspend state=0 configure state=0
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_notify_chg_type chg_type=USB_SDP_CHARGER(1) curernt chg_type=1
Jan 09 10:40:33 Sailfish kernel: USB::composite composite_disconnect
Jan 09 10:40:33 Sailfish kernel: USB::udc udc_irq speed detection handshake suspend state=0 configure state=0
Jan 09 10:40:33 Sailfish kernel: android_work: android_work: sent uevent USB_STATE=CONNECTED
Jan 09 10:40:33 Sailfish kernel: android_work: android_work: sent missed DISCONNECT event
Jan 09 10:40:33 Sailfish kernel: [BAT]## USB_ONLINE: 1 (plugin=1)
Jan 09 10:40:33 Sailfish kernel: [BAT]## SOC= 79( 79, 79),3961(3973),A=0455,T=356,On=1,0,(1),USB 000(100),vd=4370(0,0),ib=1000,0,F03,R08,W31
Jan 09 10:40:33 Sailfish kernel: android_work: android_work: sent uevent USB_STATE=CONNECTED
Jan 09 10:40:33 Sailfish mce[716]: modules/battery-udev.c: mcebat_update(): charger_type: none -> usb
Jan 09 10:40:33 Sailfish kernel: android_usb gadget: high-speed config #1: android_usb
Jan 09 10:40:33 Sailfish kernel: USB::composite set_config set usb configurate draw 500 mA
Jan 09 10:40:33 Sailfish kernel: USB::udc ci13xxx_vbus_draw draw 500 mA
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_notify_chg_type chg_type=USB_SDP_CHARGER(1) curernt chg_type=1
Jan 09 10:40:33 Sailfish kernel: msm_otg msm_otg: Avail curr from USB = 500
Jan 09 10:40:33 Sailfish kernel: USB::otg msm_otg_notify_charger Avail Curr form USB = 500
Jan 09 10:40:33 Sailfish kernel: [BAT]pm8921_charger_vbus_draw =  500 mA
Jan 09 10:40:33 Sailfish kernel: [BAT]iusbmax =  500 mA
Jan 09 10:40:33 Sailfish mce[716]: modules/battery-udev.c: mcebat_update(): charger_state: off -> on
Jan 09 10:40:33 Sailfish mce[716]: tklock.c: tklock_datapipe_uiexception_type_cb(): uiexception_type = none -> notif
Jan 09 10:40:33 Sailfish kernel: android_work: android_work: sent uevent USB_STATE=CONFIGURED
Jan 09 10:40:33 Sailfish mce[716]: tklock.c: tklock_dbus_notification_beg_cb(): notification begin from name=:1.45 owner=:1.45 pid=1601 uid=100000 gid=998 priv=1 cmd=/usr/bin/lipstick -plugin evdevtouch:/dev/input/event0:rotate=0
Jan 09 10:40:34 Sailfish kernel: [BAT]## STATUS: Discharging -> Not charging (Real)
Jan 09 10:40:34 Sailfish kernel: [BAT]## STATUS: Discharging -> Not charging (AP)
Jan 09 10:40:34 Sailfish kernel: [BAT]## SOC= 79( 79, 79),4050(3973),A=0080,T=355,On=1,0,(1),USB 500(500),vd=4370(0,0),ib=1000,0,F01,R02,W11
Jan 09 10:40:34 Sailfish kernel: [BAT] Internal R 328 mOhm, I1:290061uA I2:12625uA V1:3962649uV V2:4053783uV
Jan 09 10:40:34 Sailfish mce[716]: modules/battery-udev.c: mcebat_update(): battery_state: discharging -> not_charging
Jan 09 10:40:34 Sailfish kernel: [BAT]fastchg_irq_handler, EOC_WAKE_LOCK = 1, cc for bat battery = -1763661
Jan 09 10:40:35 Sailfish kernel: [BAT]## STATUS: Not charging -> Charging (Real)
Jan 09 10:40:35 Sailfish kernel: [BAT]## STATUS: Not charging -> Charging (AP)
Jan 09 10:40:35 Sailfish kernel: [BAT]## SOC= 79( 79, 79),4098(4108),A=0021,T=352,On=1,0,(1),USB 500(500),vd=4370(0,0),ib=1000,0,F07,R02,W1B
Jan 09 10:40:35 Sailfish kernel: [BAT] Internal R 167 mOhm, I1:12625uA I2:-130462uA V1:4053783uV V2:4077735uV
Jan 09 10:40:35 Sailfish mce[716]: modules/battery-udev.c: mcebat_update(): battery_state: not_charging -> charging
Jan 09 10:40:37 Sailfish kernel: USB::enable_store enable=0
Jan 09 10:40:37 Sailfish kernel: USB::android_enable_function rndis
Jan 09 10:40:37 Sailfish kernel: USB::enable_store enable=1
Jan 09 10:40:37 Sailfish kernel: rndis_function_bind_config: rndis_function_bind_config MAC: 32:19:60:05:07:97
Jan 09 10:40:37 Sailfish kernel: android_usb gadget: using random self ethernet address
Jan 09 10:40:37 Sailfish kernel: USB: MAC da:89:70:77:ce:79
Jan 09 10:40:37 Sailfish kernel: USB: HOST MAC 32:19:60:05:07:97
Jan 09 10:40:37 Sailfish kernel: USB::composite_suspend draw 2 mA
Jan 09 10:40:37 Sailfish kernel: USB::udc ci13xxx_vbus_draw draw 2 mA
Jan 09 10:40:37 Sailfish kernel: USB::otg msm_otg_notify_chg_type chg_type=USB_SDP_CHARGER(1) curernt chg_type=1
Jan 09 10:40:37 Sailfish kernel: msm_otg msm_otg: Avail curr from USB = 2
Jan 09 10:40:37 Sailfish kernel: ****USB::otg msm_otg_notify_charger check following condition****
Jan 09 10:40:37 Sailfish kernel: ****USB::speed 3 chg_type USB_SDP_CHARGER****
Jan 09 10:40:37 Sailfish kernel: USB::otg msm_otg_notify_charger Avail Curr form USB = 2
Jan 09 10:40:37 Sailfish kernel: [BAT]pm8921_charger_vbus_draw =    2 mA
Jan 09 10:40:37 Sailfish kernel: [BAT]iusbmax =  100 mA
Jan 09 10:40:37 Sailfish kernel: msm_hsusb msm_hsusb: CI13XXX_CONTROLLER_SUSPEND_EVENT received
Jan 09 10:40:37 Sailfish kernel: uether::eth_open
Jan 09 10:40:37 Sailfish kernel: ADDRCONF(NETDEV_UP): rndis0: link is not ready
Jan 09 10:40:37 Sailfish statefs[743]: Technology (type= "gadget" ) tethering is  ""
Jan 09 10:40:37 Sailfish mce[716]: tklock.c: tklock_dbus_notification_beg_cb(): notification begin from name=:1.45 owner=:1.45 pid=1601 uid=100000 gid=998 priv=1 cmd=/usr/bin/lipstick -plugin evdevtouch:/dev/input/event0:rotate=0
Jan 09 10:40:37 Sailfish kernel: msm_hsusb msm_hsusb: CI13XXX_CONTROLLER_RESUME_EVENT received
Jan 09 10:40:37 Sailfish kernel: USB::otg msm_otg_notify_chg_type chg_type=USB_SDP_CHARGER(1) curernt chg_type=1
Jan 09 10:40:37 Sailfish kernel: msm_otg msm_otg: Avail curr from USB = 0
Jan 09 10:40:37 Sailfish kernel: ****USB::otg msm_otg_notify_charger check following condition****
Jan 09 10:40:37 Sailfish kernel: ****USB::speed 3 chg_type USB_SDP_CHARGER****
Jan 09 10:40:37 Sailfish kernel: USB::otg msm_otg_notify_charger Avail Curr form USB = 0
Jan 09 10:40:37 Sailfish kernel: [BAT]pm8921_charger_vbus_draw =    0 mA
Jan 09 10:40:37 Sailfish kernel: USB::composite composite_disconnect
Jan 09 10:40:37 Sailfish kernel: android_work: android_work: sent uevent USB_STATE=DISCONNECTED
Jan 09 10:40:37 Sailfish kernel: USB::udc udc_irq speed detection handshake suspend state=0 configure state=0
Jan 09 10:40:37 Sailfish kernel: android_work: android_work: sent uevent USB_STATE=CONNECTED
Jan 09 10:40:37 Sailfish kernel: USB::otg msm_otg_notify_chg_type chg_type=USB_SDP_CHARGER(1) curernt chg_type=1
Jan 09 10:40:37 Sailfish kernel: USB::composite composite_disconnect
Jan 09 10:40:37 Sailfish kernel: USB::udc udc_irq speed detection handshake suspend state=0 configure state=0
Jan 09 10:40:37 Sailfish kernel: android_work: android_work: sent missed DISCONNECT event
Jan 09 10:40:37 Sailfish kernel: android_work: android_work: sent uevent USB_STATE=CONNECTED
Jan 09 10:40:37 Sailfish kernel: [BAT]## STATUS: Charging -> Discharging (Real)
Jan 09 10:40:37 Sailfish kernel: [BAT]## STATUS: Charging -> Discharging (AP)
Jan 09 10:40:37 Sailfish kernel: [BAT]## SOC= 79( 79, 79),3839(4108),A=-183,T=366,On=1,0,(1),USB 000(100),vd=4370(0,0),ib=1000,0,F03,R02,W3B
Jan 09 10:40:37 Sailfish kernel: [BAT] Internal R 371 mOhm, I1:-130462uA I2:516510uA V1:4077735uV V2:3837630uV
Jan 09 10:40:38 Sailfish mce[716]: modules/battery-udev.c: mcebat_update(): battery_state: charging -> discharging
Jan 09 10:40:38 Sailfish kernel: android_usb gadget: high-speed config #1: android_usb
Jan 09 10:40:38 Sailfish kernel: frndis:: rndis_set_alt init rndis ctrl 0
Jan 09 10:40:38 Sailfish kernel: frndis::rndis_open
Jan 09 10:40:38 Sailfish kernel: uether:: eth_start
Jan 09 10:40:38 Sailfish kernel: USB::composite set_config set usb configurate draw 500 mA
Jan 09 10:40:38 Sailfish kernel: USB::udc ci13xxx_vbus_draw draw 500 mA
Jan 09 10:40:38 Sailfish kernel: USB::otg msm_otg_notify_chg_type chg_type=USB_SDP_CHARGER(1) curernt chg_type=1
Jan 09 10:40:38 Sailfish kernel: msm_otg msm_otg: Avail curr from USB = 500
Jan 09 10:40:38 Sailfish kernel: USB::otg msm_otg_notify_charger Avail Curr form USB = 500
Jan 09 10:40:38 Sailfish kernel: [BAT]pm8921_charger_vbus_draw =  500 mA
Jan 09 10:40:38 Sailfish kernel: [BAT]iusbmax =  500 mA
Jan 09 10:40:38 Sailfish kernel: ADDRCONF(NETDEV_CHANGE): rndis0: link becomes ready
Jan 09 10:40:38 Sailfish kernel: android_work: android_work: sent uevent USB_STATE=CONFIGURED
Jan 09 10:40:38 Sailfish systemd[1]: Started udhcpcd DHCP server.
Jan 09 10:40:38 Sailfish kernel: USB::otg msm_chg_sdp_check speed=3 enumeration=1
Jan 09 10:40:38 Sailfish udhcpd[16656]: udhcpd: started, v1.31.0
Jan 09 10:40:38 Sailfish kernel: [BAT]## STATUS: Discharging -> Charging (Real)
Jan 09 10:40:38 Sailfish kernel: [BAT]## STATUS: Discharging -> Charging (AP)
Jan 09 10:40:38 Sailfish kernel: [BAT]## SOC= 79( 79, 79),4094(4108),A=-165,T=352,On=1,0,(1),USB 500(500),vd=4370(0,0),ib=1000,0,F07,R02,W3B
Jan 09 10:40:38 Sailfish kernel: [BAT] Internal R 363 mOhm, I1:516510uA I2:-137665uA V1:3837630uV V2:4075107uV
Jan 09 10:40:38 Sailfish mce[716]: modules/battery-udev.c: mcebat_update(): battery_state: discharging -> charging
Jan 09 10:40:43 Sailfish mce[716]: tklock.c: tklock_dbus_notification_beg_cb(): notification begin from name=:1.45 owner=:1.45 pid=1601 uid=100000 gid=998 priv=1 cmd=/usr/bin/lipstick -plugin evdevtouch:/dev/input/event0:rotate=0
Jan 09 10:40:48 Sailfish kernel: rndis0: no IPv6 routers present
Jan 09 10:40:49 Sailfish mce[716]: tklock.c: tklock_datapipe_uiexception_type_cb(): uiexception_type = notif -> none
Jan 09 10:40:58 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.
Jan 09 10:40:58 Sailfish dbus-daemon[754]: [system] Rejected send message, 3 matched rules; type="method_return", sender=":1.13" (uid=0 pid=1135 comm="/usr/sbin/ofonod -n --nobacktrace --noplugin=he910") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.15" (uid=0 pid=1140 comm="/usr/bin/fastdormancyd")
Jan 09 10:40:58 Sailfish dbus-daemon[754]: dbus-daemon[754]: [system] Rejected send message, 3 matched rules; type="method_return", sender=":1.13" (uid=0 pid=1135 comm="/usr/sbin/ofonod -n --nobacktrace --noplugin=he910") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.15" (uid=0 pid=1140 comm="/usr/bin/fastdormancyd")
Jan 09 10:40:58 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.
Jan 09 10:41:08 Sailfish udhcpd[16656]: udhcpd: sending OFFER to 192.168.2.3
Jan 09 10:41:08 Sailfish udhcpd[16656]: udhcpd: sending ACK to 192.168.2.3
Jan 09 10:41:10 Sailfish systemd[1]: Created slice system-sshd.slice.
Jan 09 10:41:10 Sailfish systemd[1]: Started OpenSSH per-connection server daemon (192.168.2.3:51564).
Jan 09 10:41:13 Sailfish sshd[16681]: Accepted password for nemo from 192.168.2.3 port 51564 ssh2
Jan 09 10:41:13 Sailfish sshd[16681]: pam_systemd(sshd:session): Using 600s D-Bus method call timeout
Jan 09 10:41:13 Sailfish systemd-logind[1026]: New session c6 of user nemo.
Jan 09 10:41:13 Sailfish systemd[1]: Started Session c6 of user nemo.
Jan 09 10:41:13 Sailfish sshd[16681]: pam_unix(sshd:session): session opened for user nemo by (uid=0)
Jan 09 10:41:19 Sailfish mce[716]: modules/display.c: mdy_display_state_enter(): current display state = DIM
Jan 09 10:41:19 Sailfish statefs[743]: Display: "dimmed"
Jan 09 10:41:24 Sailfish mce[716]: modules/display.c: mdy_display_state_leave(): current display state = POWER_DOWN
Jan 09 10:41:24 Sailfish sensorfwd[1119]: virtual bool HybrisAdaptor::setInterval(unsigned int, int) setInterval not ok
Jan 09 10:41:24 Sailfish kernel: [WH Lee] taos_als_enable::on=0, rc=0
Jan 09 10:41:24 Sailfish statefs[743]: Display: "off"
Jan 09 10:41:24 Sailfish lipstick[1601]: [D] unknown:0 - sleepDisplay
Jan 09 10:41:24 Sailfish kernel: request_suspend_state: sleep (0->3) at 13736262508899 (2020-01-09 09:41:24.858072271 UTC)
Jan 09 10:41:24 Sailfish kernel: [TP] synaptics_early_suspend() +++
Jan 09 10:41:24 Sailfish kernel: [TP] sleep mode 0 already set
Jan 09 10:41:24 Sailfish kernel: [TP] emitting touch release events
Jan 09 10:41:24 Sailfish kernel: [TP] synaptics_early_suspend()4085 ---
Jan 09 10:41:24 Sailfish mce[716]: modules/display.c: mdy_display_state_enter(): current display state = OFF
Jan 09 10:41:24 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.
Jan 09 10:41:24 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.
Jan 09 10:41:25 Sailfish kernel: mdp4_dsi_cmd_off: Error, SET_CLK_OFF by force
Jan 09 10:41:25 Sailfish kernel: mdp4_mixer_blend_setup: Error: no bg_pipe at mixer=0
Jan 09 10:41:25 Sailfish kernel: mipi_truly_otm9608a_lcd_off()
Jan 09 10:41:25 Sailfish kernel: mipi_truly_otm9608a_lcd_off()--
Jan 09 10:41:25 Sailfish kernel: mdp4_mixer_blend_setup: Error: no bg_pipe at mixer=0
Jan 09 10:41:33 Sailfish kernel: lcm_recovery_timer_handler: ## panel_power is off
Jan 09 10:41:45 Sailfish kernel: kworker/0:3(7269) send signal 2 to less(16765)
Jan 09 10:41:45 Sailfish kernel: kworker/0:3(7269) send signal 2 to journalctl(16764)
Jan 09 10:41:54 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.
Jan 09 10:41:55 Sailfish encpartition[1051]: Device /dev/sailfish/home doesn't exist or access denied.

device: Jolla 1

OS: 3.2.1.20

edit retag flag offensive close delete

Comments

1

I've seen the exact same behavior, with earlier versions as well, and with both Xperia XA2 and 10. Haven't had the time to collect logs though. Nice job.

Mohjive ( 2020-01-09 15:00:02 +0300 )edit

Confirming. I have experience same behaviour since 3.0 (or maybe even earlier, can't remember) on Xperia X.

mcencora ( 2020-01-09 17:20:08 +0300 )edit

happened here as well (3.2.x). Also on Xperia X. very rare though and last time the message was sent despite the crash.

btw. scrolling the code block above on my phone is really cumbersome. Shouldn't we use pastebin or something like that?

rozgwi ( 2020-01-09 19:31:46 +0300 )edit

@rozgwi for sure scrolling this on the phone is not nice but I think it's easier for Jolla to copy log from here than from external sites. Most of people paste code here using code tag.

atlochowski ( 2020-01-09 19:49:41 +0300 )edit

Another time, please don't paste humongous logs here, but use some pastebin service instead. It really messes up aggregators like the Telegram channel.

Tanghus ( 2020-01-10 13:48:43 +0300 )edit