vwemirror/TeslaRel410
1
0
Fork 0
Code Issues Pull Requests Actions Packages Projects Releases Wiki Activity
Files
edb167090043e25fb0b3bc8acc81e8094f4f8eb9
TeslaRel410/emulator/render-bridge/dosboxlog.txt
T
CydandClaude Fable 5 edb1670900 BT410 5.3.75: the quiet conf faults 3/3 -- logging SUPPRESSES the fault 
Hypothesis: our BT_* logging inflates the load window, the window is the
exposure, so a quiet run should load faster and fault less.  Both halves
wrong.  pod_render_quiet -- which differs from pod_render_rec by exactly the
six BT_* logging vars -- faulted 3/3, same signature, against ~25-40% for the
logged conf.

That is the strongest evidence yet that the residual is a timing-sensitive
HOST bug rather than anything about our data: writing to COM3 changes when
interrupts land relative to the DPMI host's handler-chain walk, and nothing
about our attribute tables or renderables could plausibly be modulated by
whether we print to a serial port.  Interrupt phase can.

Two immediate consequences: pod_render_rec is the LESS fault-prone rig, so
use it; and the 'shipped survives' baseline weakens further, since shipped
also writes plenty to COM3 and so sits on the suppressed side of the same
effect on top of its shorter window.

The wall-clock numbers also correct an earlier assumption: 150-275s
boot+load, not ~60s.  The 60s figure was the load DRAIN in game ticks; DOS
boot, two diagnose passes and card init dominate the rest.

Next is clearly emulator-side: the host keeps no EBP frames (the frame walk
returned one bogus entry), so naming the routine that owns the chain needs a
breakpoint-style trace of entries to 0x66CF with the head node, diffing a
clean run against a faulting one.

Co-Authored-By: Claude Fable 5 <noreply@anthropic.com>
2026-07-29 18:51:35 -05:00

232 lines
15 KiB
Plaintext
Raw Blame History

LOG: Early LOG Init complete
LOG: CONFIG: Loaded config file: C:/VWE/TeslaRel410/emulator/render-bridge/pod_render_quiet.conf
LOG: DOSBox-X's working directory: C:\VWE\TeslaRel410\emulator\src\src
LOG: Logging init: beginning logging proper. This is the end of the early init logging
LOG: Logging: No logfile was given. All further logging will be discarded.
LOG: DOSBox-X version 2026.06.02 Commit d75a57c (MinGW SDL2 64-bit)
LOG: Prevent capture: 0
LOG: SDL: version 2.32.10, Video windows, Audio directsound
LOG: EXPERIMENTAL: Debugger enabled for MinGW build, DOSBox-X crashes depending on the terminal software you use. Launching from command prompt (cmd.exe) is recommended.
LOG: Windows keyboard layout ID is 0x0409
LOG: Host keyboard layout is now us (US English)
LOG: Mapper keyboard layout is now us (US English)
LOG: SDL2 reports desktop display mode 3440 x 1440
LOG: OpenGL Version : 4.6.0 NVIDIA 610.47
LOG: Configured windowposition: -
LOG: SDL: Current window pixel format: SDL_PIXELFORMAT_RGB888
LOG: SDL: You are running in 24 bpp mode, this will slow down things!
LOG: Releasing keyboard
LOG: SDL: Windows 11 round corners will be disabled.
LOG: Screen report: Method 'Win98base' (3440.000 x 1440.000 pixels) at (0.000 x 0.000) (910.167 x 381.000 mm) (35.833 x 15.000 in) (96.000 x 96.000 DPI)
LOG: ISA BCLK: 8333333.333Hz (25000000/3)
LOG: monopal: green,
LOG: Active save slot: 1 [Empty]
LOG: USING AVI+ZMBV
LOG: Pentium CMPXCHG8B emulation is enabled
LOG: Max 1073741824 sz 32768
LOG: Final 32768
LOG: Final arrangement: Below 4GB = 32768KB, Above 4GB = 0KB
LOG: VPXLOG: fifosock listening on 127.0.0.1:8621
LOG: VPXLOG: live render backend started
LOG: VPXLOG: base 0x150 respond=1 handshakes=3 log='C:\Users\cyd\AppData\Local/Temp/vwe-pod/vpxresp.txt'
LOG: MIDI:Opened device:win32
LOG: mic_input: Selected microphone device: Microphone (Steam Streaming Microphone)
LOG: One joystick reported, initializing with 4axis
LOG: Using joystick Xbox One Controller with 6 axes, 16 buttons and 1 hat(s)
LOG: VOODOO LFB now at d0000000
LOG: Serial1: BASE 3f8h
LOG: Serial1: namedpipe client of \\.\pipe\vrio (poll 0.10ms burst x16)
LOG: Serial1: namedpipe connected to \\.\pipe\vrio
LOG: Serial2: BASE 2f8h
LOG: Serial2: namedpipe client of \\.\pipe\vplasma (poll 1.00ms burst x1)
LOG: Serial2: namedpipe connected to \\.\pipe\vplasma
LOG: Serial3: BASE 3e8h
LOG: Serial: port 3 will write to file C:\VWE\TeslaRel410\emulator\render-bridge\podlog.txt
LOG: Parallel1: BASE 378h
LOG: MPU-401 Registering I/O ports as if IBM PC MPU-401 at base 330h
LOG: Memory I/O complexity optimization enabled aka option 'memory io optimization 1'. If the game or demo is unable to draw to the screen properly, set the option to false.
LOG: The 'scanline render on demand' option is available and may provide a modest boost in video render performance if set to true.
LOG: Allocated APM BIOS pm entry point at f000:ce40
LOG: Writing code to fce40
LOG: Writing code to fce60
LOG: Windows: IID_ITaskbarList3 is available
LOG: ISA Plug & Play BIOS enabled
LOG: VGA ROM BIOS init callback
LOG: pixratio 1.000, dw false, dh false
LOG: Aspect ratio: 640 x 480 xToY=1.333 yToX=0.750
LOG: WARNING: No translation support (to host) for code page 0
LOG: DOS keyboard layout loaded with main language code US for layout us
LOG: XMS: 50 handles allocated for use by the DOS environment
LOG: EMS page frame at 0xe000-0xefff
LOG: COMMAND.COM env size: 720 bytes
LOG: COMMAND.COM environment block: 0x0701 sz=0x002d
LOG: COMMAND.COM main body (PSP): 0x072f sz=0x009a
LOG: COMMAND.COM stack: 0x0749
LOG: pixratio 1.350, dw false, dh false
LOG: Aspect ratio: 720 x 540 xToY=1.333 yToX=0.750
LOG: SHELL:Redirect output to COM3
LOG: VPXLOG: fifosock client connected
VPX lights@f1: type6=0 typeE=0 decoded=0 dir=0 amb(0.00,0.00,0.00)
LOG: 185106254 ERROR MOUSE:Unhandled videomode 79 on reset
LOG: pixratio 1.000, dw false, dh false
LOG: Aspect ratio: 640 x 480 xToY=1.333 yToX=0.750
VPX lightnode t=6 nb=52: [8]00000000/0 [12]00000000/0 [16]00000001/1.4013e-45 [20]3f800000/1 [24]3f800000/1 [28]3f800000/1 [32]64078678/1e+22 [36]64078678/1e+22 [40]00000000/0 [44]bf6ae9ee/-0.917632 [48]ffffffff/nan
LOG: Serial1: Errors: Framing 0, Parity 0, Overrun RX:2 (IF0:2), TX:0, Break 0
VPX lights@f121: type6=2 typeE=6 decoded=2 dir=1 amb(0.55,0.50,0.65)
L0 dir(0.11,0.77,0.63) col(1.10,0.90,1.00)
VPX lights@f241: type6=2 typeE=6 decoded=2 dir=1 amb(0.55,0.50,0.65)
L0 dir(0.11,0.77,0.63) col(1.10,0.90,1.00)
LOG: Serial1: Errors: Framing 0, Parity 0, Overrun RX:1 (IF0:1), TX:0, Break 0
LOG: Serial1: Errors: Framing 0, Parity 0, Overrun RX:1 (IF0:1), TX:0, Break 0
VPX lights@f361: type6=2 typeE=7 decoded=2 dir=1 amb(0.55,0.50,0.65)
L0 dir(0.11,0.77,0.63) col(1.10,0.90,1.00)
LOG: Serial1: Errors: Framing 0, Parity 0, Overrun RX:1 (IF0:1), TX:0, Break 0
LOG: Serial1: Errors: Framing 0, Parity 0, Overrun RX:1 (IF0:1), TX:0, Break 0
VPX_PF_WATCH hit: lin=7000FA64 code=4 CS:EIP=00FF:000066D4 SS:ESP=0107:00017560
EAX=00000001 EBX=F000CA60 ECX=00000000 EDX=FFFFFFFF ESI=00017640 EDI=00017574 EBP=00017570
now=272341.556ms last serial RX 0.548ms ago deliveries=28130
code @EIP: 6A 00 57 53 56 FF 53 04 83 F8 00 0F 84 9C 07 00 00 8B 1B EB E6 6A 01 57
stack: 00017640 F000CA60 00017574 00000000 00120F92 0001000F 00000000 00000000 00000000 00000000
IVT match for EBX=F000CA60: INT00 INT06 INT07 INT2B INT2C INT2D INT32 INT34 INT35 INT36 INT37 INT38 INT39 INT3A INT3B INT3C INT3D INT3E INT3F INT40 INT42 INT44 INT45 INT47 INT48 INT49 INT4A INT4C INT4D INT4E INT4F INT50 INT51 INT52 INT53 INT54 INT55 INT56 INT57 INT58 INT59 INT5A INT5B INT5C INT5D INT5E INT5F INT66 INT68 INT69 INT6A INT6B INT6C INT6D INT6E INT6F
IVT[08-0F]: 0D1A0777 0D1A077B 0D1A077F 0D1A0783 0D1A0787 0D1A078B 0D1A078F 0D1A0793
ebp chain: [00017570]ret=0001000F
rx[ 0] t=272215.011 (126.545 ago) cs:eip=00FF:00492A57 byte=00
rx[ 1] t=272217.007 (124.549 ago) cs:eip=0D1A:00000683 byte=00
rx[ 2] t=272219.011 (122.545 ago) cs:eip=00FF:004C6C20 byte=00
rx[ 3] t=272221.005 (120.551 ago) cs:eip=00B7:0000188D byte=07
rx[ 4] t=272222.005 (119.551 ago) cs:eip=00B7:0000188D byte=FC
rx[ 5] t=272224.005 (117.551 ago) cs:eip=00B7:0000188D byte=87
rx[ 6] t=272226.005 (115.551 ago) cs:eip=00B7:0000188D byte=00
rx[ 7] t=272228.005 (113.551 ago) cs:eip=00B7:0000188D byte=00
rx[ 8] t=272230.005 (111.551 ago) cs:eip=00B7:0000188D byte=00
rx[ 9] t=272232.605 (108.951 ago) cs:eip=00FF:00492A13 byte=00
rx[ 10] t=272233.707 (107.849 ago) cs:eip=00FF:004927E4 byte=00
rx[ 11] t=272235.005 (106.551 ago) cs:eip=00FF:00492741 byte=00
rx[ 12] t=272236.306 (105.250 ago) cs:eip=00FF:00492A13 byte=00
rx[ 13] t=272237.006 (104.550 ago) cs:eip=00FF:00492A13 byte=00
rx[ 14] t=272238.505 (103.051 ago) cs:eip=0020:00004065 byte=00
rx[ 15] t=272241.005 (100.551 ago) cs:eip=00FF:004C3E4F byte=00
rx[ 16] t=272242.705 (98.851 ago) cs:eip=00FF:00492A13 byte=07
rx[ 17] t=272245.005 (96.551 ago) cs:eip=F000:0000D10B byte=FC
rx[ 18] t=272247.005 (94.551 ago) cs:eip=00FF:004921E6 byte=87
rx[ 19] t=272249.008 (92.548 ago) cs:eip=00FF:00494C90 byte=00
rx[ 20] t=272251.005 (90.551 ago) cs:eip=0020:00004103 byte=00
rx[ 21] t=272253.005 (88.551 ago) cs:eip=00FF:004929ED byte=00
rx[ 22] t=272256.005 (85.551 ago) cs:eip=0020:00001CD1 byte=00
rx[ 23] t=272258.005 (83.551 ago) cs:eip=00FF:004929E3 byte=00
rx[ 24] t=272260.006 (81.550 ago) cs:eip=00FF:00492A1C byte=00
rx[ 25] t=272262.006 (79.550 ago) cs:eip=00B7:000020C6 byte=00
rx[ 26] t=272264.010 (77.546 ago) cs:eip=00FF:00492A13 byte=00
rx[ 27] t=272267.009 (74.547 ago) cs:eip=00FF:0049292F byte=00
rx[ 28] t=272269.010 (72.546 ago) cs:eip=00FF:004C3E4F byte=00
rx[ 29] t=272271.005 (70.551 ago) cs:eip=0020:00004112 byte=07
rx[ 30] t=272274.011 (67.545 ago) cs:eip=00FF:0048AA6A byte=FC
rx[ 31] t=272276.009 (65.547 ago) cs:eip=00FF:004897E6 byte=87
rx[ 32] t=272277.005 (64.551 ago) cs:eip=0020:000002B1 byte=00
rx[ 33] t=272280.007 (61.549 ago) cs:eip=00FF:00492844 byte=00
rx[ 34] t=272282.005 (59.551 ago) cs:eip=00FF:00492A13 byte=00
rx[ 35] t=272283.406 (58.150 ago) cs:eip=00FF:00492A13 byte=00
rx[ 36] t=272285.010 (56.546 ago) cs:eip=0020:00000FF8 byte=00
rx[ 37] t=272287.009 (54.547 ago) cs:eip=00FF:0049292F byte=00
rx[ 38] t=272289.005 (52.551 ago) cs:eip=00FF:0049292F byte=00
rx[ 39] t=272292.005 (49.551 ago) cs:eip=00FF:004929D5 byte=00
rx[ 40] t=272295.005 (46.551 ago) cs:eip=00FF:0048ED1F byte=00
rx[ 41] t=272297.008 (44.548 ago) cs:eip=0020:000008CE byte=00
rx[ 42] t=272299.010 (42.546 ago) cs:eip=00FF:00494AA9 byte=07
rx[ 43] t=272300.009 (41.547 ago) cs:eip=00FF:00494C3E byte=FC
rx[ 44] t=272304.005 (37.551 ago) cs:eip=00FF:00493FBC byte=87
rx[ 45] t=272305.005 (36.551 ago) cs:eip=00FF:004929F8 byte=00
rx[ 46] t=272306.306 (35.250 ago) cs:eip=00FF:00492A13 byte=00
rx[ 47] t=272307.709 (33.847 ago) cs:eip=00FF:00492700 byte=00
rx[ 48] t=272310.013 (31.543 ago) cs:eip=00FF:0000AEE0 byte=00
rx[ 49] t=272312.006 (29.550 ago) cs:eip=00FF:00492A13 byte=00
rx[ 50] t=272315.006 (26.550 ago) cs:eip=00FF:004C3E4F byte=00
rx[ 51] t=272316.006 (25.550 ago) cs:eip=00FF:00492A13 byte=00
rx[ 52] t=272319.005 (22.551 ago) cs:eip=0020:00004062 byte=00
rx[ 53] t=272321.005 (20.551 ago) cs:eip=00FF:0000B029 byte=00
rx[ 54] t=272323.005 (18.551 ago) cs:eip=00FF:00498AB7 byte=00
rx[ 55] t=272325.014 (16.542 ago) cs:eip=00B7:00000012 byte=07
rx[ 56] t=272327.007 (14.549 ago) cs:eip=00FF:0049292F byte=FC
rx[ 57] t=272329.605 (11.951 ago) cs:eip=00FF:00492A13 byte=87
rx[ 58] t=272331.005 (10.551 ago) cs:eip=0020:0000083E byte=00
rx[ 59] t=272333.006 (8.550 ago) cs:eip=00FF:004929E3 byte=00
rx[ 60] t=272336.005 (5.551 ago) cs:eip=F000:0000D10B byte=00
rx[ 61] t=272337.007 (4.549 ago) cs:eip=00FF:0000AF58 byte=00
rx[ 62] t=272339.007 (2.549 ago) cs:eip=00FF:0049BDD4 byte=00
rx[ 63] t=272341.008 (0.548 ago) cs:eip=00FF:00492A57 byte=00
VPX_PF_WATCH hit: lin=7000FA64 code=4 CS:EIP=00FF:000066D9 SS:ESP=0107:00017560
EAX=00000001 EBX=F000CA60 ECX=00000000 EDX=FFFFFFFF ESI=00017640 EDI=00017574 EBP=00017570
now=272342.385ms last serial RX 0.779ms ago deliveries=28131
code @EIP: FF 53 04 83 F8 00 0F 84 9C 07 00 00 8B 1B EB E6 6A 01 57 56 6A 02 6A 00
stack: 00017640 F000CA60 00017574 00000000 00120F92 0001000F 00000000 00000000 00000000 00000000
IVT match for EBX=F000CA60: INT00 INT06 INT07 INT2B INT2C INT2D INT32 INT34 INT35 INT36 INT37 INT38 INT39 INT3A INT3B INT3C INT3D INT3E INT3F INT40 INT42 INT44 INT45 INT47 INT48 INT49 INT4A INT4C INT4D INT4E INT4F INT50 INT51 INT52 INT53 INT54 INT55 INT56 INT57 INT58 INT59 INT5A INT5B INT5C INT5D INT5E INT5F INT66 INT68 INT69 INT6A INT6B INT6C INT6D INT6E INT6F
IVT[08-0F]: 0D1A0777 0D1A077B 0D1A077F 0D1A0783 0D1A0787 0D1A078B 0D1A078F 0D1A0793
ebp chain: [00017570]ret=0001000F
rx[ 0] t=272217.007 (125.377 ago) cs:eip=0D1A:00000683 byte=00
rx[ 1] t=272219.011 (123.373 ago) cs:eip=00FF:004C6C20 byte=00
rx[ 2] t=272221.005 (121.379 ago) cs:eip=00B7:0000188D byte=07
rx[ 3] t=272222.005 (120.379 ago) cs:eip=00B7:0000188D byte=FC
rx[ 4] t=272224.005 (118.379 ago) cs:eip=00B7:0000188D byte=87
rx[ 5] t=272226.005 (116.379 ago) cs:eip=00B7:0000188D byte=00
rx[ 6] t=272228.005 (114.379 ago) cs:eip=00B7:0000188D byte=00
rx[ 7] t=272230.005 (112.379 ago) cs:eip=00B7:0000188D byte=00
rx[ 8] t=272232.605 (109.780 ago) cs:eip=00FF:00492A13 byte=00
rx[ 9] t=272233.707 (108.678 ago) cs:eip=00FF:004927E4 byte=00
rx[ 10] t=272235.005 (107.380 ago) cs:eip=00FF:00492741 byte=00
rx[ 11] t=272236.306 (106.079 ago) cs:eip=00FF:00492A13 byte=00
rx[ 12] t=272237.006 (105.379 ago) cs:eip=00FF:00492A13 byte=00
rx[ 13] t=272238.505 (103.879 ago) cs:eip=0020:00004065 byte=00
rx[ 14] t=272241.005 (101.379 ago) cs:eip=00FF:004C3E4F byte=00
rx[ 15] t=272242.705 (99.679 ago) cs:eip=00FF:00492A13 byte=07
rx[ 16] t=272245.005 (97.380 ago) cs:eip=F000:0000D10B byte=FC
rx[ 17] t=272247.005 (95.379 ago) cs:eip=00FF:004921E6 byte=87
rx[ 18] t=272249.008 (93.377 ago) cs:eip=00FF:00494C90 byte=00
rx[ 19] t=272251.005 (91.379 ago) cs:eip=0020:00004103 byte=00
rx[ 20] t=272253.005 (89.380 ago) cs:eip=00FF:004929ED byte=00
rx[ 21] t=272256.005 (86.379 ago) cs:eip=0020:00001CD1 byte=00
rx[ 22] t=272258.005 (84.379 ago) cs:eip=00FF:004929E3 byte=00
rx[ 23] t=272260.006 (82.379 ago) cs:eip=00FF:00492A1C byte=00
rx[ 24] t=272262.006 (80.379 ago) cs:eip=00B7:000020C6 byte=00
rx[ 25] t=272264.010 (78.375 ago) cs:eip=00FF:00492A13 byte=00
rx[ 26] t=272267.009 (75.375 ago) cs:eip=00FF:0049292F byte=00
rx[ 27] t=272269.010 (73.375 ago) cs:eip=00FF:004C3E4F byte=00
rx[ 28] t=272271.005 (71.379 ago) cs:eip=0020:00004112 byte=07
rx[ 29] t=272274.011 (68.374 ago) cs:eip=00FF:0048AA6A byte=FC
rx[ 30] t=272276.009 (66.376 ago) cs:eip=00FF:004897E6 byte=87
rx[ 31] t=272277.005 (65.379 ago) cs:eip=0020:000002B1 byte=00
rx[ 32] t=272280.007 (62.377 ago) cs:eip=00FF:00492844 byte=00
rx[ 33] t=272282.005 (60.379 ago) cs:eip=00FF:00492A13 byte=00
rx[ 34] t=272283.406 (58.979 ago) cs:eip=00FF:00492A13 byte=00
rx[ 35] t=272285.010 (57.375 ago) cs:eip=0020:00000FF8 byte=00
rx[ 36] t=272287.009 (55.375 ago) cs:eip=00FF:0049292F byte=00
rx[ 37] t=272289.005 (53.379 ago) cs:eip=00FF:0049292F byte=00
rx[ 38] t=272292.005 (50.380 ago) cs:eip=00FF:004929D5 byte=00
rx[ 39] t=272295.005 (47.379 ago) cs:eip=00FF:0048ED1F byte=00
rx[ 40] t=272297.008 (45.377 ago) cs:eip=0020:000008CE byte=00
rx[ 41] t=272299.010 (43.375 ago) cs:eip=00FF:00494AA9 byte=07
rx[ 42] t=272300.009 (42.375 ago) cs:eip=00FF:00494C3E byte=FC
rx[ 43] t=272304.005 (38.379 ago) cs:eip=00FF:00493FBC byte=87
rx[ 44] t=272305.005 (37.380 ago) cs:eip=00FF:004929F8 byte=00
rx[ 45] t=272306.306 (36.079 ago) cs:eip=00FF:00492A13 byte=00
rx[ 46] t=272307.709 (34.675 ago) cs:eip=00FF:00492700 byte=00
rx[ 47] t=272310.013 (32.371 ago) cs:eip=00FF:0000AEE0 byte=00
rx[ 48] t=272312.006 (30.379 ago) cs:eip=00FF:00492A13 byte=00
rx[ 49] t=272315.006 (27.379 ago) cs:eip=00FF:004C3E4F byte=00
rx[ 50] t=272316.006 (26.379 ago) cs:eip=00FF:00492A13 byte=00
rx[ 51] t=272319.005 (23.379 ago) cs:eip=0020:00004062 byte=00
rx[ 52] t=272321.005 (21.379 ago) cs:eip=00FF:0000B029 byte=00
rx[ 53] t=272323.005 (19.379 ago) cs:eip=00FF:00498AB7 byte=00
rx[ 54] t=272325.014 (17.371 ago) cs:eip=00B7:00000012 byte=07
rx[ 55] t=272327.007 (15.377 ago) cs:eip=00FF:0049292F byte=FC
rx[ 56] t=272329.605 (12.779 ago) cs:eip=00FF:00492A13 byte=87
rx[ 57] t=272331.005 (11.379 ago) cs:eip=0020:0000083E byte=00
rx[ 58] t=272333.006 (9.379 ago) cs:eip=00FF:004929E3 byte=00
rx[ 59] t=272336.005 (6.380 ago) cs:eip=F000:0000D10B byte=00
rx[ 60] t=272337.007 (5.377 ago) cs:eip=00FF:0000AF58 byte=00
rx[ 61] t=272339.007 (3.377 ago) cs:eip=00FF:0049BDD4 byte=00
rx[ 62] t=272341.008 (1.377 ago) cs:eip=00FF:00492A57 byte=00
rx[ 63] t=272341.606 (0.779 ago) cs:eip=0020:0000587C byte=00
LOG: 705973517 ERROR INT10:PutPixel unhandled mode type 7
LOG: SHELL:Redirect output to RC.TXT
LOG: Serial1: Errors: Framing 0, Parity 0, Overrun RX:494 (IF0:5), TX:0, Break 0
Reference in New Issue View Git Blame Copy Permalink