Reboot to Recovery once remove the adb shell connections

[vinothkumars]

Hi All,

         In iMX8QM custom board with 4.14.98 Kernel BSP,  We are facing reboot to recovery problem when removing the ADB shell connection.

Attached log after remove the adb shell connection,

[ 389.330314] android_work: sent uevent USB_STATE=DISCONNECTED
[ 390.834816] init: Received control message 'start' for 'adbd' from pid: 1898 (system_server)
[ 390.853309] android_work: did not send uevent (0 0 (null))
[ 390.860377] init: Received control message 'stop' for 'adbd' from pid: 1789 (/vendor/bin/hw/android.hardware.usb@1.1-service.imx)
[ 390.874324] init: Sending signal 9 to service 'adbd' (pid 3173) process group...
[ 390.882468] type=1400 audit(1581651750.860:184): avc: denied { dac_read_search } for pid=9811 comm="main" capability=2 scontext=u:r:zygote:s0 tcontext=u:r:zygote:s0 tclass=capability permissive=1
[ 390.900069] type=1400 audit(1581651771.040:185): avc: denied { dac_read_search } for pid=1 comm="init" capability=2 scontext=u:r:init:s0 tcontext=u:r:init:s0 tclass=capability permissive=1
[ 390.961131] libprocessgroup: Successfully killed process cgroup uid 0 pid 3173 in 79ms
[ 390.969509] init: Service 'adbd' (pid 3173) received signal 9
[ 390.975878] init: processing action (init.svc.adbd=stopped) from (/init.usb.configfs.rc:14)
[ 391.096045] init: Received control message 'start' for 'adbd' from pid: 1789 (/vendor/bin/hw/android.hardware.usb@1.1-service.imx)
[ 391.107999] init: starting service 'adbd'...
[ 391.115026] init: Created socket '/dev/socket/adbd', mode 660, user 1000, group 1000
[ 391.129029] read descriptors
[ 391.132466] read strings
[ 392.099352] init: Received control message 'start' for 'setup-bcb' from pid: 1898 (system_server)
[ 393.219124] type=1400 audit(1581651771.040:185): avc: denied { dac_read_search } for pid=1 comm="init" capability=2 scontext=u:r:init:s0 tcontext=u:r:init:s0 tclass=capability permissive=1
[ 393.236719] type=1400 audit(1581651773.376:186): avc: denied { read } for pid=2269 comm="com.android.car" name="ext4" dev="sysfs" ino=15170 scontext=u:r:carservice_app:s0 tcontext=u:object_r:sysfs:s0 tclass=dir permissive=1
[ 393.257267] type=1400 audit(1581651773.376:186): avc: denied { read } for pid=2269 comm="com.android.car" name="ext4" dev="sysfs" ino=15170 scontext=u:r:carservice_app:s0 tcontext=u:object_r:sysfs:s0 tclass=dir permissive=1
[ 393.277351] type=1400 audit(1581651773.376:187): avc: denied { open } for pid=2269 comm="com.android.car" path="/sys/fs/ext4" dev="sysfs" ino=15170 scontext=u:r:carservice_app:s0 tcontext=u:object_r:sysfs:s0 tclass=dir permissive=1
[ 393.298281] type=1400 audit(1581651773.376:187): avc: denied { open } for pid=2269 comm="com.android.car" path="/sys/fs/ext4" dev="sysfs" ino=15170 scontext=u:r:carservice_app:s0 tcontext=u:object_r:sysfs:s0 tclass=dir permissive=1
[ 393.318934] type=1400 audit(1581651773.376:188): avc: denied { read } for pid=2269 comm="com.android.car" name="lifetime_write_kbytes" dev="sysfs" ino=20381 scontext=u:r:carservice_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 393.340675] type=1400 audit(1581651773.376:188): avc: denied { read } for pid=2269 comm="com.android.car" name="lifetime_write_kbytes" dev="sysfs" ino=20381 scontext=u:r:carservice_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1
[ 393.362350] type=1400 audit(1581651773.376:189): avc: denied { open } for pid=2269 comm="com.android.car" path="/sys/fs/ext4/dm-1/lifetime_write_kbytes" dev="sysfs" ino=20381 scontext=u:r:carservice_app:s0 tcontext=u:object_r:sysfs:s0 tclass=file permissive=1

