Contribute
Register

[SUCCESS] Gigabyte Designare Z390 (Thunderbolt 3) + i7-9700K + AMD RX 580

boot verbose and then take a pic
same screen nothing is happening even after using -v argument in clover. trying to reinstall it from scratch and i hope this time its work will update after reinstalling
 
Hi @mango1122
in case of you have XHC2 (under DSB2) shared with XHC (main USB), you have to do same test as @CaseySJ Here with Results Here
Also, you confirm that your motherboard is ASRock Z390 Phantom ?
I tried the test and doesn't look like I need it - which is good news and bad.
Bad because the port does not detect any USBC device plugged in (which is a battle for another day and another thread).
I am waiting for a TB dock to test functionality.
Thank you being patient with me.

Motherboard is ASRock Z390 PHANTOM GAMING-ITX/AC
 

Attachments

  • Capture.PNG
    Capture.PNG
    106.9 KB · Views: 72
  • Capture2.PNG
    Capture2.PNG
    108.2 KB · Views: 73
I tried the test and doesn't look like I need it - which is good news and bad.
Bad because the port does not detect any USBC device plugged in (which is a battle for another day and another thread).
I am waiting for a TB dock to test functionality.
Thank you being patient with me.

Motherboard is ASRock Z390 PHANTOM GAMING-ITX/AC

OK ! Referring to your pics, You don't have a companion with XHC2.

Bad news is that on your first request post you had send me DSDT of a GIGABYTE Z390 Ultra with GC-Alpine Ridge.
 
Last edited:
Hi @CaseySJ
Nice to view NHI0 and NH00 for full thunderbolt tree !

About XHC2, I have same result with normal operating of USB3.1Gen2 like you can see on my previous Log (Plug/Unplug)

On your ACPIDebug log, you have the following lines :
...
2020-01-28 18:32:13.910205-0800 localhost kernel[0]: (kernel) ACPIDebug: "PCED UPSB- Wait for config space..."
2020-01-28 18:32:12.434094-0800 localhost kernel[0]: (kernel) ACPIDebug: "PCED XHC2- Wait for config space..."
...
I don't really understand ! All ACPI Debug begin at :
2020-01-28 18:32:13.834288-0800 localhost kernel[0]: (kernel) ACPIDebug: Version 0.1.4 starting on OS X Darwin 19.2.
Hello @Elias64Fr,

I spent several hours last night testing a more instrumented version of the SSDT (i.e. with more logging). Some interesting findings:

After adding these extra logs to ICMB ...

\RMDT.P1 (Concatenate ("UPSB- Read VID/DID =", \_SB.PCI0.RP05.UPSB.AVND))
\RMDT.P1 (Concatenate ("DSB0- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB0.AVND))
\RMDT.P1 (Concatenate ("NHI0- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB0.NHI0.AVND))
\RMDT.P1 (Concatenate ("Global NH00- Read VID/DID =", NH00))
\RMDT.P1 (Concatenate ("DSB2- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB2.AVND))
\RMDT.P1 (Concatenate ("XHC2- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB2.XHC2.AVND))


...the UPSB.PCED method did not work properly. This method seemed to hang inside this section:

Screen Shot 2020-01-29 at 3.31.08 AM.png


The while ((Timer <= Local5)) loop would never exit when ICMB was modified with extra logging information. As a result, UPSB would hang. I would see PCED UPSB- Wait for config space, but I would not see UPSB PCED- Read VID/DID? So it hanged inside the Timer loop. Because of hanged UPSB, GPE _E17 was not issued, but I could mount and unmount USB 3.1 flash disks easily and reliably every time. No crash with hot plug of either USB-C or TB3 device. But TB3 devices of course would not work.

After removing all the extra logging lines from ICMB, it "seems" that the full TNODE/TBUS is trying to activate when a TB device is connected at boot, but macOS GUI hangs about 5 seconds after logging in. Nevertheless, we can see extract the system logs using Terminal. Those ACPI logs are more comprehensive now, in the spoiler below.
Code:
casey@Caseys-iMac ~ % log show --predicate "processID == 0" --start "2020-01-29 03:00:00" | grep ACPIDebug
2020-01-29 03:16:09.905749-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: Version 0.1.4 starting on OS X Darwin 19.2.
2020-01-29 03:16:09.905853-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x54039854
2020-01-29 03:16:09.905922-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe00e4000
2020-01-29 03:16:09.905990-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0600000
2020-01-29 03:16:09.906056-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0700000
2020-01-29 03:16:09.906125-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0708000
2020-01-29 03:16:09.906191-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0710000
2020-01-29 03:16:09.906259-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0720000
2020-01-29 03:16:09.906326-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0800000
2020-01-29 03:16:09.906392-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_INI Root Port"
2020-01-29 03:16:09.906457-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICMBoot"
2020-01-29 03:16:09.906523-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Tbt TWIN set to One"
2020-01-29 03:16:09.906589-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Tbt Force Power with FPG0"
2020-01-29 03:16:09.906825-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Up Stream VID/DID =0000000015EA8086"
2020-01-29 03:16:09.906892-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Delay to appear = 182 us"
2020-01-29 03:16:09.906957-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICMS"
2020-01-29 03:16:09.907023-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_0="
2020-01-29 03:16:09.907088-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xffffffff
2020-01-29 03:16:09.907154-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI called"
2020-01-29 03:16:09.907221-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI RP OK!"
2020-01-29 03:16:09.907287-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI UPSB OK!"
2020-01-29 03:16:09.907498-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 0 done"
2020-01-29 03:16:09.907566-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 3 done"
2020-01-29 03:16:09.907632-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 4 done"
2020-01-29 03:16:09.907697-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 5 done"
2020-01-29 03:16:09.907763-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI DPs done"
2020-01-29 03:16:09.907829-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI0 all done"
2020-01-29 03:16:09.907895-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI End"
2020-01-29 03:16:09.907961-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_1="
2020-01-29 03:16:09.908027-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a1
2020-01-29 03:16:09.908092-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_2="
2020-01-29 03:16:09.908157-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a1
2020-01-29 03:16:09.908223-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_3="
2020-01-29 03:16:09.908288-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a7
2020-01-29 03:16:09.908353-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800003f5
2020-01-29 03:16:09.908419-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_4="
2020-01-29 03:16:09.908485-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a7
2020-01-29 03:16:09.908550-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ISTA="
2020-01-29 03:16:09.908615-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800003f5
2020-01-29 03:16:09.963937-0800 0x76       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS0 RP05"
2020-01-29 03:16:09.964210-0800 0x76       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCEU RP05"
2020-01-29 03:16:09.964447-0800 0x76       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCEU RP05- Put upstream bridge back into D0 "
2020-01-29 03:16:09.984442-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS0 UPSB"
2020-01-29 03:16:09.984854-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB"
2020-01-29 03:16:09.985290-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED - enable GPIO"
2020-01-29 03:16:09.985708-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants on"
2020-01-29 03:16:09.986028-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants on"
2020-01-29 03:16:09.986422-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants on"
2020-01-29 03:16:09.986539-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT forced on"
2020-01-29 03:16:09.986748-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB forced on"
2020-01-29 03:16:09.987167-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be on"
2020-01-29 03:16:09.987521-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be on"
2020-01-29 03:16:09.987628-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is on"
2020-01-29 03:16:09.987709-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- restored flag, THUNDERBOLT_PCI_LINK_MGMT_DEVICE.PRSR"
2020-01-29 03:16:09.987968-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:09.988359-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- Wait for config space..."
2020-01-29 03:16:09.988812-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- Wait for config space..."
2020-01-29 03:16:09.989207-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- Read VID/DID =0000000015EA8086"
2020-01-29 03:16:09.989589-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UPSB PCED- Read VID/DID? 0000000000000001"
2020-01-29 03:16:09.989913-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:09.990371-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:08.773376-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774156-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x1
2020-01-29 03:16:08.774410-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774641-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x1
2020-01-29 03:16:11.862298-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST NHI0"
2020-01-29 03:16:11.862514-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST UPSB called with arg: 0000000000000000"
2020-01-29 03:16:11.862662-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UPSB UMPE"
2020-01-29 03:16:12.955608-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:12.956088-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:12.956257-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:12.956400-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:13.255990-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 XHC2, Nothing to do!"
2020-01-29 03:16:13.257569-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 DSB2"
2020-01-29 03:16:13.257674-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2"
2020-01-29 03:16:13.257775-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF DSB2"
2020-01-29 03:16:13.257875-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2- Put upstream bridge into D3"
2020-01-29 03:16:13.257976-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2- Set link disable on upstream bridge"
2020-01-29 03:16:13.258077-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2- Wait for link to drop..."
2020-01-29 03:16:14.265788-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2 - disable GPIO"
2020-01-29 03:16:14.265894-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants on"
2020-01-29 03:16:14.265996-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants on"
2020-01-29 03:16:14.266098-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants off"
2020-01-29 03:16:14.266200-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT forced on"
2020-01-29 03:16:14.266302-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB allows off"
2020-01-29 03:16:14.266403-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be on"
2020-01-29 03:16:14.266507-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be off"
2020-01-29 03:16:14.266612-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is on"
2020-01-29 03:16:19.145646-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST NHI0"
2020-01-29 03:16:19.145796-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST UPSB called with arg: 0000000000000000"
2020-01-29 03:16:34.977415-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "RTPC NHI0 called with arg: 0000000000000000"
2020-01-29 03:16:34.977818-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 NHI0, Nothing to do!"
2020-01-29 03:16:34.978928-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 DSB0"
2020-01-29 03:16:34.979009-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0"
2020-01-29 03:16:34.979088-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF DSB0"
2020-01-29 03:16:34.979167-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- Put upstream bridge into D3"
2020-01-29 03:16:34.979246-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- Set link disable on upstream bridge"
2020-01-29 03:16:34.979324-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- Wait for link to drop..."
2020-01-29 03:16:35.982667-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- disable GPIO"
2020-01-29 03:16:35.982749-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants on"
2020-01-29 03:16:35.982829-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants off"
2020-01-29 03:16:35.982907-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants off"
2020-01-29 03:16:35.982986-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT allows off"
2020-01-29 03:16:35.983064-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB allows off"
2020-01-29 03:16:35.983142-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be on"
2020-01-29 03:16:35.983220-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be on"
2020-01-29 03:16:35.983298-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is on"
2020-01-29 03:16:35.983814-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 UPSB Nothing to do!"
2020-01-29 03:16:35.987382-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 RP05"
2020-01-29 03:16:35.987467-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF RP05"
2020-01-29 03:16:35.987550-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 Calling CTBT"
2020-01-29 03:16:35.987633-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT"
2020-01-29 03:16:35.987715-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT - TBT domain is enabled"
2020-01-29 03:16:35.987796-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:35.987876-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:35.987957-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Read Value1"
2020-01-29 03:16:35.988038-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:35.988119-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Write Value1"
2020-01-29 03:16:35.988205-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x4000000
2020-01-29 03:16:35.988286-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOW In"
2020-01-29 03:16:35.988367-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOW Out"
2020-01-29 03:16:35.988449-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:35.988529-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:35.988609-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Read Value2"
2020-01-29 03:16:35.988690-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x1c000000
2020-01-29 03:16:35.988771-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Success"
2020-01-29 03:16:35.988852-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT - Set CP_ACK_POWERDOWN_OVERRIDE"
2020-01-29 03:16:35.988935-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 Calling PCDA"
2020-01-29 03:16:35.989017-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05"
2020-01-29 03:16:35.989098-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF RP05"
2020-01-29 03:16:35.989179-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- Put upstream bridge into D3"
2020-01-29 03:16:35.989262-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- Set link disable on upstream bridge"
2020-01-29 03:16:35.989345-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- Wait for link to drop..."
2020-01-29 03:16:36.062129-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- No link activity"
2020-01-29 03:16:36.062215-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- disable GPIO"
2020-01-29 03:16:36.062297-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants off"
2020-01-29 03:16:36.062379-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants off"
2020-01-29 03:16:36.062460-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants off"
2020-01-29 03:16:36.062542-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT allows off"
2020-01-29 03:16:36.062624-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB allows off"
2020-01-29 03:16:36.062705-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be off"
2020-01-29 03:16:36.062787-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be off"
2020-01-29 03:16:36.062870-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is off"
2020-01-29 03:16:36.062951-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT"
2020-01-29 03:16:36.063033-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Turn off TBT & USBC GPIO"
Because I'm curious about this, I will add one log line back at a time into ICMB and see which specific log line causes the freeze -- or whether this is or is not reproducible. If it is reproducible, I wonder if \RMDT.P1() logging might be interfering with Thunderbolt, but that remains to be seen.

The entire system log (processID == 0) is attached, but does not seem to reveal anything related to the hang.
 

Attachments

  • TNODE.txt.zip
    52.2 KB · Views: 72
Last edited:
Hello @Elias64Fr,

I spent several hours last night testing a more instrumented version of the SSDT (i.e. with more logging). Some interesting findings:

After adding these extra logs to ICMB ...

\RMDT.P1 (Concatenate ("UPSB- Read VID/DID =", \_SB.PCI0.RP05.UPSB.AVND))
\RMDT.P1 (Concatenate ("DSB0- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB0.AVND))
\RMDT.P1 (Concatenate ("NHI0- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB0.NHI0.AVND))
\RMDT.P1 (Concatenate ("Global NH00- Read VID/DID =", NH00))
\RMDT.P1 (Concatenate ("DSB2- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB2.AVND))
\RMDT.P1 (Concatenate ("XHC2- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB2.XHC2.AVND))


...the UPSB.PCED method did not work properly. This method seemed to hang inside this section:

View attachment 447278

The while ((Timer <= Local5)) loop would never exit when ICMB was modified with extra logging information. As a result, UPSB would hang. I would see PCED UPSB- Wait for config space, but I would not see UPSB PCED- Read VID/DID? So it hanged inside the Timer loop. Because of hanged UPSB, GPE _E17 was not issued, but I could mount and unmount USB 3.1 flash disks easily and reliably every time. No crash with hot plug of either USB-C or TB3 device. But TB3 devices of course would not work.

After removing all the extra logging lines from ICMB, it "seems" that the full TNODE/TBUS is trying to activate when a TB device is connected at boot, but macOS GUI hangs about 5 seconds after logging in. Nevertheless, we can see extract the system logs using Terminal. Those ACPI logs are more comprehensive now, in the spoiler below.
Code:
casey@Caseys-iMac ~ % log show --predicate "processID == 0" --start "2020-01-29 03:00:00" | grep ACPIDebug
2020-01-29 03:16:09.905749-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: Version 0.1.4 starting on OS X Darwin 19.2.
2020-01-29 03:16:09.905853-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x54039854
2020-01-29 03:16:09.905922-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe00e4000
2020-01-29 03:16:09.905990-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0600000
2020-01-29 03:16:09.906056-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0700000
2020-01-29 03:16:09.906125-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0708000
2020-01-29 03:16:09.906191-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0710000
2020-01-29 03:16:09.906259-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0720000
2020-01-29 03:16:09.906326-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0800000
2020-01-29 03:16:09.906392-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_INI Root Port"
2020-01-29 03:16:09.906457-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICMBoot"
2020-01-29 03:16:09.906523-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Tbt TWIN set to One"
2020-01-29 03:16:09.906589-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Tbt Force Power with FPG0"
2020-01-29 03:16:09.906825-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Up Stream VID/DID =0000000015EA8086"
2020-01-29 03:16:09.906892-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Delay to appear = 182 us"
2020-01-29 03:16:09.906957-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICMS"
2020-01-29 03:16:09.907023-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_0="
2020-01-29 03:16:09.907088-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xffffffff
2020-01-29 03:16:09.907154-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI called"
2020-01-29 03:16:09.907221-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI RP OK!"
2020-01-29 03:16:09.907287-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI UPSB OK!"
2020-01-29 03:16:09.907498-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 0 done"
2020-01-29 03:16:09.907566-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 3 done"
2020-01-29 03:16:09.907632-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 4 done"
2020-01-29 03:16:09.907697-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 5 done"
2020-01-29 03:16:09.907763-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI DPs done"
2020-01-29 03:16:09.907829-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI0 all done"
2020-01-29 03:16:09.907895-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI End"
2020-01-29 03:16:09.907961-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_1="
2020-01-29 03:16:09.908027-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a1
2020-01-29 03:16:09.908092-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_2="
2020-01-29 03:16:09.908157-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a1
2020-01-29 03:16:09.908223-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_3="
2020-01-29 03:16:09.908288-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a7
2020-01-29 03:16:09.908353-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800003f5
2020-01-29 03:16:09.908419-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_4="
2020-01-29 03:16:09.908485-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a7
2020-01-29 03:16:09.908550-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ISTA="
2020-01-29 03:16:09.908615-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800003f5
2020-01-29 03:16:09.963937-0800 0x76       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS0 RP05"
2020-01-29 03:16:09.964210-0800 0x76       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCEU RP05"
2020-01-29 03:16:09.964447-0800 0x76       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCEU RP05- Put upstream bridge back into D0 "
2020-01-29 03:16:09.984442-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS0 UPSB"
2020-01-29 03:16:09.984854-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB"
2020-01-29 03:16:09.985290-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED - enable GPIO"
2020-01-29 03:16:09.985708-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants on"
2020-01-29 03:16:09.986028-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants on"
2020-01-29 03:16:09.986422-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants on"
2020-01-29 03:16:09.986539-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT forced on"
2020-01-29 03:16:09.986748-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB forced on"
2020-01-29 03:16:09.987167-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be on"
2020-01-29 03:16:09.987521-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be on"
2020-01-29 03:16:09.987628-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is on"
2020-01-29 03:16:09.987709-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- restored flag, THUNDERBOLT_PCI_LINK_MGMT_DEVICE.PRSR"
2020-01-29 03:16:09.987968-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:09.988359-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- Wait for config space..."
2020-01-29 03:16:09.988812-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- Wait for config space..."
2020-01-29 03:16:09.989207-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- Read VID/DID =0000000015EA8086"
2020-01-29 03:16:09.989589-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UPSB PCED- Read VID/DID? 0000000000000001"
2020-01-29 03:16:09.989913-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:09.990371-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:08.773376-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774156-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x1
2020-01-29 03:16:08.774410-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774641-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x1
2020-01-29 03:16:11.862298-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST NHI0"
2020-01-29 03:16:11.862514-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST UPSB called with arg: 0000000000000000"
2020-01-29 03:16:11.862662-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UPSB UMPE"
2020-01-29 03:16:12.955608-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:12.956088-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:12.956257-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:12.956400-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:13.255990-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 XHC2, Nothing to do!"
2020-01-29 03:16:13.257569-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 DSB2"
2020-01-29 03:16:13.257674-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2"
2020-01-29 03:16:13.257775-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF DSB2"
2020-01-29 03:16:13.257875-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2- Put upstream bridge into D3"
2020-01-29 03:16:13.257976-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2- Set link disable on upstream bridge"
2020-01-29 03:16:13.258077-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2- Wait for link to drop..."
2020-01-29 03:16:14.265788-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2 - disable GPIO"
2020-01-29 03:16:14.265894-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants on"
2020-01-29 03:16:14.265996-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants on"
2020-01-29 03:16:14.266098-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants off"
2020-01-29 03:16:14.266200-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT forced on"
2020-01-29 03:16:14.266302-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB allows off"
2020-01-29 03:16:14.266403-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be on"
2020-01-29 03:16:14.266507-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be off"
2020-01-29 03:16:14.266612-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is on"
2020-01-29 03:16:19.145646-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST NHI0"
2020-01-29 03:16:19.145796-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST UPSB called with arg: 0000000000000000"
2020-01-29 03:16:34.977415-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "RTPC NHI0 called with arg: 0000000000000000"
2020-01-29 03:16:34.977818-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 NHI0, Nothing to do!"
2020-01-29 03:16:34.978928-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 DSB0"
2020-01-29 03:16:34.979009-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0"
2020-01-29 03:16:34.979088-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF DSB0"
2020-01-29 03:16:34.979167-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- Put upstream bridge into D3"
2020-01-29 03:16:34.979246-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- Set link disable on upstream bridge"
2020-01-29 03:16:34.979324-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- Wait for link to drop..."
2020-01-29 03:16:35.982667-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- disable GPIO"
2020-01-29 03:16:35.982749-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants on"
2020-01-29 03:16:35.982829-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants off"
2020-01-29 03:16:35.982907-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants off"
2020-01-29 03:16:35.982986-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT allows off"
2020-01-29 03:16:35.983064-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB allows off"
2020-01-29 03:16:35.983142-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be on"
2020-01-29 03:16:35.983220-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be on"
2020-01-29 03:16:35.983298-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is on"
2020-01-29 03:16:35.983814-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 UPSB Nothing to do!"
2020-01-29 03:16:35.987382-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 RP05"
2020-01-29 03:16:35.987467-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF RP05"
2020-01-29 03:16:35.987550-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 Calling CTBT"
2020-01-29 03:16:35.987633-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT"
2020-01-29 03:16:35.987715-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT - TBT domain is enabled"
2020-01-29 03:16:35.987796-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:35.987876-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:35.987957-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Read Value1"
2020-01-29 03:16:35.988038-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:35.988119-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Write Value1"
2020-01-29 03:16:35.988205-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x4000000
2020-01-29 03:16:35.988286-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOW In"
2020-01-29 03:16:35.988367-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOW Out"
2020-01-29 03:16:35.988449-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:35.988529-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:35.988609-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Read Value2"
2020-01-29 03:16:35.988690-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x1c000000
2020-01-29 03:16:35.988771-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Success"
2020-01-29 03:16:35.988852-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT - Set CP_ACK_POWERDOWN_OVERRIDE"
2020-01-29 03:16:35.988935-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 Calling PCDA"
2020-01-29 03:16:35.989017-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05"
2020-01-29 03:16:35.989098-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF RP05"
2020-01-29 03:16:35.989179-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- Put upstream bridge into D3"
2020-01-29 03:16:35.989262-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- Set link disable on upstream bridge"
2020-01-29 03:16:35.989345-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- Wait for link to drop..."
2020-01-29 03:16:36.062129-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- No link activity"
2020-01-29 03:16:36.062215-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- disable GPIO"
2020-01-29 03:16:36.062297-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants off"
2020-01-29 03:16:36.062379-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants off"
2020-01-29 03:16:36.062460-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants off"
2020-01-29 03:16:36.062542-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT allows off"
2020-01-29 03:16:36.062624-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB allows off"
2020-01-29 03:16:36.062705-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be off"
2020-01-29 03:16:36.062787-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be off"
2020-01-29 03:16:36.062870-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is off"
2020-01-29 03:16:36.062951-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT"
2020-01-29 03:16:36.063033-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Turn off TBT & USBC GPIO"
Because I'm curious about this, I will add one log line back at a time into ICMB and see which specific log line causes the freeze -- or whether this is or is not reproducible. If it is reproducible, I wonder if \RMDT.P1() logging might be interfering with Thunderbolt, but that remains to be seen.
Appreciate it !
About timing, probably due to by ACPIDebug process which isn't suitable.
Unfortunately, I don't know another Debug method. On last MacPro7,1 DSDT, we can find for exemple :
Debug = "EC Wake reason ="
It will be good if we can use a file or a boot arg to displays this king of informations. Probably most suitable and reliable !

For now, you can try removing all debug calling in a critical methods like PCED (when we have timing limit) and let only VID/DID display after timing in order to have few log .
 
boot verbose and then take a pic
I am getting no signal screen even after doing the fresh install and nothing is happening just getting no signal even after using -v argument while booting seems to be like still an issue with rx580 one. But yes I can boot with bootable usb and even if I choose to boot from catalina ssd I can get the screen. But if boot directly from ssd getting no signal screen every time. Sharing the screen shot for the same
 

Attachments

  • 467B0D9C-FD76-44C1-A4D3-782BDC1B0491.jpeg
    467B0D9C-FD76-44C1-A4D3-782BDC1B0491.jpeg
    1.2 MB · Views: 62
Hello @Elias64Fr,

I spent several hours last night testing a more instrumented version of the SSDT (i.e. with more logging). Some interesting findings:

After adding these extra logs to ICMB ...

\RMDT.P1 (Concatenate ("UPSB- Read VID/DID =", \_SB.PCI0.RP05.UPSB.AVND))
\RMDT.P1 (Concatenate ("DSB0- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB0.AVND))
\RMDT.P1 (Concatenate ("NHI0- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB0.NHI0.AVND))
\RMDT.P1 (Concatenate ("Global NH00- Read VID/DID =", NH00))
\RMDT.P1 (Concatenate ("DSB2- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB2.AVND))
\RMDT.P1 (Concatenate ("XHC2- Read VID/DID =", \_SB.PCI0.RP05.UPSB.DSB2.XHC2.AVND))


...the UPSB.PCED method did not work properly. This method seemed to hang inside this section:

View attachment 447278

The while ((Timer <= Local5)) loop would never exit when ICMB was modified with extra logging information. As a result, UPSB would hang. I would see PCED UPSB- Wait for config space, but I would not see UPSB PCED- Read VID/DID? So it hanged inside the Timer loop. Because of hanged UPSB, GPE _E17 was not issued, but I could mount and unmount USB 3.1 flash disks easily and reliably every time. No crash with hot plug of either USB-C or TB3 device. But TB3 devices of course would not work.

After removing all the extra logging lines from ICMB, it "seems" that the full TNODE/TBUS is trying to activate when a TB device is connected at boot, but macOS GUI hangs about 5 seconds after logging in. Nevertheless, we can see extract the system logs using Terminal. Those ACPI logs are more comprehensive now, in the spoiler below.
Code:
casey@Caseys-iMac ~ % log show --predicate "processID == 0" --start "2020-01-29 03:00:00" | grep ACPIDebug
2020-01-29 03:16:09.905749-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: Version 0.1.4 starting on OS X Darwin 19.2.
2020-01-29 03:16:09.905853-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x54039854
2020-01-29 03:16:09.905922-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe00e4000
2020-01-29 03:16:09.905990-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0600000
2020-01-29 03:16:09.906056-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0700000
2020-01-29 03:16:09.906125-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0708000
2020-01-29 03:16:09.906191-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0710000
2020-01-29 03:16:09.906259-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0720000
2020-01-29 03:16:09.906326-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xe0800000
2020-01-29 03:16:09.906392-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_INI Root Port"
2020-01-29 03:16:09.906457-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICMBoot"
2020-01-29 03:16:09.906523-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Tbt TWIN set to One"
2020-01-29 03:16:09.906589-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Tbt Force Power with FPG0"
2020-01-29 03:16:09.906825-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Up Stream VID/DID =0000000015EA8086"
2020-01-29 03:16:09.906892-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Delay to appear = 182 us"
2020-01-29 03:16:09.906957-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICMS"
2020-01-29 03:16:09.907023-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_0="
2020-01-29 03:16:09.907088-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0xffffffff
2020-01-29 03:16:09.907154-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI called"
2020-01-29 03:16:09.907221-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI RP OK!"
2020-01-29 03:16:09.907287-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI UPSB OK!"
2020-01-29 03:16:09.907498-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 0 done"
2020-01-29 03:16:09.907566-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 3 done"
2020-01-29 03:16:09.907632-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 4 done"
2020-01-29 03:16:09.907697-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI Dp 5 done"
2020-01-29 03:16:09.907763-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI DPs done"
2020-01-29 03:16:09.907829-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI0 all done"
2020-01-29 03:16:09.907895-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "Configure NHI End"
2020-01-29 03:16:09.907961-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_1="
2020-01-29 03:16:09.908027-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a1
2020-01-29 03:16:09.908092-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_2="
2020-01-29 03:16:09.908157-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a1
2020-01-29 03:16:09.908223-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_3="
2020-01-29 03:16:09.908288-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a7
2020-01-29 03:16:09.908353-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800003f5
2020-01-29 03:16:09.908419-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ICME_4="
2020-01-29 03:16:09.908485-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800001a7
2020-01-29 03:16:09.908550-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "ISTA="
2020-01-29 03:16:09.908615-0800 0xc8       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x800003f5
2020-01-29 03:16:09.963937-0800 0x76       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS0 RP05"
2020-01-29 03:16:09.964210-0800 0x76       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCEU RP05"
2020-01-29 03:16:09.964447-0800 0x76       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCEU RP05- Put upstream bridge back into D0 "
2020-01-29 03:16:09.984442-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS0 UPSB"
2020-01-29 03:16:09.984854-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB"
2020-01-29 03:16:09.985290-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED - enable GPIO"
2020-01-29 03:16:09.985708-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants on"
2020-01-29 03:16:09.986028-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants on"
2020-01-29 03:16:09.986422-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants on"
2020-01-29 03:16:09.986539-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT forced on"
2020-01-29 03:16:09.986748-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB forced on"
2020-01-29 03:16:09.987167-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be on"
2020-01-29 03:16:09.987521-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be on"
2020-01-29 03:16:09.987628-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is on"
2020-01-29 03:16:09.987709-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- restored flag, THUNDERBOLT_PCI_LINK_MGMT_DEVICE.PRSR"
2020-01-29 03:16:09.987968-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:09.988359-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- Wait for config space..."
2020-01-29 03:16:09.988812-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- Wait for config space..."
2020-01-29 03:16:09.989207-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCED UPSB- Read VID/DID =0000000015EA8086"
2020-01-29 03:16:09.989589-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UPSB PCED- Read VID/DID? 0000000000000001"
2020-01-29 03:16:09.989913-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:09.990371-0800 0xe9       Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:08.773376-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774156-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x1
2020-01-29 03:16:08.774410-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774641-0800 0x228      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x1
2020-01-29 03:16:11.862298-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST NHI0"
2020-01-29 03:16:11.862514-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST UPSB called with arg: 0000000000000000"
2020-01-29 03:16:11.862662-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UPSB UMPE"
2020-01-29 03:16:12.955608-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:12.956088-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:12.956257-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:12.956400-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:13.255990-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 XHC2, Nothing to do!"
2020-01-29 03:16:13.257569-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 DSB2"
2020-01-29 03:16:13.257674-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2"
2020-01-29 03:16:13.257775-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF DSB2"
2020-01-29 03:16:13.257875-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2- Put upstream bridge into D3"
2020-01-29 03:16:13.257976-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2- Set link disable on upstream bridge"
2020-01-29 03:16:13.258077-0800 0x7a4      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2- Wait for link to drop..."
2020-01-29 03:16:14.265788-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB2 - disable GPIO"
2020-01-29 03:16:14.265894-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants on"
2020-01-29 03:16:14.265996-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants on"
2020-01-29 03:16:14.266098-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants off"
2020-01-29 03:16:14.266200-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT forced on"
2020-01-29 03:16:14.266302-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB allows off"
2020-01-29 03:16:14.266403-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be on"
2020-01-29 03:16:14.266507-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be off"
2020-01-29 03:16:14.266612-0800 0x845      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is on"
2020-01-29 03:16:19.145646-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST NHI0"
2020-01-29 03:16:19.145796-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "MUST UPSB called with arg: 0000000000000000"
2020-01-29 03:16:34.977415-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "RTPC NHI0 called with arg: 0000000000000000"
2020-01-29 03:16:34.977818-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 NHI0, Nothing to do!"
2020-01-29 03:16:34.978928-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 DSB0"
2020-01-29 03:16:34.979009-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0"
2020-01-29 03:16:34.979088-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF DSB0"
2020-01-29 03:16:34.979167-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- Put upstream bridge into D3"
2020-01-29 03:16:34.979246-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- Set link disable on upstream bridge"
2020-01-29 03:16:34.979324-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- Wait for link to drop..."
2020-01-29 03:16:35.982667-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA DSB0- disable GPIO"
2020-01-29 03:16:35.982749-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants on"
2020-01-29 03:16:35.982829-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants off"
2020-01-29 03:16:35.982907-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants off"
2020-01-29 03:16:35.982986-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT allows off"
2020-01-29 03:16:35.983064-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB allows off"
2020-01-29 03:16:35.983142-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be on"
2020-01-29 03:16:35.983220-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be on"
2020-01-29 03:16:35.983298-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is on"
2020-01-29 03:16:35.983814-0800 0x810      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 UPSB Nothing to do!"
2020-01-29 03:16:35.987382-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 RP05"
2020-01-29 03:16:35.987467-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF RP05"
2020-01-29 03:16:35.987550-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 Calling CTBT"
2020-01-29 03:16:35.987633-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT"
2020-01-29 03:16:35.987715-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT - TBT domain is enabled"
2020-01-29 03:16:35.987796-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:35.987876-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:35.987957-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Read Value1"
2020-01-29 03:16:35.988038-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x0
2020-01-29 03:16:35.988119-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Write Value1"
2020-01-29 03:16:35.988205-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x4000000
2020-01-29 03:16:35.988286-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOW In"
2020-01-29 03:16:35.988367-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOW Out"
2020-01-29 03:16:35.988449-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:35.988529-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:35.988609-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Read Value2"
2020-01-29 03:16:35.988690-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: 0x1c000000
2020-01-29 03:16:35.988771-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CRMW Success"
2020-01-29 03:16:35.988852-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT - Set CP_ACK_POWERDOWN_OVERRIDE"
2020-01-29 03:16:35.988935-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "_PS3 Calling PCDA"
2020-01-29 03:16:35.989017-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05"
2020-01-29 03:16:35.989098-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "POFF RP05"
2020-01-29 03:16:35.989179-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- Put upstream bridge into D3"
2020-01-29 03:16:35.989262-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- Set link disable on upstream bridge"
2020-01-29 03:16:35.989345-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- Wait for link to drop..."
2020-01-29 03:16:36.062129-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- No link activity"
2020-01-29 03:16:36.062215-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "PCDA RP05- disable GPIO"
2020-01-29 03:16:36.062297-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - PCI wants off"
2020-01-29 03:16:36.062379-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - NHI wants off"
2020-01-29 03:16:36.062460-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - XHCI wants off"
2020-01-29 03:16:36.062542-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT allows off"
2020-01-29 03:16:36.062624-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB allows off"
2020-01-29 03:16:36.062705-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - TBT GPIO should be off"
2020-01-29 03:16:36.062787-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - USB GPIO should be off"
2020-01-29 03:16:36.062870-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Make sure TBT & USBC is off"
2020-01-29 03:16:36.062951-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "CTBT"
2020-01-29 03:16:36.063033-0800 0x804      Default     0x0                  0      0    kernel: (kernel) ACPIDebug: "UGIO - Turn off TBT & USBC GPIO"
Because I'm curious about this, I will add one log line back at a time into ICMB and see which specific log line causes the freeze -- or whether this is or is not reproducible. If it is reproducible, I wonder if \RMDT.P1() logging might be interfering with Thunderbolt, but that remains to be seen.

The entire system log (processID == 0) is attached, but does not seem to reveal anything related to the hang.
After in progress System log view we first have this interesting logs, but I don't know what happen :
2020-01-29 03:16:08.604735-0800 0x2f5 kernel: Waiting for DSMOS...
2020-01-29 03:16:08.609189-0800 0x38c kernel: ifnet_attach: All kernel threads created for interface en0 have been scheduled at least once. Proceeding.
2020-01-29 03:16:08.630227-0800 0x227 kernel: (AppleUSBXHCI) 000004.607414 SSP1@00100000: AppleUSBXHCIPort:: powerOn: deadline passed (PORTSC 0x00000000)
2020-01-29 03:16:08.630321-0800 0x21b kernel: (AppleUSBXHCI) 000004.607509 SSP2@00200000: AppleUSBXHCIPort:: powerOn: deadline passed (PORTSC 0x00000000)

2020-01-29 03:16:08.630479-0800 0x35b kernel: (ALF) ALF, old data swfs_pid_entry <private>, updaterules_msg <private>, updaterules_state <private>
2020-01-29 03:16:08.650878-0800 0x21b kernel: (IOUSBHostFamily) 000004.628064 XHC2@00000000: AppleUSBHostController::setPowerStateGated: going to state 1 returned 0xe00002e9
2020-01-29 03:16:08.650911-0800 0x21c kernel: (IOUSBHostFamily) 000004.628098 XHC2@00000000: AppleUSBHostController::hardwareExceptionThreadCallGated: attempting recovery
2020-01-29 03:16:08.650917-0800 0x21c kernel: (IOUSBHostFamily) 000004.628106 XHC2@00000000: AppleUSBHostController::hardwareExceptionThreadCallGated: 0x00000004
2020-01-29 03:16:08.650922-0800 0x21c kernel: (IOUSBHostFamily) 000004.628111 XHC2@00000000: AppleUSBHostController::hardwareExceptionThreadCallGated: attempting recovery
2020-01-29 03:16:08.650958-0800 0x21c kernel: (IOUSBHostFamily) 000004.628147 XHC2@00000000: AppleUSBHostController::setPowerStateGated: going to state 2 failed with 0xe00002e9
2020-01-29 03:16:08.650962-0800 0x21c kernel: (IOUSBHostFamily) 000004.628151 XHC2@00000000: AppleUSBHostController::hardwareExceptionThreadCallGated: 0x00000008
2020-01-29 03:16:08.751619-0800 0x227 kernel: (AppleUSBXHCI) 000004.728805 SSP2@00200000: AppleUSBXHCIPort:: powerOn: deadline passed (PORTSC 0x00000000)
2020-01-29 03:16:08.751620-0800 0x21b kernel: (AppleUSBXHCI) 000004.728807 SSP1@00100000: AppleUSBXHCIPort:: powerOn: deadline passed (PORTSC 0x00000000)
2020-01-29 03:16:08.771729-0800 0x1b9 kernel: (IOUSBHostFamily) 000004.748907 SSP1@00100000: AppleUSBHostPort::forcePowerGated: kPowerStateSuspended completed with 0xe00002d6 after 1091ms
2020-01-29 03:16:08.771749-0800 0x1bf kernel: (IOUSBHostFamily) 000004.748928 SSP2@00200000: AppleUSBHostPort::forcePowerGated: kPowerStateSuspended completed with 0xe00002d6 after 1091ms

2020-01-29 03:16:08.773376-0800 0x228 kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774156-0800 0x228 kernel: (kernel) ACPIDebug: 0x1
2020-01-29 03:16:08.774410-0800 0x228 kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774641-0800 0x228 kernel: (kernel) ACPIDebug: 0x1
 
After in progress System log view we first have this interesting logs, but I don't know what happen :
2020-01-29 03:16:08.604735-0800 0x2f5 kernel: Waiting for DSMOS...
2020-01-29 03:16:08.609189-0800 0x38c kernel: ifnet_attach: All kernel threads created for interface en0 have been scheduled at least once. Proceeding.
2020-01-29 03:16:08.630227-0800 0x227 kernel: (AppleUSBXHCI) 000004.607414 SSP1@00100000: AppleUSBXHCIPort:: powerOn: deadline passed (PORTSC 0x00000000)
2020-01-29 03:16:08.630321-0800 0x21b kernel: (AppleUSBXHCI) 000004.607509 SSP2@00200000: AppleUSBXHCIPort:: powerOn: deadline passed (PORTSC 0x00000000)

2020-01-29 03:16:08.630479-0800 0x35b kernel: (ALF) ALF, old data swfs_pid_entry <private>, updaterules_msg <private>, updaterules_state <private>
2020-01-29 03:16:08.650878-0800 0x21b kernel: (IOUSBHostFamily) 000004.628064 XHC2@00000000: AppleUSBHostController::setPowerStateGated: going to state 1 returned 0xe00002e9
2020-01-29 03:16:08.650911-0800 0x21c kernel: (IOUSBHostFamily) 000004.628098 XHC2@00000000: AppleUSBHostController::hardwareExceptionThreadCallGated: attempting recovery
2020-01-29 03:16:08.650917-0800 0x21c kernel: (IOUSBHostFamily) 000004.628106 XHC2@00000000: AppleUSBHostController::hardwareExceptionThreadCallGated: 0x00000004
2020-01-29 03:16:08.650922-0800 0x21c kernel: (IOUSBHostFamily) 000004.628111 XHC2@00000000: AppleUSBHostController::hardwareExceptionThreadCallGated: attempting recovery
2020-01-29 03:16:08.650958-0800 0x21c kernel: (IOUSBHostFamily) 000004.628147 XHC2@00000000: AppleUSBHostController::setPowerStateGated: going to state 2 failed with 0xe00002e9
2020-01-29 03:16:08.650962-0800 0x21c kernel: (IOUSBHostFamily) 000004.628151 XHC2@00000000: AppleUSBHostController::hardwareExceptionThreadCallGated: 0x00000008
2020-01-29 03:16:08.751619-0800 0x227 kernel: (AppleUSBXHCI) 000004.728805 SSP2@00200000: AppleUSBXHCIPort:: powerOn: deadline passed (PORTSC 0x00000000)
2020-01-29 03:16:08.751620-0800 0x21b kernel: (AppleUSBXHCI) 000004.728807 SSP1@00100000: AppleUSBXHCIPort:: powerOn: deadline passed (PORTSC 0x00000000)
2020-01-29 03:16:08.771729-0800 0x1b9 kernel: (IOUSBHostFamily) 000004.748907 SSP1@00100000: AppleUSBHostPort::forcePowerGated: kPowerStateSuspended completed with 0xe00002d6 after 1091ms
2020-01-29 03:16:08.771749-0800 0x1bf kernel: (IOUSBHostFamily) 000004.748928 SSP2@00200000: AppleUSBHostPort::forcePowerGated: kPowerStateSuspended completed with 0xe00002d6 after 1091ms

2020-01-29 03:16:08.773376-0800 0x228 kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774156-0800 0x228 kernel: (kernel) ACPIDebug: 0x1
2020-01-29 03:16:08.774410-0800 0x228 kernel: (kernel) ACPIDebug: "MODU - return = "
2020-01-29 03:16:08.774641-0800 0x228 kernel: (kernel) ACPIDebug: 0x1

And here, we can see that just after CIOR in/out, ACPIDebug seem to be overloaded :

2020-01-29 03:16:09.989207-0800 0xe9 (kernel) ACPIDebug: "PCED UPSB- Read VID/DID =0000000015EA8086"
2020-01-29 03:16:09.989589-0800 0xe9 (kernel) ACPIDebug: "UPSB PCED- Read VID/DID? 0000000000000001"
2020-01-29 03:16:09.989913-0800 0xe9 (kernel) ACPIDebug: "CIOR In"
2020-01-29 03:16:09.990371-0800 0xe9 (kernel) ACPIDebug: "CIOR Out"
2020-01-29 03:16:09.990519-0800 0xea (AppleACPIPlatform) AcpiOsIdleQueue exhausted
2020-01-29 03:16:09.990521-0800 0xea (AppleACPIPlatform) [PUSH] @0003D #0086:
2020-01-29 03:16:09.990522-0800 0xea (AppleACPIPlatform) [PUSH] @0003D #0086:
2020-01-29 03:16:09.990523-0800 0xea (AppleACPIPlatform) Notify
2020-01-29 03:16:09.990523-0800 0xea (AppleACPIPlatform) Notify
2020-01-29 03:16:09.990524-0800 0xea (AppleACPIPlatform) (
2020-01-29 03:16:09.990524-0800 0xea (AppleACPIPlatform) (
2020-01-29 03:16:09.990525-0800 0xea (AppleACPIPlatform) R
2020-01-29 03:16:09.990526-0800 0xea (AppleACPIPlatform) R
2020-01-29 03:16:09.990526-0800 0xea (AppleACPIPlatform) M
2020-01-29 03:16:09.990527-0800 0xea (AppleACPIPlatform) M
2020-01-29 03:16:09.990527-0800 0xea (AppleACPIPlatform) D
2020-01-29 03:16:09.990527-0800 0xea (AppleACPIPlatform) D
2020-01-29 03:16:09.990528-0800 0xea (AppleACPIPlatform) T
2020-01-29 03:16:09.990528-0800 0xea (AppleACPIPlatform) T
2020-01-29 03:16:09.990529-0800 0xea (AppleACPIPlatform) ,
2020-01-29 03:16:09.990529-0800 0xea (AppleACPIPlatform) ,
2020-01-29 03:16:09.990530-0800 0xea (AppleACPIPlatform) 0x80
2020-01-29 03:16:09.990530-0800 0xea (AppleACPIPlatform) 0x80
2020-01-29 03:16:09.990531-0800 0xea (AppleACPIPlatform) )
2020-01-29 03:16:09.990531-0800 0xea (AppleACPIPlatform) )
2020-01-29 03:16:09.990532-0800 0xea (AppleACPIPlatform) // Status Change
 
Back
Top