Index [Intvl]Time Source Severity Origin Summary FileLinePos 148247 +00:01:26.111426327820 AET_S0C00T0 IN(0x00001808)=0x000BF7EB 148248 +00:01:26.111429531860 AET_S0C00T0 IN(0x00001808) 148249 +00:01:26.111431486308 AET_S0C00T0 IN(0x00001808)=0x000BF80C 148250 +00:01:26.111431617025 AET_S0C00T0 IN(0x00001808) 148251 +00:01:26.111440054118 AET_S0C00T0 IN(0x00001808)=0x000BF829 148252 +00:01:26.111440236918 AET_S0C00T0 IN(0x00001808) 148253 +00:01:26.111452290751 AET_S0C00T0 IN(0x00001808)=0x000BF854 148254 +00:01:26.111455442912 AET_S0C00T0 IN(0x00001808) 148255 +00:01:26.111457372131 AET_S0C00T0 IN(0x00001808)=0x000BF865 148256 +00:01:26.111457525126 AET_S0C00T0 IN(0x00001808) 148257 +00:01:26.111467890422 AET_S0C00T0 IN(0x00001808)=0x000BF885 148258 +00:01:26.111468073324 AET_S0C00T0 IN(0x00001808) 148259 +00:01:26.111471354370 AET_S0C00T0 IN(0x00001808)=0x000BF89F 148260 +00:01:26.111476460368 AET_S0C00T0 IN(0x00001808) 148261 +00:01:26.111481667684 AET_S0C00T0 IN(0x00001808)=0x000BF8C8 148262 +00:01:26.111481798604 AET_S0C00T0 IN(0x00001808) 148263 +00:01:26.111493881429 AET_S0C00T0 IN(0x00001808)=0x000BF8E4 148264 +00:01:26.111494064229 AET_S0C00T0 IN(0x00001808) 148265 +00:01:26.111497317301 AET_S0C00T0 IN(0x00001808)=0x000BF8F0 148266 +00:01:26.111502447103 AET_S0C00T0 IN(0x00001808) 148267 +00:01:26.111509506632 AET_S0C00T0 IN(0x00001808)=0x000BF920 148268 +00:01:26.111509637349 AET_S0C00T0 IN(0x00001808) 148269 +00:01:26.111512919108 AET_S0C00T0 IN(0x00001808)=0x000BF92F 148270 +00:01:26.111513124288 AET_S0C00T0 IN(0x00001808) 148271 +00:01:26.111523307495 AET_S0C00T0 IN(0x00001808)=0x000BF95A 148272 +00:01:26.111528437805 AET_S0C00T0 IN(0x00001808) 148273 +00:01:26.111535469869 AET_S0C00T0 IN(0x00001808)=0x000BF973 148274 +00:01:26.111535600586 AET_S0C00T0 IN(0x00001808) 148275 +00:01:26.111538908895 AET_S0C00T0 IN(0x00001808)=0x000BF98A 148276 +00:01:26.111539114176 AET_S0C00T0 IN(0x00001808) 148277 +00:01:26.111551120300 AET_S0C00T0 IN(0x00001808)=0x000BF9B1 148278 +00:01:26.111607007141 AET_S0C00T0 Exception(#MC) 148279 +00:01:26.111621119995 AET_S0C00T0 INIT 148280 +00:01:26.111682316895 AET_S0C00T0 INIT 148281 +00:01:26.111683334859 AET_S0C00T0 INIT 148282 +00:01:26.111684635010 AET_S0C00T0 OUT(0x00000CF8)=0x80000060 148283 +00:01:26.111685366923 AET_S0C00T0 IN(0x00000CFC) 148284 +00:01:26.111686563009 AET_S0C00T0 IN(0x00000CFC)=0xC0000001 148285 +00:01:26.111717394918 AET_S0C00T0 INIT 148286 +00:01:26.111718231099 AET_S0C00T0 OUT(0x00000CF8)=0x80000060 148287 +00:01:26.111718361511 AET_S0C00T0 IN(0x00000CFC) 148288 +00:01:26.111720236715 AET_S0C00T0 IN(0x00000CFC)=0xC0000001 148289 +00:01:26.113436093038 AET_S0C00T0 OUT(0x00000CF9)=0x00000006 148290 +00:01:26.113489929098 CSME Normal IPC [IPC_DRV] ipc_drv_hw_int_cb: irq = 68 148291 +00:01:26.113492124736 CSME Normal IPC [IPC_DRV] ipc_drv_hw_int_cb p_1: Int Status: 0x00008000, IE: 0x00018001 148292 +00:01:26.113496320699 CSME Normal IPC [IPC_DRV] ipc_drv_hw_int_cb p_1: INCOMING CSR 148293 +00:01:26.113502350159 CSME User2 IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt SET incoming_csr_in_progress for p_1 [0] (0x00000008) 148294 +00:01:26.113502661845 CSME User2 IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt RESET incoming_csr_in_progress for p_1 [0] 148295 +00:01:26.113506742249 CSME Normal IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt: p_1: pg 0, CSR 0x00000008, state 3 148296 +00:01:26.113508269755 CSME Normal IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt_handle_validity_bits: p_1 GOT VALIDITY RQ [0] 148297 +00:01:26.113512340393 CSME User2 IPC [IPC_DRV] ipc_check_pg_override OVERRIDING 148298 +00:01:26.113518342997 CSME Normal KERNEL [KERNEL] RunProfileControl PG override process request state=1, global request state=1, PG in progress=0, ModuleId=80860056 148299 +00:01:26.113518696187 CSME Normal KERNEL [KERNEL] RunProfileControl PG override all processes bitmap=00000000000000000000000000082100 148300 +00:01:26.113525539449 CSME Normal IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt_handle_validity_bits: VAL GUARANTEED for p_1 148301 +00:01:26.113526980693 CSME User2 IPC [IPC_DRV] ipc_check_pg_override ALREADY OVERRIDDEN 148302 +00:01:26.113529139709 CSME User2 IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt SET incoming_csr_in_progress for p_1 [1] (0x00000000) 148303 +00:01:26.113531502177 CSME User2 IPC [IPC_DRV] ipc_check_pg_override ALREADY OVERRIDDEN 148304 +00:01:26.113535139872 CSME User2 IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt RESET incoming_csr_in_progress for p_1 [1] 148305 +00:01:26.113536585999 CSME Normal IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt: p_1: pg 1, CSR 0x00000000, state 3 148306 +00:01:26.113557563375 CSME Normal IPC [IPC_DRV] ipc_drv_hw_int_cb: irq = 68 148307 +00:01:26.113557781474 CSME Normal IPC [IPC_DRV] ipc_drv_hw_int_cb p_1: Int Status: 0x00000001, IE: 0x00018001 148308 +00:01:26.113559725647 CSME Normal IPC [IPC_DRV] ipc_drv_hw_int_cb p_1: INCOMING DB 148309 +00:01:26.113563965556 CSME User2 IPC [IPC_DRV] ipc_drv_handle_IN_DB_interrupt: p_1 Incoming DB interrupt: 0x80000004 148310 +00:01:26.113565964254 AET_S0C00T0 OUT(0x00000080)=0x00000003 148311 +00:01:26.113574367574 CSME User2 IPC [IPC_DRV] ipc_drv_consume_in_msg p_1:c_0 DB 0x80000004 Data 0x00008022 0x00000000 0x00000000 148312 +00:01:26.113578072001 AET_S0C00T0 OUT(0x00000080)=0x00000004 148313 +00:01:26.113587967834 CSME User2 IPC [IPC_DRV] ipc_drv_handle_IN_DB_interrupt: p_1:c_0 Sending Select event to client 148314 +00:01:26.113592007853 AET_S0C00T0 OUT(0x00000021)=0x000000FF 148315 +00:01:26.113606850077 AET_S0C00T0 OUT(0x000000A1)=0x000000FF 148316 +00:01:26.113616777242 CSME Normal IPC [IPC_DRV] ipc_drv_hw_int_cb: irq = 68 148317 +00:01:26.113617228088 CSME Normal IPC [IPC_DRV] ipc_drv_hw_int_cb p_1: Int Status: 0x00008000, IE: 0x00018001 148318 +00:01:26.113621583557 CSME Normal IPC [IPC_DRV] ipc_drv_hw_int_cb p_1: INCOMING CSR 148319 +00:01:26.113622987366 CSME User2 IPC [IPC_DRV] ipc_check_pg_override ALREADY OVERRIDDEN 148320 +00:01:26.113625193583 CSME User2 IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt SET incoming_csr_in_progress for p_1 [1] (0x00000020) 148321 +00:01:26.113627676493 CSME User2 IPC [IPC_DRV] ipc_check_pg_override ALREADY OVERRIDDEN 148322 +00:01:26.113629558818 CSME User2 IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt RESET incoming_csr_in_progress for p_1 [1] 148323 +00:01:26.113633982646 CSME Normal IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt: p_1: pg 1, CSR 0x00000020, state 3 148324 +00:01:26.113635585836 CSME Normal IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt_handle_validity_bits: p_1 GOT VALIDITY RQ CANCEL [1] 148325 +00:01:26.113637603658 AET_S0C00T0 OUT(0x00000CF8)=0x8000FDDC 148326 +00:01:26.113637734070 AET_S0C00T0 IN(0x00000CFC) 148327 +00:01:26.113641090393 CSME Normal KERNEL [KERNEL] RunProfileControl PG override process request state=0, global request state=1, PG in progress=0, ModuleId=80860056 148328 +00:01:26.113641489970 CSME Normal KERNEL [KERNEL] RunProfileControl PG override all processes bitmap=00000000000000000000000000082000 148329 +00:01:26.113648384501 CSME User2 IPC [IPC_DRV] ipc_check_pg_override OVERRIDE CANCELLED 148330 +00:01:26.113650026754 CSME User2 IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt SET incoming_csr_in_progress for p_1 [0] (0x00000000) 148331 +00:01:26.113650835571 AET_S0C00T0 IN(0x00000CFC)=0x00000008 148332 +00:01:26.113652625224 CSME User2 IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt RESET incoming_csr_in_progress for p_1 [0] 148333 +00:01:26.113656777242 CSME Normal IPC [IPC_DRV] ipc_drv_handle_CSR_interrupt: p_1: pg 0, CSR 0x00000000, state 3 148334 +00:01:26.113677608134 CSME User1 PWR_DRV [PWR] SelectCB Called. 148335 +00:01:26.113677774150 CSME User1 PWR_DRV [PWR] New IPC Event Received. 0x00000001 148336 +00:01:26.113727622782 CSME Normal IPC [IPC_DRV] ipc_drv_read_cb: p_1:c_0 DB 0x80000004, Data 0x00008022 0x00000000 0x00000000 148337 +00:01:26.113775622457 CSME User1 PWR_DRV [PWR] New IPC Event Received: Read FDS. ipc_event->Command = 0x8022 148338 +00:01:26.113775838114 CSME User1 PWR_DRV [PWR] IPC PMC Manageability Event. Value (0x8022). 148339 +00:01:26.113782431539 CSME User1 PWR_DRV [PWR] HW interrupt cb: received from PMC. pmcintsts = 0x80001. 148340 +00:01:26.113782647196 CSME User1 PWR_DRV [PWR] Posting event. event = 2. 148341 +00:01:26.113799227600 CSME User1 PWR_DRV [PWR] Sent to PMC. Offset = 0x308, Value = 0x1. 148342 +00:01:26.113803629456 CSME User1 PWR_DRV [PWR]: pwr_clear_intr_mask 148343 +00:01:26.113804186096 CSME User1 PWR_DRV [PWR] Sent to PMC. Offset = 0x10, Value = 0x80000000. 148344 +00:01:26.113845639547 CSME User1 PWR_DRV [PWR] pwr_drv_ioctl_cb(): Minor: (2) Handle: (2) Tid: (13001000) Request: (2) 148345 +00:01:26.113846004944 CSME User1 PWR_DRV [PWR] pwr_maestro_receive_notify_causes_handler(): Maestro ReceiveNotifyCauses. 148346 +00:01:26.113850036519 CSME User1 PWR_DRV [PWR] pwr_maestro_receive_notify_causes_handler(): Cause: (1) Event (2) Context (5). 148347 +00:01:26.113850509338 CSME User1 PWR_DRV [PWR]: pwr_update_fwsts_and_shared_bup_data(): Context = 0x5 148348 +00:01:26.113856867574 CSME User2 BUP [HECI1_CSE_GS1] write data = 0x82070106, mask data = 0xF0FF0000. 148349 +00:01:26.113857173564 CSME User1 PWR_DRV [PWR]: pwr_log_add_entry - log_value = 0x101 148350 +00:01:26.114054349569 AET_S0C00T0 OUT(0x00000CF8)=0x80000060 148351 +00:01:26.114088153687 AET_S0C00T0 OUT(0x00000CFC)=0xC0000001 148352 +00:01:26.114146875712 AET_S0C00T0 OUT(0x00000CF8)=0x80000060 148353 +00:01:26.114147006124 AET_S0C00T0 IN(0x00000CFC) 148354 +00:01:26.114149664001 AET_S0C00T0 IN(0x00000CFC)=0xC0000001 148355 +00:01:26.114257281799 CSME User1 MAESTRO [NOTIFY] PM driver notify. cause = 1, event = 2, context = 5 148356 +00:01:26.114258791402 CSME User1 MAESTRO [POWER] Current State = CM0_ENTRY_COMPLETE. (3) 148357 +00:01:26.114262879944 CSME User1 MAESTRO [POWER] New Event = MAESTRO_PWR_RESET_WARN. (0) 148358 +00:01:26.114263159078 CSME User1 MAESTRO [POWER] State Flags = (0x00000101) Policy Flags = (0x00013200) Wait Flags = (0x00000008) 148359 +00:01:26.114270082906 CSME User1 MAESTRO [POWER] in_early_host_reset_flow 148360 +00:01:26.114270252177 CSME User1 MAESTRO [POWER] Next State = CM0_EXIT_BEGIN. (4) 148361 +00:01:26.114271715393 CSME User1 MAESTRO [POWER] State Flags = (0x00040111) Policy Flags = (0x00013200) Wait Flags = (0x00000008) 148362 +00:01:26.114274261780 CSME User1 MAESTRO [POWER] Current State = CM0_EXIT_BEGIN. (4) 148363 +00:01:26.114276397196 CSME User1 MAESTRO [POWER] New Event = MAESTRO_NEW_STATE. (0) 148364 +00:01:26.114278362528 CSME User1 MAESTRO [POWER] State Flags = (0x00040111) Policy Flags = (0x00013200) Wait Flags = (0x00000008) 148365 +00:01:26.114285298564 CSME User2 BUP [HECI1_CSE_GS1] write data = 0x82200106, mask data = 0xF0FF0000. 148366 +00:01:26.114316889547 CSME User1 PWR_DRV [PWR] pwr_drv_ioctl_cb(): Minor: (2) Handle: (2) Tid: (13001000) Request: (4) 148367 +00:01:26.114317252502 CSME User1 PWR_DRV [PWR] pwr_maestro_get_data_handler(): Maestro GetData (4). 148368 +00:01:26.114348090719 CSME User2 BUP FWSTS HECI 0x0:REG 0x10,write data = 0x8B200106, mask data = 0xF000000. 148369 +00:01:26.114348727926 CSME User2 BUP [HECI1_CSE_GS1] write data = 0x8B240106, mask data = 0xF0FF0000. 148370 +00:01:26.114382095439 CSME Normal BUP [PERF],TS,80860030,0,25,589840,234239045 148371 +00:01:26.114480987854 CSME User2 BUP [BUP_PM] srv_maestro_ioctl_cb(): IOCTL_Request 6 PID 4096 TID 13001000 Handle 9. 148372 +00:01:26.114532208557 CSME Normal BUP [BUP_EVT] Post Event 12, isService=1 148373 +00:01:26.114532470601 CSME Normal BUP [BUP_EVT] Adding Event=12. TotalNumEvents=0. CurEventIdx=0. NextEventIdxInList=0. EventLock=0 148374 +00:01:26.114535085348 CSME Normal BUP [BUP_EVT] Handling Event=12. TotalNumEvents=1. CurEventIdx=0. NextEventIdxInList=1. EventLock=0 148375 +00:01:26.114537843323 CSME Normal BUP [BUP_EVT] Framework: Invoking entry callbacks for event - 12 (4 of them) 148376 +00:01:26.114540283915 CSME Normal BUP [BUP_EVT] Framework: Invoking entry callback, event 12 index 0 148377 +00:01:26.114630217997 CSME Normal BUP [BUP_PM] bup_pm_drv_timer_disable(): device = 0x0, state = 0x6 148378 +00:01:26.114630487366 CSME Normal BUP [BUP_PM] bup_pm_drv_timer_disable(): device = 0x1, state = 0x6 148379 +00:01:26.114631978251 CSME Normal BUP [BUP_PM] bup_pm_drv_timer_disable(): device = 0x2, state = 0x6 148380 +00:01:26.114633887431 CSME Normal BUP [BUP_PM] bup_pm_drv_timer_disable(): device = 0x3, state = 0x6 148381 +00:01:26.114636191305 CSME Normal BUP [BUP_PM] bup_pm_drv_timer_disable(): device = 0x5, state = 0x6 148382 +00:01:26.114643458557 CSME Normal BUP [BUP_EVT] Framework: Returning from entry callback, event 12 index 0 148383 +00:01:26.114652232971 CSME Normal BUP [BUP_EVT] Framework: Invoking entry callback, event 12 index 1 148384 +00:01:26.114665108134 CSME Normal BUP [BUP_EVT] Framework: Returning from entry callback, event 12 index 1 148385 +00:01:26.114671830953 CSME Normal BUP [BUP_EVT] Framework: Invoking entry callback, event 12 index 2 148386 +00:01:26.114678820699 CSME Normal BUP [BUP_EVT] Post Event 15, isService=1 148387 +00:01:26.114683027242 CSME Normal BUP [BUP_EVT] Adding Event=15. TotalNumEvents=1. CurEventIdx=0. NextEventIdxInList=1. EventLock=1 148388 +00:01:26.114690274150 CSME Normal BUP [BUP_EVT] Framework: Returning from entry callback, event 12 index 2 148389 +00:01:26.114697027079 CSME Normal BUP [BUP_EVT] Framework: Invoking entry callback, event 12 index 3 148390 +00:01:26.114783037008 CSME User2 BUP [BTG] +++bup_sb_cse_transition_abort 148391 +00:01:26.114863039449 CSME User2 BUP [BTG] ---bup_sb_cse_transition_abort 148392 +00:01:26.114866703186 CSME Normal BUP [BUP_EVT] Framework: Returning from entry callback, event 12 index 3 148393 +00:01:26.114874217834 CSME Normal BUP [BUP_EVT] Framework: Invoking exit callbacks for event - 12 148394 +00:01:26.114875656637 CSME Normal BUP [BUP_EVT] Framework: Invoking exit callback, event 12 index 0 148395 +00:01:26.114930646871 CSME Normal BUP [PTT] BUP PTT CSE Transition Aabort Exit callback entry 148396 +00:01:26.115022630107 CSME User2 BUP [PTT] BUP PTT Allocated Memory Released 148397 +00:01:26.115022796122 CSME Normal BUP [PTT] BUP PTT CSE Transition Aabort Exit callback exit with status:0 148398 +00:01:26.115029874573 CSME Normal BUP [BUP_EVT] Framework: Returning from exit callback, event 12 index 0 148399 +00:01:26.115037416890 CSME Normal BUP [BUP_PM] bup_maestro_post_transition_abort(): Status = 1 148400 +00:01:26.115061012268 CSME Normal BUP [PERF],TS,80860030,0,25,589840,234239716 148401 +00:01:26.115295007223 CSME User1 MAESTRO [POWER] maestro_mark_host_transtion_start pm_cause: 4 148402 +00:01:26.115616315816 CSME User2 MAESTRO [POWER] maestro_zero_boot_progress_data(): clearing the boot progress susram 148403 +00:01:26.116191985575 CSME User1 PMCONTEXT [PmContext] Pm Context: 148404 +00:01:26.116192156474 CSME User1 PMCONTEXT [PmContext] Host State: Src S0 (1) 148405 +00:01:26.116193732808 CSME User1 PMCONTEXT [PmContext] Host State: Dst S5 (5) 148406 +00:01:26.116195896708 CSME User1 PMCONTEXT [PmContext] Cse State: Src CM0 (1) 148407 +00:01:26.116198129781 CSME User1 PMCONTEXT [PmContext] Cse State: Dst CMOFF-PD (6) 148408 +00:01:26.116200203349 CSME User1 PMCONTEXT [PmContext] Boot Type: Normal (1) 148409 +00:01:26.116204360250 CSME User1 PMCONTEXT [PmContext] Cause: Host Power Cycle Reset (4) 148410 +00:01:26.116204580790 CSME User1 PMCONTEXT [PmContext] Attribs: 0x00000000 148411 +00:01:26.116562100321 CSME User1 MAESTRO [POWER] Publishing event 0x20000001 148412 +00:01:26.116956654358 CSME Normal ED [ED] PublishEvent: Event: (0x20000001) Flags(0x00000000). 148413 +00:01:26.116966669006 CSME User2 ED [ED] PE: Immediately Publishing Event (0x20000001). 148414 +00:01:26.117159483134 CSME User1 MAESTRO [POWER] Next State = CM0_EXIT_BEGIN. (4) 148415 +00:01:26.117159698792 CSME User1 MAESTRO [POWER] State Flags = (0x00000111) Policy Flags = (0x00013200) Wait Flags = (0x00000010) 148416 +00:01:26.117182698466 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148417 +00:01:26.117372676493 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 41 client fd: 4) 148418 +00:01:26.117472335510 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 12, minor: 0 148419 +00:01:26.117472647196 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 41... 148420 +00:01:26.117711409403 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 40 client fd: 3) 148421 +00:01:26.117818950907 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 11, minor: 0 148422 +00:01:26.117819256897 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 40... 148423 +00:01:26.118034541728 CSME Normal RAVDM [VDM][LIB] vdm_close: fd = 2 148424 +00:01:26.118232458394 CSME User2 RAVDM [VDM] VdmDrvCloseCallback, ClientHandle = 0, minor = 1 148425 +00:01:26.118610835673 CSME User2 ED [ED] Close Service Handle (29). 148426 +00:01:26.119082203674 CSME User2 PM do_exit for pid 1b1e 148427 +00:01:26.119086588440 CSME Normal KERNEL [KERNEL] process termination begin PID=1b00 148428 +00:01:26.120229103088 CSME Normal KERNEL [KERNEL] process termination end PID=1b00 148429 +00:01:26.120637943420 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148430 +00:01:26.120686367900 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148431 +00:01:26.121344859924 CSME Normal BUS_DRV sys_interrupt_control -disable int for Device 21 , write reg offset 00000044, reg_val 00005005 148432 +00:01:26.121389610087 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148433 +00:01:26.121416433818 CSME Normal HOTHAM Hotham HtmEdCallback: Exiting Hotham! 148434 +00:01:26.121520894267 CSME Normal HOTHAM Hotham HtmEdCallback: Waiting for HTM_EXIT_CMH_THREAD_ACK 148435 +00:01:26.121683389384 CSME Normal HOTHAM hotham_cmh_thread: ActualEventFlags is 0x0, select_status = 0, errno = 0 148436 +00:01:26.121687779032 CSME Normal HOTHAM hotham_cmh_thread:Cleared flags not serviced. ActualEventFlags: 0x8000000 148437 +00:01:26.121689222717 CSME Normal HOTHAM Hotham info: HothamCoreResetSingleSequenceState, clearing SequenceNo: 0 148438 +00:01:26.121693369853 CSME Normal HOTHAM Hotham info: HothamCoreResetSingleSequenceState, clearing SequenceNo: 1 148439 +00:01:26.121693585510 CSME Normal HOTHAM Hotham info: HothamCoreResetSingleSequenceState, clearing SequenceNo: 2 148440 +00:01:26.121696181539 CSME Normal HOTHAM Hotham info: HothamCoreResetSingleSequenceState, clearing SequenceNo: 3 148441 +00:01:26.121698406474 CSME Normal HOTHAM Hotham info: HothamCoreResetSingleSequenceState, clearing SequenceNo: 4 148442 +00:01:26.121700610250 CSME Normal HOTHAM Hotham info: HothamCoreResetSingleSequenceState, clearing SequenceNo: 5 148443 +00:01:26.121702889709 CSME Normal HOTHAM Hotham info: HothamCoreResetSingleSequenceState, clearing SequenceNo: 6 148444 +00:01:26.121705166728 CSME Normal HOTHAM Hotham info: HothamCoreResetSingleSequenceState, clearing SequenceNo: 7 148445 +00:01:26.121707421773 CSME Normal HOTHAM Hotham function entry/exit: HothamCoreResetAllMsgState 148446 +00:01:26.121795833232 CSME Normal HOTHAM Hotham Function entry: HtmDbgHostIfResetDhi 148447 +00:01:26.121796004944 CSME Error HOTHAM [ERROR] Hotham info: HtmDbgHostIfResetDhi, No active connection 148448 +00:01:26.122238587952 CSME User2 STORAGE storage_open_cb: received open for minor = 25 148449 +00:01:26.123120227763 CSME Normal HOTHAM Hotham function entry/exit: HothamCoreAbortAll, status 148 148450 +00:01:26.123120440979 CSME Normal HOTHAM Hotham: Exiting Hotham worker thread started...! 148451 +00:01:26.123136603088 CSME Normal HOTHAM Hotham: Exiting Hotham worker thread done! 148452 +00:01:26.123162193909 CSME Normal HOTHAM Hotham HtmEdCallback: HTM_EXIT_CMH_THREAD_ACK received. ActualEventFlags:0x10000000! 148453 +00:01:26.123162412008 CSME Normal HOTHAM Hotham function entry: HtmHeciContextLock 148454 +00:01:26.123168989970 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 39 client fd: 1) 148455 +00:01:26.123370612691 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 10, minor: 0 148456 +00:01:26.123370921122 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 39... 148457 +00:01:26.123500232646 CSME Normal HOTHAM Hotham function entry: HtmHeciContextUnlock 148458 +00:01:26.123831848043 CSME User2 ED [ED] Close Service Handle (26). 148459 +00:01:26.123901894430 CSME Normal HOTHAM Hotham main: srv_task_newsrvlibver returned 0 148460 +00:01:26.123902110087 CSME Normal HOTHAM Hotham main: About to EXIT, should never reach here 148461 +00:01:26.123941872457 CSME User2 PM do_exit for pid 1e1d 148462 +00:01:26.123943347880 CSME Normal KERNEL [KERNEL] process termination begin PID=1e00 148463 +00:01:26.124217793681 CSME Normal KERNEL [KERNEL] process termination end PID=1e00 148464 +00:01:26.124393747457 CSME User2 STORAGE storage_close_cb: received close for minor = 25 148465 +00:01:26.124824090881 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148466 +00:01:26.124875244853 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148467 +00:01:26.124919678446 CSME Normal DAL_IVM Process deinitialization started 148468 +00:01:26.125128872782 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 37 client fd: 2) 148469 +00:01:26.125271659241 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148470 +00:01:26.125402529195 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 7, minor: 0 148471 +00:01:26.125402840881 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 37... 148472 +00:01:26.125791342672 CSME User1 MAESTRO [SERVICE] maestro_close_cb: service_handle 3. 148473 +00:01:26.127874946187 CSME Normal TLS [TLS_ISO][LIB] tls_isolated_lib_close(): Start 148474 +00:01:26.127916529032 CSME Normal TLS [TLS_ISO][LIB] tls_isolated_lib_close(): End 148475 +00:01:26.127967781474 CSME User2 STORAGE storage_close_cb: received close for minor = 27 148476 +00:01:26.129032502340 CSME Normal DAL_IVM Process deinitialization completed successfully 148477 +00:01:26.129032674052 CSME Normal DAL_IVM Process received an exit event 148478 +00:01:26.129124129944 CSME User2 ED [ED] Close Service Handle (27). 148479 +00:01:26.129316063538 CSME User2 PM do_exit for pid 141c 148480 +00:01:26.129317975159 CSME Normal KERNEL [KERNEL] process termination begin PID=1400 148481 +00:01:26.129558556213 CSME Normal KERNEL [KERNEL] process termination end PID=1400 148482 +00:01:26.130786590881 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148483 +00:01:26.130837801005 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148484 +00:01:26.131661725159 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148485 +00:01:26.132087735087 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 36 client fd: 1) 148486 +00:01:26.132304284566 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 6, minor: 0 148487 +00:01:26.132304595439 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 36... 148488 +00:01:26.132532269592 CSME User1 MAESTRO [SERVICE] maestro_close_cb: service_handle 2. 148489 +00:01:26.132625857646 CSME Normal DAL_LNC Process received an exit event 148490 +00:01:26.132713926493 CSME User2 ED [ED] Close Service Handle (25). 148491 +00:01:26.132805551656 CSME User2 PM do_exit for pid 151b 148492 +00:01:26.132807146708 CSME Normal KERNEL [KERNEL] process termination begin PID=1500 148493 +00:01:26.132958426005 CSME Normal KERNEL [KERNEL] process termination end PID=1500 148494 +00:01:26.133382573140 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148495 +00:01:26.133433747457 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148496 +00:01:26.133783024801 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 35 client fd: 1) 148497 +00:01:26.134082725321 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148498 +00:01:26.134348788961 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 5, minor: 0 148499 +00:01:26.134349100647 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 35... 148500 +00:01:26.134721967672 CSME User1 MAESTRO [SERVICE] maestro_close_cb: service_handle 1. 148501 +00:01:26.134893132223 CSME Normal DAL_SDM Process received an exit event 148502 +00:01:26.134991995341 CSME User2 ED [ED] Close Service Handle (24). 148503 +00:01:26.135084433492 CSME User2 PM do_exit for pid 161a 148504 +00:01:26.135085995178 CSME Normal KERNEL [KERNEL] process termination begin PID=1600 148505 +00:01:26.135353272196 CSME Normal KERNEL [KERNEL] process termination end PID=1600 148506 +00:01:26.135753009338 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148507 +00:01:26.135800641988 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148508 +00:01:26.136055460510 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148509 +00:01:26.136141078186 CSME Normal SIGMA [MAIN_THREAD] srv_task end 148510 +00:01:26.136238220113 CSME User2 ED [ED] Close Service Handle (23). 148511 +00:01:26.136357504781 CSME User1 SIGMA [MAIN_THREAD] end, return code 0 148512 +00:01:26.136388674215 CSME User2 PM do_exit for pid 1d19 148513 +00:01:26.136390105693 CSME Normal KERNEL [KERNEL] process termination begin PID=1d00 148514 +00:01:26.136622198792 CSME Normal KERNEL [KERNEL] process termination end PID=1d00 148515 +00:01:26.136996683655 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148516 +00:01:26.137045862528 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148517 +00:01:26.137083863017 CSME Normal RAVDM [VDM] VDM power flow - START (VdmPmHandler), CSE state from 1 to 6, Host state from 1 to 5, event type = WARN, Event ID = 0x1 148518 +00:01:26.137084272359 CSME User2 RAVDM [VDM] VdmPmStopM0Exit: started 148519 +00:01:26.137141012268 CSME Normal PCI_CFG pci_cfg_register_to_interrupt(): device id 6, sel 0x17, enable 0, optional thread id 0x0 148520 +00:01:26.137204244690 CSME Normal RAVDM [VDM] VDM power flow - DONE (VdmPmHandler) (CSE state from 1 to 6, Host state from 1 to 5, Event ID = 0x1) 148521 +00:01:26.137430728251 CSME Normal RAVDM [VDM] vdm main: vdm process - END 148522 +00:01:26.137479946187 CSME User2 PM do_exit for pid 2318 148523 +00:01:26.137481384989 CSME Normal KERNEL [KERNEL] process termination begin PID=2300 148524 +00:01:26.137713230693 CSME Normal KERNEL [KERNEL] process termination end PID=2300 148525 +00:01:26.137882850647 CSME User2 ED [ED] Close Service Handle (22). 148526 +00:01:26.138186894430 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148527 +00:01:26.138217678935 CSME User2 ED [ED] SendEvent: No Receiver. Skip Send. 148528 +00:01:26.138294862366 CSME Normal TLS [TLS_ISO][SRV] TlsIsolatedServiceEvtCallback(): Start 148529 +00:01:26.139193345439 CSME Normal TLS [TLS_ISO][SRV] TlsIsolatedServiceEvtCallback(): Terminating Process 148530 +00:01:26.139200125224 CSME Normal TLS [TLS_ISO][SRV] TlsIsolatedServiceEvtCallback(): End 148531 +00:01:26.139540987854 CSME User2 ED [ED] Close Service Handle (21). 148532 +00:01:26.139694149475 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148533 +00:01:26.139724159241 CSME User2 PM do_exit for pid 2217 148534 +00:01:26.139725598043 CSME Normal KERNEL [KERNEL] process termination begin PID=2200 148535 +00:01:26.139978247782 CSME Normal KERNEL [KERNEL] process termination end PID=2200 148536 +00:01:26.140373037008 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148537 +00:01:26.140421877340 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148538 +00:01:26.141478386943 CSME User2 FWUPDATE [Fwupdate]: Calling FwuAppProcessEvents. 148539 +00:01:26.141625161845 CSME User2 PM do_exit for pid 2015 148540 +00:01:26.141626603088 CSME Normal KERNEL [KERNEL] process termination begin PID=2000 148541 +00:01:26.141850828349 CSME Normal KERNEL [KERNEL] process termination end PID=2000 148542 +00:01:26.141883657125 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148543 +00:01:26.142016499736 CSME User2 FWUPDATE [Fwupdate]: Calling FwuAppProcessPmEvents on event_id: 1 148544 +00:01:26.142016720276 CSME User2 FWUPDATE [Fwupdate]: Calling ed_event_processing_done on event_id: 1 148545 +00:01:26.142144026591 CSME User2 FWUPDATE [Fwupdate]: is_pm_exit true. Exiting select loop. 148546 +00:01:26.142144203186 CSME User2 FWUPDATE [Fwupdate]: Exiting... 148547 +00:01:26.142146269430 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 33 client fd: 0) 148548 +00:01:26.142162426656 CSME User2 ED [ED] Close Service Handle (19). 148549 +00:01:26.142197269592 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148550 +00:01:26.142306252340 CSME User2 HECI [HECILIB] heci_get_client_fd() return 3 for client address 9 148551 +00:01:26.142312816467 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 9 client fd: 3) 148552 +00:01:26.142461722717 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 2, minor: 0 148553 +00:01:26.142462036845 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 33... 148554 +00:01:26.142488504944 CSME User2 ED [ED] SendEvent: No Receiver. Skip Send. 148555 +00:01:26.142704872131 CSME User2 HECI [HECI1] heci_ioctl_callback(): Request 7 for service handle 3 by TID 33001800, minor 0 148556 +00:01:26.142706708069 CSME Normal HECI [HECI1] heci_handle_close_connection(): Connection 1 148557 +00:01:26.142768041890 CSME Normal HECI [HECI1] heci_conn_close(): Closing connection 1, CSE Addr 9, Host Addr 0 148558 +00:01:26.142867696025 CSME User2 HECI [HECILIB] heci_lib_return_request_to_user(): return buffer (XX) to client (client fd: 3 connection id: 65535) 148559 +00:01:26.142868061422 CSME User1 POLICY [HI] hi_heci_async_complete() data size = 0 148560 +00:01:26.142872475484 CSME User2 HECI [HECILIB] heci_is_fixed_address_client() return 1 for client address 9 (fd 3) 148561 +00:01:26.142952458394 CSME User2 ED [ED] Close Service Handle (18). 148562 +00:01:26.143182024638 CSME User2 PM do_exit for pid 1214 148563 +00:01:26.143183460999 CSME Normal KERNEL [KERNEL] process termination begin PID=1200 148564 +00:01:26.143320421448 CSME Normal KERNEL [KERNEL] process termination end PID=1200 148565 +00:01:26.143406414286 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148566 +00:01:26.143436014709 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 3, minor: 0 148567 +00:01:26.143436318258 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 9... 148568 +00:01:26.143669644267 CSME User2 HECI [HECILIB] heci_get_client_fd() return 4 for client address 34 148569 +00:01:26.143671644592 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 34 client fd: 4) 148570 +00:01:26.143894517314 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148571 +00:01:26.143934904683 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 4, minor: 0 148572 +00:01:26.143935210673 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 34... 148573 +00:01:26.144886835836 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148574 +00:01:26.144966784566 CSME Normal ICC [ICC] Received PM event... 148575 +00:01:26.145085225321 CSME User2 ED [ED] Close Service Handle (20). 148576 +00:01:26.145121181539 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148577 +00:01:26.145598607483 CSME User2 STORAGE storage_close_cb: received close for minor = 27 148578 +00:01:26.145922340393 CSME User2 PM do_exit for pid 1813 148579 +00:01:26.145924176331 CSME Normal KERNEL [KERNEL] process termination begin PID=1800 148580 +00:01:26.146140803935 CSME Normal KERNEL [KERNEL] process termination end PID=1800 148581 +00:01:26.146644024150 CSME Normal HECI [HECI2] heci_close_callback(): CLOSE: service handle 65, minor: 0 148582 +00:01:26.146695627340 CSME Normal HECI [HECI2] heci_handle_client_close(): Closing connections for CSE Addr 15... 148583 +00:01:26.146695891825 CSME Warning HECI [HECI2] heci_handle_client_close(): Connection for CSE Addr 15 is active (2)! 148584 +00:01:26.146698210348 CSME Normal HECI [HECI2] heci_conn_close(): Closing connection 0, CSE Addr 15, Host Addr 0 148585 +00:01:26.146823159078 CSME Warning BUP bup_close_cb is called, service handle=27 148586 +00:01:26.147219571025 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148587 +00:01:26.147259982808 CSME User2 ED [ED] Close Service Handle (11). 148588 +00:01:26.147489997457 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148589 +00:01:26.147567634989 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 8 client fd: 0) 148590 +00:01:26.147616852926 CSME User2 HECI [HECI1] heci_ioctl_callback(): Request 7 for service handle 0 by TID 3d002100, minor 0 148591 +00:01:26.147618448792 CSME Normal HECI [HECI1] heci_handle_close_connection(): Connection 0 148592 +00:01:26.147622862854 CSME Normal HECI [HECI1] heci_conn_close(): Closing connection 0, CSE Addr 8, Host Addr 0 148593 +00:01:26.147694112854 CSME User2 HECI [HECILIB] heci_lib_return_request_to_user(): return buffer (XX) to client (client fd: 0 connection id: 65535) 148594 +00:01:26.147694377340 CSME Normal ICC [ICC] HECI async_complete callback... 148595 +00:01:26.147696183980 CSME Warning ICC [ICC] HECI async_complete status failure... 148596 +00:01:26.147700487366 CSME User2 HECI [HECILIB] heci_is_fixed_address_client() return 1 for client address 8 (fd 0) 148597 +00:01:26.147702012431 CSME User2 HECI [HECILIB] heci_read_async(): Start (address: 8 client fd: 0 connection id: 65535 buffer: (XX)) 148598 +00:01:26.147704413961 CSME Warning HECI [HECILIB] heci_read_async() failed because connection started close flow (connection id: 65535 buffer: (XX)) 148599 +00:01:26.147899256897 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 0, minor: 0 148600 +00:01:26.147899571025 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 8... 148601 +00:01:26.148134490458 CSME Normal HECI [HECILIB] heci_destroy_client(): Start (address: 32 client fd: 2) 148602 +00:01:26.148254872131 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 1, minor: 0 148603 +00:01:26.148255191142 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 32... 148604 +00:01:26.148422384338 CSME User2 PM do_exit for pid 2112 148605 +00:01:26.148424306539 CSME Normal KERNEL [KERNEL] process termination begin PID=2100 148606 +00:01:26.148631169332 CSME Normal KERNEL [KERNEL] process termination end PID=2100 148607 +00:01:26.148893982646 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148608 +00:01:26.148943132223 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148609 +00:01:26.148986673889 CSME User2 PTT OS Event Callback:: event_group=2; event_id=1 148610 +00:01:26.148986938375 CSME User2 PTT OS Event Callback: power event 148611 +00:01:26.148988321025 CSME User2 PTT Handle Pwr Event: Event 0x01, Cause 0x04 148612 +00:01:26.149197901917 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148613 +00:01:26.149322722880 CSME User2 PTT PTT Host Interface Exiting due to power transitions or error, result=0x0 148614 +00:01:26.149378335673 CSME Normal KERNEL [KERNEL] RunProfileControl PG override process request state=0, global request state=1, PG in progress=0, ModuleId=808600c2 148615 +00:01:26.149378693746 CSME Normal KERNEL [KERNEL] RunProfileControl PG override all processes bitmap=00000000000000000000000000002000 148616 +00:01:26.149383134664 CSME Normal PCI_CFG pci_cfg_register_to_interrupt(): device id 4, sel 0x17, enable 0, optional thread id 0x0 148617 +00:01:26.149396752014 CSME Normal PCI_CFG pci_cfg_set_pme(): device id 4, sel 0xf, enable 1 148618 +00:01:26.149446725159 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148619 +00:01:26.149723132223 CSME Normal SKUMGR [SKU_MGR] sku_mgr_process_events: PM Event Received 148620 +00:01:26.149723303935 CSME Normal SKUMGR [SKU_MGR] sku_mgr_process_pm_events: src_cse_state:1 dst_cse_state:6 148621 +00:01:26.149772771708 CSME User1 POLICY [Policy] process_cpu_replacement_pm_events 148622 +00:01:26.149968784078 CSME Normal CRYPTO [CRYPTO] open on slow thread. minor = 0, caller = 0x16001311, sh = 128 148623 +00:01:26.150583230693 CSME Normal PWR_DRV [PWR] Closing PM Ops (4) 148624 +00:01:26.150946940816 CSME User1 PWR_DRV [PWR] CloseCB Called. Service Handle (1). 148625 +00:01:26.150983781637 CSME User2 STORAGE storage_close_cb: received close for minor = 29 148626 +00:01:26.151119769592 CSME User2 PTT FTPM Host Interface End completed successfully 148627 +00:01:26.151195406799 CSME Normal BUP [PTT] BUP PTT Unregister callback entry with context size:0 148628 +00:01:26.151216497294 CSME User2 BUP [PTT] BUP PTT Allocated Memory Released 148629 +00:01:26.151216666565 CSME Normal BUP [PTT] BUP PTT Unregister callback exit status:0 148630 +00:01:26.151395722555 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148631 +00:01:26.151431306051 CSME User2 PM do_exit for pid b10 148632 +00:01:26.151432737528 CSME Normal KERNEL [KERNEL] process termination begin PID=0b00 148633 +00:01:26.151645602926 CSME Normal KERNEL [KERNEL] process termination end PID=0b00 148634 +00:01:26.151690394592 CSME User2 HECI [HECI3] heci_handle_pm_event(): PM Event due to 4, moving from (CSE:1, Host:1) to (CSE:6, Host:5) 148635 +00:01:26.151690847880 CSME User1 HECI [HECI3] Power state changed: Stop event (0x00000001), unloading HECI driver! 148636 +00:01:26.151816865133 CSME Warning BUP bup_close_cb is called, service handle=26 148637 +00:01:26.151856480204 CSME User2 ED [ED] Close Service Handle (12). 148638 +00:01:26.152098880107 CSME User1 PTT Process ending with status=0 148639 +00:01:26.152196441142 CSME User2 PM do_exit for pid 1311 148640 +00:01:26.152197884827 CSME Normal KERNEL [KERNEL] process termination begin PID=1300 148641 +00:01:26.152455188700 CSME Normal KERNEL [KERNEL] process termination end PID=1300 148642 +00:01:26.152482783915 CSME User1 MAESTRO [SERVICE] maestro_close_cb: service_handle 0. 148643 +00:01:26.152499617411 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 8, minor: 0 148644 +00:01:26.152501131897 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 7... 148645 +00:01:26.152503301493 CSME Warning HECI [HECI1] heci_handle_client_close(): Connection for CSE Addr 7 is active (2)! 148646 +00:01:26.152507625224 CSME Normal HECI [HECI1] heci_conn_close(): Closing connection 2, CSE Addr 7, Host Addr 0 148647 +00:01:26.152544421285 CSME Warning BUP bup_close_cb is called, service handle=3 148648 +00:01:26.152854090881 CSME User2 ED [ED] Close Service Handle (17). 148649 +00:01:26.153002470601 CSME Normal HECI [HECI1] heci_close_callback(): CLOSE: service handle 9, minor: 0 148650 +00:01:26.153002781474 CSME Normal HECI [HECI1] heci_handle_client_close(): Closing connections for CSE Addr 38... 148651 +00:01:26.153025276591 CSME Warning BUP bup_close_cb is called, service handle=4 148652 +00:01:26.153331271871 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148653 +00:01:26.153620541890 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148654 +00:01:26.153794597880 CSME Warning BUP bup_close_cb is called, service handle=25 148655 +00:01:26.153944195862 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148656 +00:01:26.154058289286 CSME User2 HECI [HECI3] heci_read_csr(): Read 0x80000001 from HECI3_CSE_CSR. 148657 +00:01:26.154058597717 CSME User2 HECI [HECI3] heci_write_csr(): Wrote 0x80000031 to HECI3_CSE_CSR 148658 +00:01:26.154061179098 CSME User2 HECI [HECI3] heci_read_csr(): Read 0x80000011 from HECI3_CSE_CSR. 148659 +00:01:26.154076664124 CSME Normal PCI_CFG pci_cfg_register_to_interrupt(): device id 2, sel 0x67, enable 0, optional thread id 0x0 148660 +00:01:26.154091053772 CSME Normal PCI_CFG pci_cfg_set_pme(): device id 2, sel 0x5f, enable 1 148661 +00:01:26.154304725647 CSME User2 HECI [HECI3] heci_device_thread(): Driver thread exit from service library loop with status 0, errno 0 148662 +00:01:26.154305247294 CSME User2 HECI [HECI3] heci_read_csr(): Read 0x80000011 from HECI3_CSE_CSR. 148663 +00:01:26.154308105367 CSME Warning HECI [HECI3] heci_make_unready() 148664 +00:01:26.154312303772 CSME User2 HECI [HECI3] heci_set_cse_ig(): Wrote 0x80000005 to HECI3_CSE_CSR 148665 +00:01:26.154313823140 CSME User2 HECI [HECI3] heci_read_h_pci_csr(): Read 0x00400040 from HECI3_H_PCI_CSR 148666 +00:01:26.154316399638 CSME User2 HECI [HECI3] heci_write_h_pci_csr(): Wrote 0x00400000 to HECI3_H_PCI_CSR 148667 +00:01:26.154320728251 CSME User2 HECI [HECI3] heci_read_csr(): Read 0x80000001 from HECI3_CSE_CSR. 148668 +00:01:26.154321541239 CSME User2 HECI [HECI3] heci_write_csr(): Wrote 0x80000000 to HECI3_CSE_CSR 148669 +00:01:26.154381962789 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148670 +00:01:26.154451612854 CSME User2 HECI [HECI2] heci_handle_pm_event(): PM Event due to 4, moving from (CSE:1, Host:1) to (CSE:6, Host:5) 148671 +00:01:26.154452066142 CSME User1 HECI [HECI2] Power state changed: Stop event (0x00000001), unloading HECI driver! 148672 +00:01:26.154463614807 CSME User2 HECI [HECI2] heci_read_csr(): Read 0x80000001 from HECI2_CSE_CSR. 148673 +00:01:26.154463926493 CSME User2 HECI [HECI2] heci_write_csr(): Wrote 0x80000031 to HECI2_CSE_CSR 148674 +00:01:26.154468411357 CSME User2 HECI [HECI2] heci_read_csr(): Read 0x80000011 from HECI2_CSE_CSR. 148675 +00:01:26.154482407125 CSME Normal PCI_CFG pci_cfg_register_to_interrupt(): device id 1, sel 0x57, enable 0, optional thread id 0x0 148676 +00:01:26.154495210673 CSME Normal PCI_CFG pci_cfg_set_pme(): device id 1, sel 0x4f, enable 1 148677 +00:01:26.154525161845 CSME User2 ED [ED] Close Service Handle (15). 148678 +00:01:26.154702816467 CSME User2 HECI [HECI2] heci_device_thread(): Driver thread exit from service library loop with status 0, errno 0 148679 +00:01:26.154703124898 CSME User2 HECI [HECI2] heci_read_csr(): Read 0x80000011 from HECI2_CSE_CSR. 148680 +00:01:26.154705688375 CSME Warning HECI [HECI2] heci_make_unready() 148681 +00:01:26.154708195699 CSME User2 HECI [HECI2] heci_set_cse_ig(): Wrote 0x80000005 to HECI2_CSE_CSR 148682 +00:01:26.154714798889 CSME User2 HECI [HECI2] heci_read_h_pci_csr(): Read 0x00400040 from HECI2_H_PCI_CSR 148683 +00:01:26.154715113017 CSME User2 HECI [HECI2] heci_write_h_pci_csr(): Wrote 0x00400000 to HECI2_H_PCI_CSR 148684 +00:01:26.154717429098 CSME User2 HECI [HECI2] heci_read_csr(): Read 0x80000001 from HECI2_CSE_CSR. 148685 +00:01:26.154721581116 CSME User2 HECI [HECI2] heci_write_csr(): Wrote 0x80000000 to HECI2_CSE_CSR 148686 +00:01:26.154776438700 CSME Normal HECI [HECI3] Driver worker thread(0x10000d0f) exit with status 0 148687 +00:01:26.154797637431 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148688 +00:01:26.154929232483 CSME User2 HECI [HECI1] heci_handle_pm_event(): PM Event due to 4, moving from (CSE:1, Host:1) to (CSE:6, Host:5) 148689 +00:01:26.154929693909 CSME User1 HECI [HECI1] Power state changed: Stop event (0x00000001), unloading HECI driver! 148690 +00:01:26.155338899638 CSME User2 HECI [HECI1] heci_remove_pg_override(): Start (pg_override_mask:0x00000001) 148691 +00:01:26.155339161682 CSME Normal HECI [HECI1] Remove PG override! 148692 +00:01:26.155348093160 CSME Normal KERNEL [KERNEL] RunProfileControl PG override process request state=0, global request state=0, PG in progress=0, ModuleId=8086000f 148693 +00:01:26.155348456116 CSME Normal KERNEL [KERNEL] RunProfileControl PG override all processes bitmap=00000000000000000000000000000000 148694 +00:01:26.155355712789 CSME User2 HECI [HECI1] heci_read_csr(): Read 0x80000008 from HECI1_CSE_CSR. 148695 +00:01:26.155357465719 CSME User2 HECI [HECI1] heci_write_csr(): Wrote 0x80000038 to HECI1_CSE_CSR 148696 +00:01:26.155361703186 CSME User2 HECI [HECI1] heci_read_csr(): Read 0x80000010 from HECI1_CSE_CSR. 148697 +00:01:26.155376124573 CSME Normal PCI_CFG pci_cfg_register_to_interrupt(): device id 0, sel 0x47, enable 0, optional thread id 0x0 148698 +00:01:26.155388105367 CSME Normal PCI_CFG pci_cfg_set_pme(): device id 0, sel 0x3f, enable 1 148699 +00:01:26.155421320699 CSME User2 ED [ED] Close Service Handle (14). 148700 +00:01:26.155619762268 CSME User2 HECI [HECI1] heci_device_thread(): Driver thread exit from service library loop with status 0, errno 0 148701 +00:01:26.155620073140 CSME User2 HECI [HECI1] heci_read_csr(): Read 0x80000010 from HECI1_CSE_CSR. 148702 +00:01:26.155622882385 CSME Warning HECI [HECI1] heci_make_unready() 148703 +00:01:26.155626953023 CSME User2 HECI [HECI1] heci_set_cse_ig(): Wrote 0x80000004 to HECI1_CSE_CSR 148704 +00:01:26.155628636780 CSME User2 HECI [HECI1] heci_read_h_pci_csr(): Read 0x08000000 from HECI1_H_PCI_CSR 148705 +00:01:26.155632943420 CSME User2 HECI [HECI1] heci_write_h_pci_csr(): Wrote 0x08000000 to HECI1_H_PCI_CSR 148706 +00:01:26.155634465230 CSME User2 HECI [HECI1] heci_read_csr(): Read 0x80000000 from HECI1_CSE_CSR. 148707 +00:01:26.155636603088 CSME User2 HECI [HECI1] heci_write_csr(): Wrote 0x80000000 to HECI1_CSE_CSR 148708 +00:01:26.155664943746 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148709 +00:01:26.155693318583 CSME Normal HECI [HECI2] Driver worker thread(0x0f000d0f) exit with status 0 148710 +00:01:26.155810115458 CSME User2 ED [ED] Close Service Handle (13). 148711 +00:01:26.155980073140 CSME Normal HECI [HECI1] Driver worker thread(0x0e000d0f) exit with status 0 148712 +00:01:26.156454536845 CSME User1 HECI [HECI] HECI FW Device Driver finished with status 0 148713 +00:01:26.156483374736 CSME User2 PM do_exit for pid d0f 148714 +00:01:26.156484811096 CSME Normal KERNEL [KERNEL] process termination begin PID=0d00 148715 +00:01:26.156689740295 CSME Normal KERNEL [KERNEL] process termination end PID=0d00 148716 +00:01:26.157027821350 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148717 +00:01:26.157075021871 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148718 +00:01:26.157287441305 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148719 +00:01:26.157417833557 CSME Normal IPC [IPC_DRV] ipc_drv_close_cb fd 4 p_1:c_0 148720 +00:01:26.157418141988 CSME Normal IPC [IPC_DRV] ipc_destroy_cli_queues c_0 148721 +00:01:26.157498207906 CSME User2 IPC [IPC_DRV] ipc_drv_close_cb: CHECKING PG OVERRIDE for p_1 [0] 148722 +00:01:26.157656935933 CSME User1 PWR_DRV [PWR] Sent to PMC. Offset = 0x210, Value = 0x0. 148723 +00:01:26.157833328349 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148724 +00:01:26.157896502177 CSME Normal IPC [IPC_DRV] ipc_drv_evt_cb: event_grp = 2 evt_id 1 148725 +00:01:26.157896772359 CSME Normal IPC [IPC_DRV] ipc_drv_handle_pwr_event: Event 0x1, src_cse_state 0x1, dst_cse_state 0x6 cause 0x4 148726 +00:01:26.157904497782 CSME Normal PCI_CFG pci_cfg_set_pme(): device id 8, sel 0x7, enable 0 148727 +00:01:26.157906983134 CSME Normal PCI_CFG pci_cfg_set_pme(): PME is already set, skipping... 148728 +00:01:26.157911298726 CSME Normal PCI_CFG pci_cfg_register_to_interrupt(): device id 8, sel 0xf, enable 0, optional thread id 0x0 148729 +00:01:26.158095230204 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148730 +00:01:26.158176404521 CSME Normal PCI_CFG pci_cfg_register_to_interrupt(): device id 7, sel 0xf, enable 0, optional thread id 0x0 148731 +00:01:26.158287613017 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148732 +00:01:26.158316789449 CSME Normal FPF Received PM Event with event ID 1 148733 +00:01:26.158317005107 CSME Normal FPF Terminating FPF process 148734 +00:01:26.158400345764 CSME User2 PM do_exit for pid 1107 148735 +00:01:26.158401935933 CSME Normal KERNEL [KERNEL] process termination begin PID=1100 148736 +00:01:26.158589936422 CSME Normal KERNEL [KERNEL] process termination end PID=1100 148737 +00:01:26.158721152242 CSME User2 STORAGE storage_close_cb: received close for minor = 24 148738 +00:01:26.158771516825 CSME User2 STORAGE storage_close_cb: received close for minor = 25 148739 +00:01:26.158819951070 CSME User2 ED [ED] Close Service Handle (7). 148740 +00:01:26.158873587952 CSME User2 STORAGE storage_close_cb: received close for minor = 8 148741 +00:01:26.159018416239 CSME Warning BUP bup_close_cb is called, service handle=22 148742 +00:01:26.159281249898 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148743 +00:01:26.159328865458 CSME User2 ED [ED] Notifying Send Event Receiver PID (1280) Handle (5). 148744 +00:01:26.159371271871 CSME User2 STORAGE storage_ed_cb: received event received_notify_cause = 1, received_group = 2 148745 +00:01:26.159371526591 CSME User2 STORAGE storage_ed_cb: received_id = 1, received cause = 4, dst_cse_state = 6 148746 +00:01:26.159520855204 CSME User2 ED [ED] ReceiveNotifyConsumer: Receiver Call: Cause: (1) Event: (0x10000008). 148747 +00:01:26.159593193258 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148748 +00:01:26.159700465393 CSME Normal BUS_DRV sys_interrupt_control -disable int for Device 20 , write reg offset 00000044, reg_val 00005005 148749 +00:01:26.160106463114 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148750 +00:01:26.160135627340 CSME User1 BUS_DRV PM_CSE_STATE_CMOFF_PD entry 148751 +00:01:26.160135799052 CSME User1 BUS_DRV pwr_state_cm0_to_cmoff() 148752 +00:01:26.160196460673 CSME Error BUS_DRV Device 21 has wrong power-state. curr_power_state = 0, expected 3 148753 +00:01:26.160217661845 CSME Normal BUS_DRV Device 21 got corrected curr_power_state = 3, expected 3 148754 +00:01:26.160313602600 CSME User2 ED [ED] ReceiveNotifyConsumer: Consumer Call: Cause: (1) Event: (0x20000001). 148755 +00:01:26.160343176168 CSME User2 CRYPTO [CRYPTO] Received power transition cause:0x4, dst_state:0x6 148756 +00:01:26.160532000224 CSME User2 67 Corrupt message (too short) raw:6302430210003E00430002008680