Stock (not rectlinear) Vision compositing: None 2023-03-30 18:50:21.559 Scripting TRACE: Scripting.on Vision.PartAlignment.Before 2023-03-30 18:50:21.559 AbstractHeadMountable DEBUG: N1.moveTo((167.370435, 28.707672, -8.227176, 90.000000 mm), 1.0) 2023-03-30 18:50:21.559 ReferenceNozzle TRACE: N1.toHeadLocation((167.370435, 28.707672, -8.227176, -29.169833 mm), ...) rotation mode offset 119.16983317341861 2023-03-30 18:50:21.559 ReferenceNozzle TRACE: N1.toHeadLocation((167.456542, 28.584038, -8.227176, -29.169833 mm), ...) runout compensation (-0.086107, 0.123634, 0.000000, 0.000000 mm) 2023-03-30 18:50:21.559 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M119 ; DRAGPIN endstop status, 10000)... 2023-03-30 18:50:21.560 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M119 2023-03-30 18:50:21.560 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 18:50:21.560 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:50:21.560 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 18:50:21.560 ReferenceActuator DEBUG: DRAGPIN.read(): 1 2023-03-30 18:50:21.562 Scripting TRACE: Scripting.on Camera.BeforeSettle 2023-03-30 18:50:21.562 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S1255.90 G1 X133.4469 Y46.8769 F623.40 ; move to target, 10000)... 2023-03-30 18:50:21.563 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S1255.9G1X133.4469Y46.8769F623.4 2023-03-30 18:50:21.563 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:50:21.563 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S96.81 G1 X133.5094 Y46.8013 F600.00 ; move to target, 10000)... 2023-03-30 18:50:21.563 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S96.81G1X133.5094Y46.8013F600 2023-03-30 18:50:21.563 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:50:21.564 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M400 ; Wait for moves to complete before returning, 10000)... 2023-03-30 18:50:21.564 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M114 ; get position, -1)... 2023-03-30 18:50:21.565 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M400 2023-03-30 18:50:21.565 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M114 2023-03-30 18:50:21.630 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:50:21.630 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok C: X:133.5094 Y:46.8013 Z:-28.0424 A:-29.1698 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:50:21.630 GcodeDriver TRACE: Position report: ok C: X:133.5094 Y:46.8013 Z:-28.0424 A:-29.1698 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:50:21.630 GcodeDriver TRACE: GcodeDriver got lastReportedLocation (X:133.509400, Y:46.801300, ZN:-28.042400, C1:-29.169800, C2:180.000000) 2023-03-30 18:50:21.631 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2023-03-30 18:50:21.631 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:50:21.649 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:50:21.650 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:50:21.672 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:50:21.676 AbstractSettlingCamera TRACE: autoSettleAndCapture t=45 auto settle score: 0.254 compute time: 3 2023-03-30 18:50:21.676 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:50:21.692 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:50:21.694 AbstractSettlingCamera TRACE: autoSettleAndCapture t=63 auto settle score: 0.226 compute time: 2 2023-03-30 18:50:21.694 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:50:21.712 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:50:21.715 AbstractSettlingCamera TRACE: autoSettleAndCapture t=83 auto settle score: 0.176 compute time: 2 2023-03-30 18:50:21.715 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 84 ms 2023-03-30 18:50:21.715 Scripting TRACE: Scripting.on Camera.AfterSettle 2023-03-30 18:50:21.715 ReferenceActuator DEBUG: UPLED.actuate(false) 2023-03-30 18:50:21.715 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M811 ; UPLED M810 on / M811 off, 10000)... 2023-03-30 18:50:21.715 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M811 2023-03-30 18:50:21.715 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:50:21.795 MaskHsv TRACE: Fraction actually masked = 0.027546527546527533 2023-03-30 18:50:21.796 MaskHsv TRACE: Fraction actually masked = 0.9976023976023976 2023-03-30 18:50:21.825 ReferenceBottomVision DEBUG: Alignment result: SOT23-BC857 2023-03-30 18:50:21.826 ReferenceBottomVision DEBUG: Bottom vision part SOT23-BC857 result rect { {502.7728271484375, 546.6138305664062} 116x118 * -90.5562515258789 } 2023-03-30 18:50:21.826 ReferenceBottomVision DEBUG: Offsets accepted (0.044087, -0.132460, 0.000000, 0.556252 mm) 2023-03-30 18:50:21.826 ReferenceBottomVision DEBUG: Alignment result: SOT23-BC857 | X:0.044 Y:-0.132 C:0.556 Δ:0.140 2023-03-30 18:50:21.827 AbstractNozzle TRACE: Set rotation mode offset: 119.72608469929752°. 2023-03-30 18:50:21.827 Scripting TRACE: Scripting.on Vision.PartAlignment.After 2023-03-30 18:50:21.827 AbstractHeadMountable DEBUG: N1.moveTo((167.326348, 28.840133, -8.227176, 90.000000 mm), 1.0) 2023-03-30 18:50:21.827 ReferenceNozzle TRACE: N1.toHeadLocation((167.326348, 28.840133, -8.227176, -29.726085 mm), ...) rotation mode offset 119.72608469929752 2023-03-30 18:50:21.827 ReferenceNozzle TRACE: N1.toHeadLocation((167.411251, 28.715668, -8.227176, -29.726085 mm), ...) runout compensation (-0.084902, 0.124465, 0.000000, 0.000000 mm) 2023-03-30 18:50:21.827 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M119 ; DRAGPIN endstop status, 10000)... 2023-03-30 18:50:21.827 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M119 2023-03-30 18:50:21.827 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 18:50:21.827 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:50:21.827 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 18:50:21.827 ReferenceActuator DEBUG: DRAGPIN.read(): 1 2023-03-30 18:50:21.829 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S7710.56 G1 Y46.8952 A-29.7261 F3957.11 ; move to target, 10000)... 2023-03-30 18:50:21.830 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S7710.56G1Y46.8952A-29.7261F3957.11 2023-03-30 18:50:21.830 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:50:21.830 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S96.81 G1 X133.4496 Y46.9707 F600.00 ; move to target, 10000)... 2023-03-30 18:50:21.830 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S96.81G1X133.4496Y46.9707F600 2023-03-30 18:50:21.831 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:50:21.832 AbstractMachine TRACE: Machine entering idle state. 2023-03-30 18:50:21.832 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M400 ; Wait for moves to complete before returning, 10000)... 2023-03-30 18:50:21.832 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M114 ; get position, -1)... 2023-03-30 18:50:21.832 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M400 2023-03-30 18:50:21.832 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M114 2023-03-30 18:50:21.904 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:50:21.904 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok C: X:133.4496 Y:46.9707 Z:-28.0424 A:-29.7261 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:50:21.904 GcodeDriver TRACE: Position report: ok C: X:133.4496 Y:46.9707 Z:-28.0424 A:-29.7261 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:50:21.904 GcodeDriver TRACE: GcodeDriver got lastReportedLocation (X:133.449600, Y:46.970700, ZN:-28.042400, C1:-29.726100, C2:180.000000) 2023-03-30 18:50:21.905 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2023-03-30 18:50:21.905 ReferenceActuator DEBUG: UPLED.actuate(true) 2023-03-30 18:50:21.905 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M810 ; UPLED M810 on / M811 off, 10000)... 2023-03-30 18:50:21.905 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M810 2023-03-30 18:50:21.905 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok Vision compositing: Auto (trapezoid) 2023-03-30 18:55:04.616 Scripting TRACE: Scripting.on Vision.PartAlignment.Before 2023-03-30 18:55:04.618 AbstractHeadMountable DEBUG: N1.moveTo((166.210435, 28.707672, -8.227176, 90.000000 mm), 1.0) 2023-03-30 18:55:04.618 ReferenceNozzle TRACE: N1.toHeadLocation((166.210435, 28.707672, -8.227176, -29.726085 mm), ...) rotation mode offset 119.72608469929752 2023-03-30 18:55:04.618 ReferenceNozzle TRACE: N1.toHeadLocation((166.295337, 28.583208, -8.227176, -29.726085 mm), ...) runout compensation (-0.084902, 0.124465, 0.000000, 0.000000 mm) 2023-03-30 18:55:04.618 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M119 ; DRAGPIN endstop status, 10000)... 2023-03-30 18:55:04.619 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M119 2023-03-30 18:55:04.619 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 18:55:04.619 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:04.619 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 18:55:04.619 ReferenceActuator DEBUG: DRAGPIN.read(): 1 2023-03-30 18:55:04.623 Scripting TRACE: Scripting.on Camera.BeforeSettle 2023-03-30 18:55:04.623 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S4054.22 G1 X132.3962 Y46.8760 F3928.97 ; move to target, 10000)... 2023-03-30 18:55:04.623 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S4054.22G1X132.3962Y46.876F3928.97 2023-03-30 18:55:04.624 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:04.624 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S96.81 G1 X132.3337 Y46.8005 F600.00 ; move to target, 10000)... 2023-03-30 18:55:04.624 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S96.81G1X132.3337Y46.8005F600 2023-03-30 18:55:04.625 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:04.626 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M400 ; Wait for moves to complete before returning, 10000)... 2023-03-30 18:55:04.626 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M114 ; get position, -1)... 2023-03-30 18:55:04.626 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M400 2023-03-30 18:55:04.627 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M114 2023-03-30 18:55:04.708 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:04.709 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok C: X:132.3337 Y:46.8005 Z:-28.0424 A:-29.7261 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:55:04.709 GcodeDriver TRACE: Position report: ok C: X:132.3337 Y:46.8005 Z:-28.0424 A:-29.7261 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:55:04.709 GcodeDriver TRACE: GcodeDriver got lastReportedLocation (X:132.333700, Y:46.800500, ZN:-28.042400, C1:-29.726100, C2:180.000000) 2023-03-30 18:55:04.709 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2023-03-30 18:55:04.710 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:04.719 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:04.720 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:04.733 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:04.736 AbstractSettlingCamera TRACE: autoSettleAndCapture t=26 auto settle score: 0.215 compute time: 2 2023-03-30 18:55:04.736 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:04.753 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:04.755 AbstractSettlingCamera TRACE: autoSettleAndCapture t=46 auto settle score: 1.302 compute time: 2 2023-03-30 18:55:04.755 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:04.772 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:04.774 AbstractSettlingCamera TRACE: autoSettleAndCapture t=65 auto settle score: 0.210 compute time: 2 2023-03-30 18:55:04.774 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:04.792 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:04.794 AbstractSettlingCamera TRACE: autoSettleAndCapture t=85 auto settle score: 0.166 compute time: 2 2023-03-30 18:55:04.794 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:04.812 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:04.814 AbstractSettlingCamera TRACE: autoSettleAndCapture t=104 auto settle score: 0.156 compute time: 1 2023-03-30 18:55:04.814 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 105 ms 2023-03-30 18:55:04.814 Scripting TRACE: Scripting.on Camera.AfterSettle 2023-03-30 18:55:04.814 ReferenceActuator DEBUG: UPLED.actuate(false) 2023-03-30 18:55:04.814 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M811 ; UPLED M810 on / M811 off, 10000)... 2023-03-30 18:55:04.814 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M811 2023-03-30 18:55:04.815 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:04.893 MaskHsv TRACE: Fraction actually masked = 0.027978502978503017 2023-03-30 18:55:04.894 MaskHsv TRACE: Fraction actually masked = 0.9978817478817479 2023-03-30 18:55:04.925 ReferenceBottomVision DEBUG: Alignment result: SOT23-BC857 2023-03-30 18:55:04.925 AbstractHeadMountable DEBUG: N1.moveTo((168.530435, 28.707672, -8.227176, 90.000000 mm), 1.0) 2023-03-30 18:55:04.925 ReferenceNozzle TRACE: N1.toHeadLocation((168.530435, 28.707672, -8.227176, -29.726085 mm), ...) rotation mode offset 119.72608469929752 2023-03-30 18:55:04.925 ReferenceNozzle TRACE: N1.toHeadLocation((168.615337, 28.583208, -8.227176, -29.726085 mm), ...) runout compensation (-0.084902, 0.124465, 0.000000, 0.000000 mm) 2023-03-30 18:55:04.925 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M119 ; DRAGPIN endstop status, 10000)... 2023-03-30 18:55:04.926 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M119 2023-03-30 18:55:04.926 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 18:55:04.926 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:04.926 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 18:55:04.926 ReferenceActuator DEBUG: DRAGPIN.read(): 1 2023-03-30 18:55:04.929 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S5217.06 G1 X134.6057 F6532.24 ; move to target, 10000)... 2023-03-30 18:55:04.929 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S5217.06G1X134.6057F6532.24 2023-03-30 18:55:04.929 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:04.929 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S98.43 G1 X134.6682 F600.00 ; move to target, 10000)... 2023-03-30 18:55:04.929 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S98.43G1X134.6682F600 2023-03-30 18:55:04.929 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:04.930 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M400 ; Wait for moves to complete before returning, 10000)... 2023-03-30 18:55:04.930 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M114 ; get position, -1)... 2023-03-30 18:55:04.930 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M400 2023-03-30 18:55:04.930 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M114 2023-03-30 18:55:05.010 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:05.010 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok C: X:134.6682 Y:46.8005 Z:-28.0424 A:-29.7261 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:55:05.010 GcodeDriver TRACE: Position report: ok C: X:134.6682 Y:46.8005 Z:-28.0424 A:-29.7261 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:55:05.010 GcodeDriver TRACE: GcodeDriver got lastReportedLocation (X:134.668200, Y:46.800500, ZN:-28.042400, C1:-29.726100, C2:180.000000) 2023-03-30 18:55:05.011 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2023-03-30 18:55:05.011 ReferenceActuator DEBUG: UPLED.actuate(true) 2023-03-30 18:55:05.011 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M810 ; UPLED M810 on / M811 off, 10000)... 2023-03-30 18:55:05.011 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M810 2023-03-30 18:55:05.011 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:05.061 Scripting TRACE: Scripting.on Camera.BeforeSettle 2023-03-30 18:55:05.062 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:05.079 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:05.080 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:05.099 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:05.101 AbstractSettlingCamera TRACE: autoSettleAndCapture t=40 auto settle score: 0.153 compute time: 2 2023-03-30 18:55:05.101 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:05.126 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:05.129 AbstractSettlingCamera TRACE: autoSettleAndCapture t=67 auto settle score: 0.152 compute time: 2 2023-03-30 18:55:05.129 Scripting TRACE: Scripting.on Camera.BeforeCapture 2023-03-30 18:55:05.145 Scripting TRACE: Scripting.on Camera.AfterCapture 2023-03-30 18:55:05.147 AbstractSettlingCamera TRACE: autoSettleAndCapture t=86 auto settle score: 0.152 compute time: 2 2023-03-30 18:55:05.148 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 87 ms 2023-03-30 18:55:05.148 Scripting TRACE: Scripting.on Camera.AfterSettle 2023-03-30 18:55:05.148 ReferenceActuator DEBUG: UPLED.actuate(false) 2023-03-30 18:55:05.148 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M811 ; UPLED M810 on / M811 off, 10000)... 2023-03-30 18:55:05.148 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M811 2023-03-30 18:55:05.148 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:05.227 MaskHsv TRACE: Fraction actually masked = 0.012690087690087726 2023-03-30 18:55:05.229 MaskHsv TRACE: Fraction actually masked = 0.9982711732711733 2023-03-30 18:55:05.262 ReferenceBottomVision DEBUG: Alignment result: SOT23-BC857 2023-03-30 18:55:05.262 ReferenceBottomVision DEBUG: Bottom vision part SOT23-BC857 result rect { {502.4999999999999, 546.7500000000001} 204x0 * -89.99999999999977 } 2023-03-30 18:55:05.262 ReferenceBottomVision DEBUG: Offsets accepted (0.039924, -0.134744, 0.000000, -0.000000 mm) 2023-03-30 18:55:05.263 ReferenceBottomVision DEBUG: Alignment result: SOT23-BC857 | X:0.040 Y:-0.135 C:-0.000 Δ:0.141 2023-03-30 18:55:05.264 AbstractNozzle TRACE: Set rotation mode offset: 119.72608469929729°. 2023-03-30 18:55:05.264 Scripting TRACE: Scripting.on Vision.PartAlignment.After 2023-03-30 18:55:05.264 AbstractHeadMountable DEBUG: N1.moveTo((167.330511, 28.842417, -8.227176, 90.000000 mm), 1.0) 2023-03-30 18:55:05.264 ReferenceNozzle TRACE: N1.toHeadLocation((167.330511, 28.842417, -8.227176, -29.726085 mm), ...) rotation mode offset 119.72608469929729 2023-03-30 18:55:05.264 ReferenceNozzle TRACE: N1.toHeadLocation((167.415413, 28.717952, -8.227176, -29.726085 mm), ...) runout compensation (-0.084902, 0.124465, 0.000000, 0.000000 mm) 2023-03-30 18:55:05.264 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M119 ; DRAGPIN endstop status, 10000)... 2023-03-30 18:55:05.264 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M119 2023-03-30 18:55:05.264 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 18:55:05.264 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:05.264 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 18:55:05.265 ReferenceActuator DEBUG: DRAGPIN.read(): 1 2023-03-30 18:55:05.267 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S4174.72 G1 X133.5163 Y46.8975 F4168.04 ; move to target, 10000)... 2023-03-30 18:55:05.267 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S4174.72G1X133.5163Y46.8975F4168.04 2023-03-30 18:55:05.267 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:05.267 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M204 S96.81 G1 X133.4538 Y46.9730 F600.00 ; move to target, 10000)... 2023-03-30 18:55:05.267 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M204S96.81G1X133.4538Y46.973F600 2023-03-30 18:55:05.268 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:05.269 AbstractMachine TRACE: Machine entering idle state. 2023-03-30 18:55:05.269 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M400 ; Wait for moves to complete before returning, 10000)... 2023-03-30 18:55:05.269 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M114 ; get position, -1)... 2023-03-30 18:55:05.269 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M400 2023-03-30 18:55:05.269 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M114 2023-03-30 18:55:05.352 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok 2023-03-30 18:55:05.352 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok C: X:133.4538 Y:46.9730 Z:-28.0424 A:-29.7261 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:55:05.352 GcodeDriver TRACE: Position report: ok C: X:133.4538 Y:46.9730 Z:-28.0424 A:-29.7261 B:180.0000 C:6.0000 D:0.0000 2023-03-30 18:55:05.352 GcodeDriver TRACE: GcodeDriver got lastReportedLocation (X:133.453800, Y:46.973000, ZN:-28.042400, C1:-29.726100, C2:180.000000) 2023-03-30 18:55:05.353 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2023-03-30 18:55:05.353 ReferenceActuator DEBUG: UPLED.actuate(true) 2023-03-30 18:55:05.353 GcodeAsyncDriver DEBUG: serial://ttyUSB0 commandQueue.offer(M810 ; UPLED M810 on / M811 off, 10000)... 2023-03-30 18:55:05.353 GcodeAsyncDriver$WriterThread TRACE: [serial://ttyUSB0] >> M810 2023-03-30 18:55:05.353 GcodeDriver$ReaderThread TRACE: [serial://ttyUSB0] << ok