Seems like camera is broken on grouper, the default camera app crash immediately.
version: divestos 14.1 20231111
I don't yet have the time to dive into it but here is the critical section of the log if someone else wants to debug it:
12-04 00:35:21.824 1634 5083 I CameraFlashlight: Opening camera 1
12-04 00:35:21.824 1634 5083 I NvOmxCamera: HAL_camera_device_open: open camera 1
12-04 00:35:21.824 1634 5083 W : Parse of /etc/nvcamera.conf failed: Using default camera configuration.
12-04 00:35:21.838 1634 5083 D NvOsDebugPrintf: NvxLiteH264DecoderInit : Opening TVMR H264 block
12-04 00:35:21.838 1634 5083 D NvOsDebugPrintf: NvxLiteH264DecoderInit : Opening TVMR H264 block
12-04 00:35:21.838 1634 12074 E NvOmxCameraCallbacks: execute: semaphore wait failed! [2]
12-04 00:35:21.839 1634 5083 D NvOsDebugPrintf: NvxCameraSetParameter: failed to open /etc/nvcamera.conf
12-04 00:35:21.840 1634 5083 E NvOmxCamera: getCameraSensorSettings: GetSensorModesList fails [0x80001000]
12-04 00:35:21.840 1634 5083 E NvOmxCamera: setCameraHWSensor: getCameraSensorSettings failed! [0x80001000]
12-04 00:35:21.840 1634 5083 E NvOmxCamera: createCameraObjects: setCameraHWSensor failed! [0x80001000]
12-04 00:35:21.840 1634 5083 E NvOmxCamera: NvOmxCamera::createObjects :::: FAIL ::::::::::::::::: [0x80001000]
12-04 00:35:21.840 1634 5083 I NvOmxCamera: HAL_camera_device_open: opened camera 1 (0xb6323c80)
Full log:
12-04 00:35:21.274 2721 3083 I ActivityManager: START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 pkg=com.android.camera2 cmp=com.android.camera2/com.android.camera.CameraLauncher bnds=[467,1103][579,1199] (has extras)} from uid 10020 on display 0
12-04 00:35:21.276 2721 3083 I am_create_task: [0,17]
12-04 00:35:21.276 2721 3083 I am_create_activity: [0,260491040,17,com.android.camera2/com.android.camera.CameraLauncher,android.intent.action.MAIN,NULL,NULL,270532608]
12-04 00:35:21.276 2721 3083 E powerHAL::common: Invalid power hint: 0x8
12-04 00:35:21.276 2721 3083 I wm_task_created: [17,1]
12-04 00:35:21.277 2721 3083 I wm_task_moved: [17,1,1]
12-04 00:35:21.277 2721 3083 I am_focused_stack: [0,1,0,startedActivity setFocusedActivity]
12-04 00:35:21.278 2721 3083 I wm_task_moved: [17,1,1]
12-04 00:35:21.282 2721 3083 I am_focused_activity: [0,com.android.camera2/com.android.camera.CameraLauncher,startedActivity]
12-04 00:35:21.282 2721 3083 I am_pause_activity: [0,169972219,com.cyanogenmod.trebuchet/com.android.launcher3.Launcher]
12-04 00:35:21.290 2721 3083 I sysui_action: [323,com.android.camera2/com.android.camera.CameraLauncher]
12-04 00:35:21.290 2721 3083 I sysui_action: [324,false]
12-04 00:35:21.290 2721 3083 I sysui_action: [325,491]
12-04 00:35:21.299 7743 7743 I am_on_paused_called: [0,com.android.launcher3.Launcher,handlePauseActivity]
12-04 00:35:21.351 2721 3082 W ActivityManager: Slow operation: 51ms so far, now at startProcess: returned from zygote!
12-04 00:35:21.351 2721 3082 W ActivityManager: Slow operation: 51ms so far, now at startProcess: done updating battery stats
12-04 00:35:21.351 2721 3082 I am_proc_start: [0,12014,10030,com.android.camera2,activity,com.android.camera2/com.android.camera.CameraLauncher]
12-04 00:35:21.351 2721 3082 W ActivityManager: Slow operation: 51ms so far, now at startProcess: building log message
12-04 00:35:21.351 2721 3082 I ActivityManager: Start proc 12014:com.android.camera2/u0a30 for activity com.android.camera2/com.android.camera.CameraLauncher
12-04 00:35:21.351 2721 3082 W ActivityManager: Slow operation: 51ms so far, now at startProcess: starting to update pids map
12-04 00:35:21.351 2721 3082 W ActivityManager: Slow operation: 51ms so far, now at startProcess: done updating pids map
12-04 00:35:21.351 2721 3082 W ActivityManager: Slow operation: 51ms so far, now at startProcess: done starting proc!
12-04 00:35:21.352 2721 3086 I sysui_count: [window_time_0,22]
12-04 00:35:21.353 2721 5289 I am_proc_bound: [0,12014,com.android.camera2]
12-04 00:35:21.372 2721 5289 I am_restart_activity: [0,260491040,17,com.android.camera2/com.android.camera.CameraLauncher]
12-04 00:35:21.429 2721 3095 I sysui_action: [321,155]
12-04 00:35:21.429 2721 3095 I sysui_action: [320,1]
12-04 00:35:21.429 2721 3095 I sysui_action: [319,156]
12-04 00:35:21.476 12014 12014 I CAM_Profiler: [ 22.774ms][ui] GUARD: FirstRunDetector getTimeOfFirstRun - [19.685ms] preferences.getLong
12-04 00:35:21.476 12014 12014 I CAM_Profiler: [ 30.185ms][ui] GUARD: FirstRunDetector getTimeOfFirstRun - STOP
12-04 00:35:21.477 12014 12014 I CAM_Profiler: [ 35.568ms][ui] GUARD: CameraApp onCreate() - [35.568ms] initializeTimeOfFirstRun
12-04 00:35:21.483 2721 3082 I notification_cancel_all: [10030,12014,com.android.camera2,0,0,64,9,NULL]
12-04 00:35:21.487 12014 12014 I CAM_Profiler: [ 44.733ms][ui] GUARD: CameraApp onCreate() - STOP
12-04 00:35:21.557 12014 12029 D libEGL : loaded /vendor/lib/egl/libEGL_tegra.so
12-04 00:35:21.569 12014 12014 I CAM_OneCamFtrCnfgCrtr: CaptureModule? true
12-04 00:35:21.590 2721 5289 I ActivityManager: START u0 {cmp=com.android.camera2/com.android.camera.PermissionsActivity} from uid 10030 on display 0
12-04 00:35:21.591 2721 5289 I wm_task_moved: [17,1,1]
12-04 00:35:21.592 2721 5289 I am_create_activity: [0,32127056,17,com.android.camera2/com.android.camera.PermissionsActivity,NULL,NULL,NULL,8388608]
12-04 00:35:21.593 2721 5289 I wm_task_moved: [17,1,1]
12-04 00:35:21.595 2721 5289 I am_focused_activity: [0,com.android.camera2/com.android.camera.PermissionsActivity,startedActivity]
12-04 00:35:21.595 2721 5289 I am_pause_activity: [0,260491040,com.android.camera2/com.android.camera.CameraLauncher]
12-04 00:35:21.608 2721 5289 I am_finish_activity: [0,260491040,17,com.android.camera2/com.android.camera.CameraLauncher,app-request]
12-04 00:35:21.609 12014 12029 D libEGL : loaded /vendor/lib/egl/libGLESv1_CM_tegra.so
12-04 00:35:21.644 12014 12014 I CAM_Profiler: [127.084ms][ui] GUARD: CameraActivity.onCreateTasks - [34.575ms] Glide.setup
12-04 00:35:21.674 12014 12014 I CameraManagerGlobal: Connecting to camera service
12-04 00:35:21.674 12014 12047 E WVMExtractor: Failed to open libwvm.so: dlopen failed: library "libwvm.so" not found
12-04 00:35:21.679 1634 1946 I CameraService: getCameraCharacteristics: Switching to HAL1 shim implementation...
12-04 00:35:21.679 1634 1946 I CameraService: CameraService::connect call (PID -1 "cameraserver", camera ID 0) for HAL version default and Camera API version 1
12-04 00:35:21.679 1634 1946 E CameraService: getCameraPriorityFromProcState: Received invalid process state -1 from ActivityManagerService!
12-04 00:35:21.680 1634 1946 W : Parse of /etc/nvcamera.conf failed: Using default camera configuration.
12-04 00:35:21.692 1643 1643 I MediaPlayerService: MediaPlayerService::getOMX
12-04 00:35:21.692 1643 12048 D NuPlayerDriver: notifyListener_l(0xb5d20cc0), (1, 0, 0), loop setting(0, 0)
12-04 00:35:21.694 12014 12050 I OMXClient: MuxOMX ctor
12-04 00:35:21.696 1639 1667 I OMXMaster: makeComponentInstance(OMX.google.vorbis.decoder) in mediacodec process
12-04 00:35:21.709 1643 12051 D NuPlayerDriver: notifyListener_l(0xb5d20d80), (1, 0, 0), loop setting(0, 0)
12-04 00:35:21.721 12014 12029 D libEGL : loaded /vendor/lib/egl/libGLESv2_tegra.so
12-04 00:35:21.724 1643 12055 D NuPlayerDriver: notifyListener_l(0xb5d20e40), (1, 0, 0), loop setting(0, 0)
12-04 00:35:21.727 1634 1946 I CameraFlashlight: Opening camera 0
12-04 00:35:21.727 1634 1946 I NvOmxCamera: HAL_camera_device_open: open camera 0
12-04 00:35:21.727 1634 1946 W : Parse of /etc/nvcamera.conf failed: Using default camera configuration.
12-04 00:35:21.749 1634 1946 D NvOsDebugPrintf: NvxLiteH264DecoderInit : Opening TVMR H264 block
12-04 00:35:21.749 1634 1946 D NvOsDebugPrintf: NvxLiteH264DecoderInit : Opening TVMR H264 block
12-04 00:35:21.750 1634 12061 E NvOmxCameraCallbacks: execute: semaphore wait failed! [2]
12-04 00:35:21.751 1634 1946 D NvOsDebugPrintf: NvxCameraSetParameter: failed to open /etc/nvcamera.conf
12-04 00:35:21.752 1634 1946 E NvOmxCamera: getCameraSensorSettings: GetSensorModesList fails [0x80001000]
12-04 00:35:21.752 1634 1946 E NvOmxCamera: setCameraHWSensor: getCameraSensorSettings failed! [0x80001000]
12-04 00:35:21.752 1634 1946 E NvOmxCamera: createCameraObjects: setCameraHWSensor failed! [0x80001000]
12-04 00:35:21.752 1634 1946 E NvOmxCamera: NvOmxCamera::createObjects :::: FAIL ::::::::::::::::: [0x80001000]
12-04 00:35:21.752 1634 1946 I NvOmxCamera: HAL_camera_device_open: opened camera 0 (0xb6323d20)
12-04 00:35:21.756 1634 1946 I CameraFlashlight: Destroying camera 0
12-04 00:35:21.756 1634 1946 W NvOmxCamera: Already called release()
12-04 00:35:21.758 1634 1946 W : Parse of /etc/nvcamera.conf failed: Using default camera configuration.
12-04 00:35:21.758 1634 1946 I CameraService: disconnect: Disconnected client for camera 0 for PID 1634
12-04 00:35:21.759 1643 1643 D NuPlayerDriver: reset(0xb5d20cc0) at state 4
12-04 00:35:21.759 1643 1643 D NuPlayerDriver: notifyListener_l(0xb5d20cc0), (8, 0, 0), loop setting(0, 0)
12-04 00:35:21.759 1643 12048 W AMessage: failed to post message as target looper for handler 0 is gone.
12-04 00:35:21.759 1643 12048 D NuPlayerDriver: notifyResetComplete(0xb5d20cc0)
12-04 00:35:21.760 1643 1711 D NuPlayerDriver: reset(0xb5d20d80) at state 4
12-04 00:35:21.760 1643 1711 D NuPlayerDriver: notifyListener_l(0xb5d20d80), (8, 0, 0), loop setting(0, 0)
12-04 00:35:21.761 1643 12051 W AMessage: failed to post message as target looper for handler 0 is gone.
12-04 00:35:21.761 1643 12051 D NuPlayerDriver: notifyResetComplete(0xb5d20d80)
12-04 00:35:21.764 1643 4978 D NuPlayerDriver: reset(0xb5d20e40) at state 4
12-04 00:35:21.764 1643 4978 D NuPlayerDriver: notifyListener_l(0xb5d20e40), (8, 0, 0), loop setting(0, 0)
12-04 00:35:21.764 1643 12055 W AMessage: failed to post message as target looper for handler 0 is gone.
12-04 00:35:21.764 1643 12055 D NuPlayerDriver: notifyResetComplete(0xb5d20e40)
12-04 00:35:21.765 1634 1946 E CameraService: getLegacyParametersLazy: Parameters were not initialized, or were empty. Device may not be present.
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: Could not get device listing from camera subsystem
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: android.hardware.camera2.CameraAccessException: CAMERA_ERROR (3): getLegacyParametersLazy:912: Unable to initialize legacy parameters
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.hardware.camera2.CameraManager.throwAsPublicException(CameraManager.java:702)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.hardware.camera2.CameraManager.getOrCreateDeviceIdListLocked(CameraManager.java:778)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.hardware.camera2.CameraManager.getCameraIdList(CameraManager.java:140)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at com.android.ex.camera2.portability.AndroidCamera2AgentImpl.updateCameraDevices(AndroidCamera2AgentImpl.java:110)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at com.android.ex.camera2.portability.AndroidCamera2AgentImpl.<init>(AndroidCamera2AgentImpl.java:100)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at com.android.ex.camera2.portability.CameraAgentFactory.getAndroidCameraAgent(CameraAgentFactory.java:129)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at com.android.camera.CameraActivity.onCreateTasks(CameraActivity.java:1515)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at com.android.camera.util.QuickActivity.onCreate(QuickActivity.java:114)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.app.Activity.performCreate(Activity.java:6684)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1119)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:2637)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:2751)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.app.ActivityThread.-wrap12(ActivityThread.java)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1496)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.os.Handler.dispatchMessage(Handler.java:102)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.os.Looper.loop(Looper.java:154)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.app.ActivityThread.main(ActivityThread.java:6186)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at java.lang.reflect.Method.invoke(Native Method)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:889)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:779)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: Caused by: android.os.ServiceSpecificException: getLegacyParametersLazy:912: Unable to initialize legacy parameters
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.os.Parcel.readException(Parcel.java:1706)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.os.Parcel.readException(Parcel.java:1645)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.hardware.ICameraService$Stub$Proxy.getCameraCharacteristics(ICameraService.java:404)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: at android.hardware.camera2.CameraManager.getOrCreateDeviceIdListLocked(CameraManager.java:765)
12-04 00:35:21.770 12014 12014 E CAM2PORT_AndCam2AgntImp: ... 18 more
12-04 00:35:21.788 1634 5083 I CameraService: CameraService::connect call (PID -1 "com.android.camera2", camera ID 1) for HAL version default and Camera API version 1
12-04 00:35:21.789 1634 5083 W : Parse of /etc/nvcamera.conf failed: Using default camera configuration.
12-04 00:35:21.801 1643 12068 D NuPlayerDriver: notifyListener_l(0xb5d20cc0), (1, 0, 0), loop setting(0, 0)
12-04 00:35:21.812 1643 12070 D NuPlayerDriver: notifyListener_l(0xb5d20d80), (1, 0, 0), loop setting(0, 0)
12-04 00:35:21.821 1643 12072 D NuPlayerDriver: notifyListener_l(0xb5d20e40), (1, 0, 0), loop setting(0, 0)
12-04 00:35:21.824 1634 5083 I CameraFlashlight: Opening camera 1
12-04 00:35:21.824 1634 5083 I NvOmxCamera: HAL_camera_device_open: open camera 1
12-04 00:35:21.824 1634 5083 W : Parse of /etc/nvcamera.conf failed: Using default camera configuration.
12-04 00:35:21.838 1634 5083 D NvOsDebugPrintf: NvxLiteH264DecoderInit : Opening TVMR H264 block
12-04 00:35:21.838 1634 5083 D NvOsDebugPrintf: NvxLiteH264DecoderInit : Opening TVMR H264 block
12-04 00:35:21.838 1634 12074 E NvOmxCameraCallbacks: execute: semaphore wait failed! [2]
12-04 00:35:21.839 1634 5083 D NvOsDebugPrintf: NvxCameraSetParameter: failed to open /etc/nvcamera.conf
12-04 00:35:21.840 1634 5083 E NvOmxCamera: getCameraSensorSettings: GetSensorModesList fails [0x80001000]
12-04 00:35:21.840 1634 5083 E NvOmxCamera: setCameraHWSensor: getCameraSensorSettings failed! [0x80001000]
12-04 00:35:21.840 1634 5083 E NvOmxCamera: createCameraObjects: setCameraHWSensor failed! [0x80001000]
12-04 00:35:21.840 1634 5083 E NvOmxCamera: NvOmxCamera::createObjects :::: FAIL ::::::::::::::::: [0x80001000]
12-04 00:35:21.840 1634 5083 I NvOmxCamera: HAL_camera_device_open: opened camera 1 (0xb6323c80)
12-04 00:35:21.845 12014 12014 D AndroidRuntime: Shutting down VM
12-04 00:35:21.846 12014 12014 E AndroidRuntime: FATAL EXCEPTION: main
12-04 00:35:21.846 12014 12014 E AndroidRuntime: Process: com.android.camera2, PID: 12014
12-04 00:35:21.846 12014 12014 E AndroidRuntime: java.lang.RuntimeException: Unable to start activity ComponentInfo{com.android.camera2/com.android.camera.CameraActivity}: java.lang.RuntimeException: getParameters failed (empty parameters)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:2684)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:2751)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.app.ActivityThread.-wrap12(ActivityThread.java)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1496)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.os.Handler.dispatchMessage(Handler.java:102)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.os.Looper.loop(Looper.java:154)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.app.ActivityThread.main(ActivityThread.java:6186)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at java.lang.reflect.Method.invoke(Native Method)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:889)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:779)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: Caused by: java.lang.RuntimeException: getParameters failed (empty parameters)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.hardware.Camera.native_getParameters(Native Method)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.hardware.Camera.getParameters(Camera.java:2027)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at com.android.camera.settings.CameraPictureSizesCacher.getSizesForCamera(CameraPictureSizesCacher.java:88)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at com.android.camera.settings.AppUpgrader.upgradeCameraSizeSetting(AppUpgrader.java:352)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at com.android.camera.settings.AppUpgrader.upgrade(AppUpgrader.java:150)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at com.android.camera.settings.SettingsUpgrader.upgrade(SettingsUpgrader.java:54)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at com.android.camera.CameraActivity.onCreateTasks(CameraActivity.java:1538)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at com.android.camera.util.QuickActivity.onCreate(QuickActivity.java:114)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.app.Activity.performCreate(Activity.java:6684)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1119)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:2637)
12-04 00:35:21.846 12014 12014 E AndroidRuntime: ... 9 more
12-04 00:35:21.848 2721 3083 I am_crash: [12014,0,com.android.camera2,819510341,java.lang.RuntimeException,getParameters failed (empty parameters),Camera.java,-2]
12-04 00:35:22.105 2721 3086 W ActivityManager: Activity pause timeout for ActivityRecord{f86c720 u0 com.android.camera2/com.android.camera.CameraLauncher t17 f}
12-04 00:35:22.108 2721 3086 I am_restart_activity: [0,32127056,17,com.android.camera2/com.android.camera.PermissionsActivity]
12-04 00:35:22.116 2721 3086 I sysui_count: [window_time_0,1]
Seems like camera is broken on grouper, the default camera app crash immediately.
version: divestos 14.1 20231111
I don't yet have the time to dive into it but here is the critical section of the log if someone else wants to debug it:
Full log: