vision compositing: auto.
2023-03-30 19:33:34.959 Scripting TRACE: Scripting.on Vision.PartAlignment.Before
2023-03-30 19:33:34.960 AbstractHeadMountable DEBUG: N1.moveTo((167.370435, 29.867672, -8.227176, 0.000000 mm), 1.0)
2023-03-30 19:33:34.960 ReferenceNozzle TRACE: N1.toHeadLocation((167.370435, 29.867672, -8.227176, -71.772375 mm), ...) rotation mode offset 71.77237472139751
2023-03-30 19:33:34.960 ReferenceNozzle TRACE: N1.toHeadLocation((167.350126, 29.718383, -8.227176, -71.772375 mm), ...) runout compensation (0.020309, 0.149290, 0.000000, 0.000000 mm)
2023-03-30 19:33:34.960 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M119 ; DRAGPIN endstop status, 10000)...
2023-03-30 19:33:34.960 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M119
2023-03-30 19:33:34.961 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << X_min:0 Y_min:0 Z_min:0 pins- (X)P4.4:0 (Y)P4.3:0 (Z)P2.13:0 (Z)P4.2:1
2023-03-30 19:33:34.961 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:34.961 GcodeDriver TRACE: actuatorRead response: X_min:0 Y_min:0 Z_min:0 pins- (X)P4.4:0 (Y)P4.3:0 (Z)P2.13:0 (Z)P4.2:1
2023-03-30 19:33:34.961 ReferenceActuator DEBUG: DRAGPIN.read(): 1
2023-03-30 19:33:34.962 Scripting TRACE: Scripting.on Camera.BeforeSettle
2023-03-30 19:33:34.963 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S3022.19 G1 X133.4510 Y47.8979    F3395.45 ; move to target, 10000)...
2023-03-30 19:33:34.963 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S3022.19G1X133.451Y47.8979F3395.45
2023-03-30 19:33:34.963 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:34.963 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S96.81 G1 X133.3885 Y47.9735    F600.00 ; move to target, 10000)...
2023-03-30 19:33:34.963 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S96.81G1X133.3885Y47.9735F600
2023-03-30 19:33:34.964 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:34.965 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M400 ; Wait for moves to complete before returning, 10000)...
2023-03-30 19:33:34.965 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M114 ; get position, -1)...
2023-03-30 19:33:34.965 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M400
2023-03-30 19:33:34.965 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M114
2023-03-30 19:33:35.049 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.049 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok C: X:133.3885 Y:47.9735 Z:-28.0424 A:-71.7724 B:180.0000 C:6.0000 D:0.0000
2023-03-30 19:33:35.050 GcodeDriver TRACE: Position report: ok C: X:133.3885 Y:47.9735 Z:-28.0424 A:-71.7724 B:180.0000 C:6.0000 D:0.0000
2023-03-30 19:33:35.050 GcodeDriver TRACE: GcodeDriver got lastReportedLocation (X:133.388500, Y:47.973500, ZN:-28.042400, C1:-71.772400, C2:180.000000)
2023-03-30 19:33:35.050 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete.
2023-03-30 19:33:35.050 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.059 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.060 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.078 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.084 AbstractSettlingCamera TRACE: autoSettleAndCapture t=34 auto settle score: 1.306 compute time: 5
2023-03-30 19:33:35.084 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.103 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.105 AbstractSettlingCamera TRACE: autoSettleAndCapture t=55 auto settle score: 2.353 compute time: 2
2023-03-30 19:33:35.105 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.115 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.117 AbstractSettlingCamera TRACE: autoSettleAndCapture t=67 auto settle score: 1.259 compute time: 2
2023-03-30 19:33:35.117 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.133 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.135 AbstractSettlingCamera TRACE: autoSettleAndCapture t=85 auto settle score: 0.253 compute time: 2
2023-03-30 19:33:35.135 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.156 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.159 AbstractSettlingCamera TRACE: autoSettleAndCapture t=108 auto settle score: 0.195 compute time: 2
2023-03-30 19:33:35.159 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.177 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.179 AbstractSettlingCamera TRACE: autoSettleAndCapture t=128 auto settle score: 0.200 compute time: 1
2023-03-30 19:33:35.179 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 128 ms
2023-03-30 19:33:35.179 Scripting TRACE: Scripting.on Camera.AfterSettle
2023-03-30 19:33:35.179 ReferenceActuator DEBUG: UPLED.actuate(false)
2023-03-30 19:33:35.179 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M811 ; UPLED M810 on / M811 off, 10000)...
2023-03-30 19:33:35.179 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M811
2023-03-30 19:33:35.179 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.258 MaskHsv TRACE: Fraction actually masked = 0.046551596551596575
2023-03-30 19:33:35.259 MaskHsv TRACE: Fraction actually masked = 0.9981222481222481
2023-03-30 19:33:35.290 ReferenceBottomVision DEBUG: Alignment result: SOT23-BC857
2023-03-30 19:33:35.290 AbstractHeadMountable DEBUG: N1.moveTo((167.370435, 27.547672, -8.227176, 0.000000 mm), 1.0)
2023-03-30 19:33:35.290 ReferenceNozzle TRACE: N1.toHeadLocation((167.370435, 27.547672, -8.227176, -71.772375 mm), ...) rotation mode offset 71.77237472139751
2023-03-30 19:33:35.290 ReferenceNozzle TRACE: N1.toHeadLocation((167.350126, 27.398383, -8.227176, -71.772375 mm), ...) runout compensation (0.020309, 0.149290, 0.000000, 0.000000 mm)
2023-03-30 19:33:35.291 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M119 ; DRAGPIN endstop status, 10000)...
2023-03-30 19:33:35.291 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M119
2023-03-30 19:33:35.291 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << X_min:0 Y_min:0 Z_min:0 pins- (X)P4.4:0 (Y)P4.3:0 (Z)P2.13:0 (Z)P4.2:1
2023-03-30 19:33:35.291 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.291 GcodeDriver TRACE: actuatorRead response: X_min:0 Y_min:0 Z_min:0 pins- (X)P4.4:0 (Y)P4.3:0 (Z)P2.13:0 (Z)P4.2:1
2023-03-30 19:33:35.291 ReferenceActuator DEBUG: DRAGPIN.read(): 1
2023-03-30 19:33:35.293 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S3393.35 G1  Y45.6912    F5280.14 ; move to target, 10000)...
2023-03-30 19:33:35.293 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S3393.35G1Y45.6912F5280.14
2023-03-30 19:33:35.293 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.293 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S74.58 G1  Y45.6157    F600.00 ; move to target, 10000)...
2023-03-30 19:33:35.293 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S74.58G1Y45.6157F600
2023-03-30 19:33:35.294 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.294 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M400 ; Wait for moves to complete before returning, 10000)...
2023-03-30 19:33:35.294 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M114 ; get position, -1)...
2023-03-30 19:33:35.294 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M400
2023-03-30 19:33:35.294 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M114
2023-03-30 19:33:35.393 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.394 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok C: X:133.3885 Y:45.6157 Z:-28.0424 A:-71.7724 B:180.0000 C:6.0000 D:0.0000
2023-03-30 19:33:35.394 GcodeDriver TRACE: Position report: ok C: X:133.3885 Y:45.6157 Z:-28.0424 A:-71.7724 B:180.0000 C:6.0000 D:0.0000
2023-03-30 19:33:35.394 GcodeDriver TRACE: GcodeDriver got lastReportedLocation (X:133.388500, Y:45.615700, ZN:-28.042400, C1:-71.772400, C2:180.000000)
2023-03-30 19:33:35.394 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete.
2023-03-30 19:33:35.394 ReferenceActuator DEBUG: UPLED.actuate(true)
2023-03-30 19:33:35.394 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M810 ; UPLED M810 on / M811 off, 10000)...
2023-03-30 19:33:35.395 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M810
2023-03-30 19:33:35.395 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.445 Scripting TRACE: Scripting.on Camera.BeforeSettle
2023-03-30 19:33:35.445 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.454 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.455 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.465 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.466 AbstractSettlingCamera TRACE: autoSettleAndCapture t=21 auto settle score: 0.163 compute time: 1
2023-03-30 19:33:35.467 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.484 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.487 AbstractSettlingCamera TRACE: autoSettleAndCapture t=42 auto settle score: 0.177 compute time: 3
2023-03-30 19:33:35.487 Scripting TRACE: Scripting.on Camera.BeforeCapture
2023-03-30 19:33:35.505 Scripting TRACE: Scripting.on Camera.AfterCapture
2023-03-30 19:33:35.507 AbstractSettlingCamera TRACE: autoSettleAndCapture t=62 auto settle score: 0.171 compute time: 2
2023-03-30 19:33:35.508 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 62 ms
2023-03-30 19:33:35.508 Scripting TRACE: Scripting.on Camera.AfterSettle
2023-03-30 19:33:35.508 ReferenceActuator DEBUG: UPLED.actuate(false)
2023-03-30 19:33:35.508 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M811 ; UPLED M810 on / M811 off, 10000)...
2023-03-30 19:33:35.508 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M811
2023-03-30 19:33:35.508 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.587 MaskHsv TRACE: Fraction actually masked = 0.013623413623413572
2023-03-30 19:33:35.590 MaskHsv TRACE: Fraction actually masked = 0.9987401487401487
2023-03-30 19:33:35.622 ReferenceBottomVision DEBUG: Alignment result: SOT23-BC857
2023-03-30 19:33:35.623 ReferenceBottomVision DEBUG: Bottom vision part SOT23-BC857 result rect { {477.16241025436955, 544.6862385701828} 205x0 * 0.24246009608511088 }
2023-03-30 19:33:35.623 ReferenceBottomVision DEBUG: Offsets accepted (-0.465467, -0.095518, 0.000000, -0.242460 mm)
2023-03-30 19:33:35.623 ReferenceBottomVision DEBUG: Alignment result: SOT23-BC857  |  X:-0.465 Y:-0.096 C:-0.242 Δ:0.475
2023-03-30 19:33:35.624 AbstractNozzle TRACE: Set rotation mode offset: 71.5299146253124°.
2023-03-30 19:33:35.624 Scripting TRACE: Scripting.on Vision.PartAlignment.After
2023-03-30 19:33:35.624 AbstractHeadMountable DEBUG: N1.moveTo((167.835902, 28.803190, -8.227176, 0.000000 mm), 1.0)
2023-03-30 19:33:35.624 ReferenceNozzle TRACE: N1.toHeadLocation((167.835902, 28.803190, -8.227176, -71.529915 mm), ...) rotation mode offset 71.5299146253124
2023-03-30 19:33:35.624 ReferenceNozzle TRACE: N1.toHeadLocation((167.816226, 28.653816, -8.227176, -71.529915 mm), ...) runout compensation (0.019677, 0.149374, 0.000000, 0.000000 mm)
2023-03-30 19:33:35.624 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M119 ; DRAGPIN endstop status, 10000)...
2023-03-30 19:33:35.624 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M119
2023-03-30 19:33:35.625 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << X_min:0 Y_min:0 Z_min:0 pins- (X)P4.4:0 (Y)P4.3:0 (Z)P2.13:0 (Z)P4.2:1
2023-03-30 19:33:35.625 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.625 GcodeDriver TRACE: actuatorRead response: X_min:0 Y_min:0 Z_min:0 pins- (X)P4.4:0 (Y)P4.3:0 (Z)P2.13:0 (Z)P4.2:1
2023-03-30 19:33:35.625 ReferenceActuator DEBUG: DRAGPIN.read(): 1
2023-03-30 19:33:35.627 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S3173.15 G1 X133.8066 Y46.8334  A-71.5299  F3868.50 ; move to target, 10000)...
2023-03-30 19:33:35.627 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S3173.15G1X133.8066Y46.8334A-71.5299F3868.5
2023-03-30 19:33:35.627 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.628 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S96.81 G1 X133.8691 Y46.9089    F600.00 ; move to target, 10000)...
2023-03-30 19:33:35.628 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S96.81G1X133.8691Y46.9089F600
2023-03-30 19:33:35.628 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.631 AbstractMachine TRACE: Machine entering idle state.
2023-03-30 19:33:35.631 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M400 ; Wait for moves to complete before returning, 10000)...
2023-03-30 19:33:35.631 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M114 ; get position, -1)...
2023-03-30 19:33:35.631 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M400
2023-03-30 19:33:35.631 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M114
2023-03-30 19:33:35.718 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok
2023-03-30 19:33:35.719 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok C: X:133.8691 Y:46.9089 Z:-28.0424 A:-71.5299 B:180.0000 C:6.0000 D:0.0000
2023-03-30 19:33:35.719 GcodeDriver TRACE: Position report: ok C: X:133.8691 Y:46.9089 Z:-28.0424 A:-71.5299 B:180.0000 C:6.0000 D:0.0000
2023-03-30 19:33:35.719 GcodeDriver TRACE: GcodeDriver got lastReportedLocation (X:133.869100, Y:46.908900, ZN:-28.042400, C1:-71.529900, C2:180.000000)
2023-03-30 19:33:35.719 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete.
2023-03-30 19:33:35.719 ReferenceActuator DEBUG: UPLED.actuate(true)
2023-03-30 19:33:35.719 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M810 ; UPLED M810 on / M811 off, 10000)...
2023-03-30 19:33:35.719 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M810
2023-03-30 19:33:35.719 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok