rtcwake does not appear to be the culprit here.
In addition to nickel and hindenburg, this time I also killed fickel and sickel.
I started a second script in parallel that was monitoring via "pgrep" if the script loop.sh was still running. If not it called dmesg and restarted the script.
Once again script loop.sh broke after 84 cycles. According to dmesg-Output it appears that suspend and resume went fine (output see below).
After loop.sh was restarted by the monitoring script it ran for more than 150 cycle before I stopped it.
What I do right now is starting the loop.sh from another script (killing nickel and friends, changing directory, and starting the script with "nohup ./loop.sh &" - usually I started from the console without the "&") - this instance right now is at cycle 174 without breaking.
Is there a difference in calling nohup from the console or from another script - or is it important to immediately send nohup to backround with "&"?
Code:
ricoh619 2-0032: PMU: print_time *** rtc-time : 4/24/2020 13:58:22 *****
PMU: ricoh61x_rtc_alarm_enable :1
PMU: ricoh61x_rtc_alarm_enable :1
cpufreq_save_default_governor():save policy "interactive"
cpufreq_set_governor():set policy "performance"
PM: Syncing filesystems ... done.
PM: Preparing system for mem sleep
Freezing user space processes ... freeze 1!!!!!!!!!!!!!!!!!!!!
(elapsed 0.01 seconds) done.
Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
PM: Entering mem sleep
Suspending console(s) (use no_console_suspend to debug)
udc suspend begins
USB Gadget suspend ends
PMU: ricoh61x_battery_suspend START ================================================================================
ricoh619-battery ricoh619-battery: sleep entry time : 1587736671 secs
PMU: cc_sum = 119: cc_cap_res= 23: cc_cap_mas = -1687126528
PMU: cc_cap_offset= 23:
PMU : ricoh61x_battery_suspend : Suspend : temp_cc_delta_cap_mas is -874, cc_delta is 119, last_cc_delta_cap_mas is 459
PMU FG_RESET : calc_capacity_2 : capacity 9100 init_pswr 91 cc_delta 0
PMU: ricoh61x_battery_suspend status(7), rrf(0), suspend_soc(9144), suspend_cc(0)
PMU: ricoh61x_battery_suspend DSOC(9144), init_pswr(91), cc_delta(0)
PMU : set_current_time2register : second is 1587736671, hour is 441037
PMU FG_RESET : calc_capacity_2 : capacity 9100 init_pswr 91 cc_delta 0
PMU: ricoh61x_battery_suspend : STATUS(7), DSOC(9144), RSOC(9100), init_pswr*100(9100), cc_delta(0) ====================
PMU: ricoh61x_battery_suspend : fl_current(0), slp_state(0), sus_current(1900), hiber_current(889)
PM: suspend of devices complete after 97.951 msecs
PM: late suspend of devices complete after 0.269 msecs
PM: early resume of devices complete after 0.139 msecs
PMU: ricoh61x_rtc_alarm_enable :0
PMU: ricoh61x_battery_resume START ================================================================================
ricoh619-battery ricoh619-battery: sleep exit time : 1587736702 secs
PMU : ricoh61x_battery_resume : suspend_period_time is 31, sleepExitTime is 1587736702 sleepEntryTime is 1587736671 ==========
PMU calc_capacity_in_period 1%FACAP(54360)[mAs], cc_sum(213)[mAs], cc_sum_dec(-213)
PMU: ricoh61x_battery_resume : Discharge Processing (rrf=0)
PMU : calc_cc_value_by_sleepPeriod : delta_cap is 479100 [uAs], Period is 31 [s], fa_cap is 1510 [mAh], delta_soc is 0 [0.01%], offset is 479100 [uAs]
PMU: calc_soc_by_currentMethod Suspend(S/W) slp_current(1900), sus_current(1900), fl_current(0), cc_delta(0) ----------
PMU : calc_soc_by_currentMethod : slp_state is 0, soc is 9144 [0.01%] ----------
PMU : calc_soc_by_voltageMethod : VBAT is 4109800 [uV], soc is 9900 [0.01%] ----------
PMU: ricoh61x_battery_resume : soc_current(9144), soc_voltage(9900), Diff(-756) ==========
PMU: update_rsoc_on_currentMethod : Current Method. state(7), dsoc(9144), rsoc(9100), init_pswr(91), cc_delta(0) ----------
PMU FG_RESET : calc_capacity : capacity 91 init_pswr 91 cc_delta 0
PMU:check_charge_status_2 Resume Sus_soc(9144), cc(0)
PMU : set_current_time2register : second is 1587736702, hour is 441037
PMU FG_RESET : calc_capacity_2 : capacity 9100 init_pswr 91 cc_delta 0
PMU: ricoh61x_battery_resume : STATUS(7), DSOC(9144), RSOC(9100), init_pswr*100(9100), cc_delta(0) ====================
USB Gadget resume begins
fsl_udc_resume, Wait for wakeup thread finishes
dr_controller_run: udc enter low power mode
USB Gadget resume ends
PM: resume of devices complete after 294.863 msecs
PM: Finishing wakeup.
Restarting tasks ... done.
cpufreq_set_governor():set policy "interactive"
cpufreq_restore_default_governor():restore policy "interactive"
PMU: ricoh61x_rtc_alarm_enable :0
drivers/input/keyboard/tle4913_keypad.c(79): gpio140,val=1
PMU: mainFlowOfLowVoltage : Noxx : cc_delta_cap is -1, cc_delta_cap_now is 0, last_cc_delta_cap is 0
PMU: mainFlowOfLowVoltage : Noxx : temp_cc_delta_cap is -1, after temp_cc_delta_cap_mas is -331, cc_cap_mas 457
PMU: mainFlowOfLowVoltage : No9 :Cap is 9269 , low_rate is 88, dsoc is 9144, capnow is 9280, capzero is 61, delta cc is 0, delta cc ori is -1
PMU: mainFlowOfLowVoltage : No10 :temp_mas is -809, offset_mas is -478, value is -88, final value is 0
PMU : set_current_time2register : second is 1587736703, hour is 441037
PMU:STATUS= 7: IBAT= -7: VSYS= 4054000: VBAT= 4109800: DSOC= 9144: RSOC= 9100: cc_delta=0: rrf= 0
TPS65185 temprature data = 0x15,21