143|mek_8q:/ $ [ 393.613398] binder: release 6687:6687 transaction 591606 in, still active
[ 393.620306] binder: send failed reply for transaction 591606 to 6782:6849
[ 393.681481] binder: undelivered TRANSACTION_COMPLETE
[ 393.686486] binder: undelivered TRANSACTION_ERROR: 29189
[ 393.697422] binder: release 12391:12391 transaction 675661 out, still active
[ 393.704638] binder: undelivered TRANSACTION_COMPLETE
[ 393.710318] binder: undelivered death notification, 0000f97a82450820
[ 393.944582] binder_alloc: 1898: binder_alloc_buf, no vma
[ 393.950011] binder: 1685:2235 transaction failed 29189/-3, size 88-0 line 3135
[ 393.985514] binder: release 1898:2244 transaction 675661 in, still active
[ 393.992420] binder: send failed reply for transaction 675661, target dead
[ 393.999413] binder: undelivered TRANSACTION_COMPLETE
[ 394.004491] binder: undelivered transaction 677663, process died.
[ 394.114665] android_work: did not send uevent (0 0 (null))
[ 394.127526] logd.daemon: 75 output lines suppressed due to ratelimiting
[ 394.402413] ci_hdrc ci_hdrc.0: remove, state 1
[ 394.407115] usb usb1: USB disconnect, device number 1
[ 394.412268] usb 1-1: USB disconnect, device number 2
[ 394.444184] ci_hdrc ci_hdrc.0: USB bus 1 deregistered
[ 394.449779] arm-smmu 51400000.iommu: removing device with active domains!
[ 394.493291] reboot: Restarting system with command 'recovery'

Thanks & Regards,

VinothS,

[vinothkumars]

Hi All,

Finally, I found this problem in the custom application which is trying to access a USB camera. The application crashed when the error buffer full at the same time the camera is not present. 

Fixed by removing the error log storing mechanism.

Thank You,

Vinoth S,

[vinothkumars]

Hi All,

Still, I am facing the same problem. I got to USB disconnect and as well as a reboot problem.

143|mek_8q:/ $ [ 27.536980] binder: release 1664:1664 transaction 27995 in, still active
[ 27.544391] binder: send failed reply for transaction 27995 to 3049:3124
[ 27.554301] binder: release 2954:2954 transaction 26890 out, still active
[ 27.561668] binder: undelivered TRANSACTION_COMPLETE
[ 27.612973] binder: undelivered TRANSACTION_COMPLETE
[ 27.618041] binder: undelivered TRANSACTION_ERROR: 29189
[ 27.700466] binder_alloc: 1786: binder_alloc_buf, no vma
[ 27.705968] binder: 1613:1613 transaction failed 29189/-3, size 88-0 line 3135
[ 27.736445] audit: audit_lost=4420 audit_rate_limit=5 audit_backlog_limit=64
[ 27.743598] audit: rate limit exceeded
[ 27.746113] binder: release 1786:2420 transaction 26890 in, still active
[ 27.754279] binder: send failed reply for transaction 26890, target dead
[ 27.894302] android_work: sent uevent USB_STATE=DISCONNECTED
[ 27.906314] logd.writer: 27 output lines suppressed due to ratelimiting
[ 28.116975] arm-smmu 51400000.iommu: removing device with active domains!
[ 28.168771] reboot: Restarting system with command 'recovery'

Thank You,

Vinoth S,

[jamesbone]

Hello Vinothkumar,

This is standard behavior of Android when you are using ADB and get disconnected Android recognize as an issue was found and this make Reboot in Recovery Mode.

[vinothkumars]

Thank you James one for your reply.

Vinoth S