2021-11-15 17:17:24.557 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:17:24.557 ReferenceHead DEBUG: H1.moveToSafeZ(0.88) 2021-11-15 17:17:24.557 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.88) 2021-11-15 17:17:24.557 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.88) 2021-11-15 17:17:24.558 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.88) 2021-11-15 17:17:24.558 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.88) 2021-11-15 17:17:24.558 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.88) 2021-11-15 17:17:24.558 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:24.559 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:24.559 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:24.560 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.61 G1 X93.0324 Y43.1393 F1360.49 ; move to target, 25000 2021-11-15 17:17:24.561 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:24.561 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.61G1X93.0324Y43.1393F1360.49 2021-11-15 17:17:24.562 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.2587 Y43.2193 F293.11 ; move to target, 25000 2021-11-15 17:17:24.563 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:24.563 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.2587Y43.2193F293.11 2021-11-15 17:17:24.563 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:24.673 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:24.674 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:24.674 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:24.680 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:24.683 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:24.696 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:24.699 AbstractCamera TRACE: autoSettleAndCapture t=25 auto settle score: 100.0 2021-11-15 17:17:24.700 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:24.729 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:24.734 AbstractCamera TRACE: autoSettleAndCapture t=60 auto settle score: 100.0 2021-11-15 17:17:24.734 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:24.774 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:24.777 AbstractCamera TRACE: autoSettleAndCapture t=103 auto settle score: 100.0 2021-11-15 17:17:24.777 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:24.806 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:24.809 AbstractCamera TRACE: autoSettleAndCapture t=135 auto settle score: 100.0 2021-11-15 17:17:24.809 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:24.854 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:24.857 AbstractCamera TRACE: autoSettleAndCapture t=183 auto settle score: 100.0 2021-11-15 17:17:24.857 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:24.886 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:24.889 AbstractCamera TRACE: autoSettleAndCapture t=215 auto settle score: 100.0 2021-11-15 17:17:24.889 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:24.934 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:24.938 AbstractCamera TRACE: autoSettleAndCapture t=264 auto settle score: 100.0 2021-11-15 17:17:24.938 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:24.966 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:24.969 AbstractCamera TRACE: autoSettleAndCapture t=295 auto settle score: 100.0 2021-11-15 17:17:24.969 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.014 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.018 AbstractCamera TRACE: autoSettleAndCapture t=344 auto settle score: 100.0 2021-11-15 17:17:25.018 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.047 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.050 AbstractCamera TRACE: autoSettleAndCapture t=376 auto settle score: 100.0 2021-11-15 17:17:25.050 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.094 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.098 AbstractCamera TRACE: autoSettleAndCapture t=424 auto settle score: 100.0 2021-11-15 17:17:25.098 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.126 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.130 AbstractCamera TRACE: autoSettleAndCapture t=456 auto settle score: 100.0 2021-11-15 17:17:25.130 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.174 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.177 AbstractCamera TRACE: autoSettleAndCapture t=503 auto settle score: 100.0 2021-11-15 17:17:25.177 AbstractCamera DEBUG: autoSettleAndCapture in 503 ms 2021-11-15 17:17:25.177 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:25.231 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.235688, 36.967765, 0.000000, 0.000000 mm) 2021-11-15 17:17:25.231 AbstractHeadMountable DEBUG: DOWN.moveTo((92.235688, 36.967765, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:25.233 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:25.233 GcodeDriver DEBUG: [serial://COM3] >> M204 S1171.24 G1 X92.4620 Y37.0478 F5122.31 ; move to target, 25000 2021-11-15 17:17:25.235 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:25.235 GcodeDriver TRACE: [serial://COM3] confirmed M204S1171.24G1X92.462Y37.0478F5122.31 2021-11-15 17:17:25.235 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.2357 Y36.9678 F293.11 ; move to target, 25000 2021-11-15 17:17:25.236 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:25.237 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.2357Y36.9678F293.11 2021-11-15 17:17:25.237 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:25.454 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:25.454 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:25.454 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.460 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.463 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.494 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.497 AbstractCamera TRACE: autoSettleAndCapture t=43 auto settle score: 100.0 2021-11-15 17:17:25.497 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.531 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.534 AbstractCamera TRACE: autoSettleAndCapture t=80 auto settle score: 100.0 2021-11-15 17:17:25.534 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.574 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.577 AbstractCamera TRACE: autoSettleAndCapture t=123 auto settle score: 100.0 2021-11-15 17:17:25.577 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.606 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.609 AbstractCamera TRACE: autoSettleAndCapture t=155 auto settle score: 100.0 2021-11-15 17:17:25.609 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.654 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.657 AbstractCamera TRACE: autoSettleAndCapture t=203 auto settle score: 100.0 2021-11-15 17:17:25.657 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.686 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.689 AbstractCamera TRACE: autoSettleAndCapture t=235 auto settle score: 100.0 2021-11-15 17:17:25.689 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.734 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.737 AbstractCamera TRACE: autoSettleAndCapture t=283 auto settle score: 100.0 2021-11-15 17:17:25.738 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.766 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.769 AbstractCamera TRACE: autoSettleAndCapture t=315 auto settle score: 100.0 2021-11-15 17:17:25.769 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.818 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.821 AbstractCamera TRACE: autoSettleAndCapture t=367 auto settle score: 100.0 2021-11-15 17:17:25.821 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.847 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.850 AbstractCamera TRACE: autoSettleAndCapture t=396 auto settle score: 100.0 2021-11-15 17:17:25.850 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.895 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.898 AbstractCamera TRACE: autoSettleAndCapture t=444 auto settle score: 100.0 2021-11-15 17:17:25.898 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.926 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.929 AbstractCamera TRACE: autoSettleAndCapture t=475 auto settle score: 100.0 2021-11-15 17:17:25.929 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:25.975 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:25.978 AbstractCamera TRACE: autoSettleAndCapture t=524 auto settle score: 100.0 2021-11-15 17:17:25.978 AbstractCamera DEBUG: autoSettleAndCapture in 524 ms 2021-11-15 17:17:25.978 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:26.030 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (90.858566, 32.367566, 0.000000, 0.000000 mm) 2021-11-15 17:17:26.030 AbstractHeadMountable DEBUG: DOWN.moveTo((90.858566, 32.367566, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:26.032 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:26.032 GcodeDriver DEBUG: [serial://COM3] >> M204 S1198.66 G1 X91.0848 Y32.4476 F4486.39 ; move to target, 25000 2021-11-15 17:17:26.033 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:26.034 GcodeDriver TRACE: [serial://COM3] confirmed M204S1198.66G1X91.0848Y32.4476F4486.39 2021-11-15 17:17:26.034 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X90.8586 Y32.3676 F293.11 ; move to target, 25000 2021-11-15 17:17:26.035 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:26.035 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X90.8586Y32.3676F293.11 2021-11-15 17:17:26.035 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:26.231 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:26.231 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:26.232 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.239 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.242 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.249 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.252 AbstractCamera TRACE: autoSettleAndCapture t=20 auto settle score: 100.0 2021-11-15 17:17:26.252 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.295 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.298 AbstractCamera TRACE: autoSettleAndCapture t=66 auto settle score: 100.0 2021-11-15 17:17:26.298 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.330 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.333 AbstractCamera TRACE: autoSettleAndCapture t=101 auto settle score: 100.0 2021-11-15 17:17:26.333 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.374 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.377 AbstractCamera TRACE: autoSettleAndCapture t=145 auto settle score: 100.0 2021-11-15 17:17:26.377 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.406 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.409 AbstractCamera TRACE: autoSettleAndCapture t=177 auto settle score: 100.0 2021-11-15 17:17:26.409 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.454 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.457 AbstractCamera TRACE: autoSettleAndCapture t=225 auto settle score: 100.0 2021-11-15 17:17:26.457 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.486 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.489 AbstractCamera TRACE: autoSettleAndCapture t=257 auto settle score: 100.0 2021-11-15 17:17:26.489 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.534 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.537 AbstractCamera TRACE: autoSettleAndCapture t=305 auto settle score: 100.0 2021-11-15 17:17:26.537 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.567 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.570 AbstractCamera TRACE: autoSettleAndCapture t=338 auto settle score: 100.0 2021-11-15 17:17:26.570 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.615 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.618 AbstractCamera TRACE: autoSettleAndCapture t=386 auto settle score: 100.0 2021-11-15 17:17:26.618 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.649 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.652 AbstractCamera TRACE: autoSettleAndCapture t=420 auto settle score: 100.0 2021-11-15 17:17:26.652 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.694 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.697 AbstractCamera TRACE: autoSettleAndCapture t=465 auto settle score: 100.0 2021-11-15 17:17:26.697 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.727 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.729 AbstractCamera TRACE: autoSettleAndCapture t=497 auto settle score: 100.0 2021-11-15 17:17:26.729 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:26.774 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:26.777 AbstractCamera TRACE: autoSettleAndCapture t=545 auto settle score: 100.0 2021-11-15 17:17:26.777 AbstractCamera DEBUG: autoSettleAndCapture in 545 ms 2021-11-15 17:17:26.777 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:26.825 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (85.586156, 34.608688, 0.000000, 0.000000 mm) 2021-11-15 17:17:26.825 AbstractHeadMountable DEBUG: DOWN.moveTo((85.586156, 34.608688, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:26.827 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (88.222361, 33.488127, 0.000000, 0.000000 mm) 2021-11-15 17:17:26.827 AbstractHeadMountable DEBUG: DOWN.moveTo((88.222361, 33.488127, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:26.827 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: Fiducial Fiducial for visual homing detected too far away. at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getFiducialLocation(ReferenceFiducialLocator.java:454) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getHomeFiducialLocation(ReferenceFiducialLocator.java:302) at org.openpnp.machine.reference.ReferenceHead.visualHome(ReferenceHead.java:75) at org.openpnp.machine.reference.wizards.ReferenceHeadConfigurationWizard$3.lambda$actionPerformed$0(ReferenceHeadConfigurationWizard.java:147) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:17:26.827 GcodeDriver DEBUG: [serial://COM3] >> M204 S1267.24 G1 X85.8124 Y34.5680 F5011.41 ; move to target, 25000 2021-11-15 17:17:26.829 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:26.829 GcodeDriver TRACE: [serial://COM3] confirmed M204S1267.24G1X85.8124Y34.568F5011.41 2021-11-15 17:17:26.829 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X85.5862 Y34.6480 F293.11 ; move to target, 25000 2021-11-15 17:17:26.830 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:26.831 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X85.5862Y34.648F293.11 2021-11-15 17:17:26.831 GcodeDriver DEBUG: [serial://COM3] >> M204 S1266.25 G1 X88.0748 Y33.5681 F3516.58 ; move to target, 25000 2021-11-15 17:17:26.833 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:26.833 GcodeDriver TRACE: [serial://COM3] confirmed M204S1266.25G1X88.0748Y33.5681F3516.58 2021-11-15 17:17:26.833 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X88.3011 Y33.4881 F293.11 ; move to target, 25000 2021-11-15 17:17:26.835 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:26.835 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X88.3011Y33.4881F293.11 2021-11-15 17:17:26.835 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:27.196 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:27.196 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:27.196 MessageBoxes DEBUG: Error: java.lang.Exception: Fiducial Fiducial for visual homing detected too far away. 2021-11-15 17:17:31.635 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:17:31.635 ReferenceHead DEBUG: H1.moveToSafeZ(0.88) 2021-11-15 17:17:31.635 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.88) 2021-11-15 17:17:31.635 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.88) 2021-11-15 17:17:31.635 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.88) 2021-11-15 17:17:31.635 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.88) 2021-11-15 17:17:31.636 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.88) 2021-11-15 17:17:31.636 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:31.636 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:31.637 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:31.637 GcodeDriver DEBUG: [serial://COM3] >> M204 S1293.68 G1 X93.0324 Y43.1393 F7075.20 ; move to target, 25000 2021-11-15 17:17:31.638 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:31.639 GcodeDriver TRACE: [serial://COM3] confirmed M204S1293.68G1X93.0324Y43.1393F7075.2 2021-11-15 17:17:31.639 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.2587 Y43.2193 F293.11 ; move to target, 25000 2021-11-15 17:17:31.640 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:31.640 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.2587Y43.2193F293.11 2021-11-15 17:17:31.640 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:31.894 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:31.894 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:31.894 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:31.904 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:31.907 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:31.915 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:31.918 AbstractCamera TRACE: autoSettleAndCapture t=24 auto settle score: 100.0 2021-11-15 17:17:31.918 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:31.959 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:31.962 AbstractCamera TRACE: autoSettleAndCapture t=68 auto settle score: 100.0 2021-11-15 17:17:31.962 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:31.992 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:31.994 AbstractCamera TRACE: autoSettleAndCapture t=100 auto settle score: 100.0 2021-11-15 17:17:31.994 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.039 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.043 AbstractCamera TRACE: autoSettleAndCapture t=149 auto settle score: 100.0 2021-11-15 17:17:32.043 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.071 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.074 AbstractCamera TRACE: autoSettleAndCapture t=180 auto settle score: 100.0 2021-11-15 17:17:32.074 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.119 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.122 AbstractCamera TRACE: autoSettleAndCapture t=228 auto settle score: 100.0 2021-11-15 17:17:32.122 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.151 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.154 AbstractCamera TRACE: autoSettleAndCapture t=260 auto settle score: 100.0 2021-11-15 17:17:32.154 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.199 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.202 AbstractCamera TRACE: autoSettleAndCapture t=308 auto settle score: 100.0 2021-11-15 17:17:32.203 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.235 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.238 AbstractCamera TRACE: autoSettleAndCapture t=344 auto settle score: 100.0 2021-11-15 17:17:32.238 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.278 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.282 AbstractCamera TRACE: autoSettleAndCapture t=388 auto settle score: 100.0 2021-11-15 17:17:32.282 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.310 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.314 AbstractCamera TRACE: autoSettleAndCapture t=420 auto settle score: 100.0 2021-11-15 17:17:32.314 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.358 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.361 AbstractCamera TRACE: autoSettleAndCapture t=467 auto settle score: 100.0 2021-11-15 17:17:32.361 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.390 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.394 AbstractCamera TRACE: autoSettleAndCapture t=500 auto settle score: 100.0 2021-11-15 17:17:32.394 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.439 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.442 AbstractCamera TRACE: autoSettleAndCapture t=548 auto settle score: 100.0 2021-11-15 17:17:32.442 AbstractCamera DEBUG: autoSettleAndCapture in 548 ms 2021-11-15 17:17:32.442 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:32.494 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.511112, 43.140682, 0.000000, 0.000000 mm) 2021-11-15 17:17:32.494 AbstractHeadMountable DEBUG: DOWN.moveTo((92.511112, 43.140682, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:32.496 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:32.496 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.60 G1 X92.7374 Y43.2207 F1476.48 ; move to target, 25000 2021-11-15 17:17:32.497 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:32.497 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.6G1X92.7374Y43.2207F1476.48 2021-11-15 17:17:32.498 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.5111 Y43.1407 F293.11 ; move to target, 25000 2021-11-15 17:17:32.500 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:32.500 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.5111Y43.1407F293.11 2021-11-15 17:17:32.500 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:32.613 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:32.613 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:32.613 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.620 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.623 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.631 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.634 AbstractCamera TRACE: autoSettleAndCapture t=21 auto settle score: 100.0 2021-11-15 17:17:32.634 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.678 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.681 AbstractCamera TRACE: autoSettleAndCapture t=68 auto settle score: 100.0 2021-11-15 17:17:32.681 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.710 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.713 AbstractCamera TRACE: autoSettleAndCapture t=100 auto settle score: 100.0 2021-11-15 17:17:32.713 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.759 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.762 AbstractCamera TRACE: autoSettleAndCapture t=149 auto settle score: 100.0 2021-11-15 17:17:32.762 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.790 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.793 AbstractCamera TRACE: autoSettleAndCapture t=180 auto settle score: 100.0 2021-11-15 17:17:32.793 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.838 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.841 AbstractCamera TRACE: autoSettleAndCapture t=228 auto settle score: 100.0 2021-11-15 17:17:32.841 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.870 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.873 AbstractCamera TRACE: autoSettleAndCapture t=260 auto settle score: 100.0 2021-11-15 17:17:32.873 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.918 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.921 AbstractCamera TRACE: autoSettleAndCapture t=308 auto settle score: 100.0 2021-11-15 17:17:32.921 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.950 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.953 AbstractCamera TRACE: autoSettleAndCapture t=340 auto settle score: 100.0 2021-11-15 17:17:32.954 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:32.982 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:32.985 AbstractCamera TRACE: autoSettleAndCapture t=372 auto settle score: 100.0 2021-11-15 17:17:32.985 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.031 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.034 AbstractCamera TRACE: autoSettleAndCapture t=421 auto settle score: 100.0 2021-11-15 17:17:33.034 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.062 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.065 AbstractCamera TRACE: autoSettleAndCapture t=452 auto settle score: 100.0 2021-11-15 17:17:33.065 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.114 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.117 AbstractCamera TRACE: autoSettleAndCapture t=504 auto settle score: 100.0 2021-11-15 17:17:33.117 AbstractCamera DEBUG: autoSettleAndCapture in 504 ms 2021-11-15 17:17:33.117 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:33.169 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.550459, 43.140682, 0.000000, 0.000000 mm) 2021-11-15 17:17:33.169 AbstractHeadMountable DEBUG: DOWN.moveTo((92.550459, 43.140682, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:33.170 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:33.170 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.60 G1 X92.4029 F672.77 ; move to target, 25000 2021-11-15 17:17:33.171 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:33.172 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.6G1X92.4029F672.77 2021-11-15 17:17:33.172 GcodeDriver DEBUG: [serial://COM3] >> M204 S93.75 G1 X92.6292 F276.35 ; move to target, 25000 2021-11-15 17:17:33.173 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:33.173 GcodeDriver TRACE: [serial://COM3] confirmed M204S93.75G1X92.6292F276.35 2021-11-15 17:17:33.173 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:33.289 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:33.290 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:33.290 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.299 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.302 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.308 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.311 AbstractCamera TRACE: autoSettleAndCapture t=21 auto settle score: 100.0 2021-11-15 17:17:33.311 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.350 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.354 AbstractCamera TRACE: autoSettleAndCapture t=64 auto settle score: 100.0 2021-11-15 17:17:33.354 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.382 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.385 AbstractCamera TRACE: autoSettleAndCapture t=95 auto settle score: 100.0 2021-11-15 17:17:33.385 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.430 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.433 AbstractCamera TRACE: autoSettleAndCapture t=143 auto settle score: 100.0 2021-11-15 17:17:33.433 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.465 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.467 AbstractCamera TRACE: autoSettleAndCapture t=177 auto settle score: 100.0 2021-11-15 17:17:33.468 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.511 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.514 AbstractCamera TRACE: autoSettleAndCapture t=224 auto settle score: 100.0 2021-11-15 17:17:33.514 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.543 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.547 AbstractCamera TRACE: autoSettleAndCapture t=257 auto settle score: 100.0 2021-11-15 17:17:33.547 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.590 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.593 AbstractCamera TRACE: autoSettleAndCapture t=303 auto settle score: 100.0 2021-11-15 17:17:33.593 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.622 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.625 AbstractCamera TRACE: autoSettleAndCapture t=335 auto settle score: 100.0 2021-11-15 17:17:33.625 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.671 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.675 AbstractCamera TRACE: autoSettleAndCapture t=385 auto settle score: 100.0 2021-11-15 17:17:33.675 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.702 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.705 AbstractCamera TRACE: autoSettleAndCapture t=415 auto settle score: 100.0 2021-11-15 17:17:33.705 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.752 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.755 AbstractCamera TRACE: autoSettleAndCapture t=465 auto settle score: 100.0 2021-11-15 17:17:33.755 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.782 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.785 AbstractCamera TRACE: autoSettleAndCapture t=495 auto settle score: 100.0 2021-11-15 17:17:33.785 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:33.830 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:33.833 AbstractCamera TRACE: autoSettleAndCapture t=543 auto settle score: 100.0 2021-11-15 17:17:33.833 AbstractCamera DEBUG: autoSettleAndCapture in 543 ms 2021-11-15 17:17:33.833 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:33.887 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.471766, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:17:33.887 AbstractHeadMountable DEBUG: DOWN.moveTo((92.471766, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:33.889 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (92.511112, 43.160341, 0.000000, 0.000000 mm) 2021-11-15 17:17:33.889 AbstractHeadMountable DEBUG: DOWN.moveTo((92.511112, 43.160341, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:33.889 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.83 G1 X92.6980 Y43.1393 F536.83 ; move to target, 25000 2021-11-15 17:17:33.890 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:33.890 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.83G1X92.698Y43.1393F536.83 2021-11-15 17:17:33.891 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.4718 Y43.2193 F293.11 ; move to target, 25000 2021-11-15 17:17:33.892 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:33.892 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.4718Y43.2193F293.11 2021-11-15 17:17:33.892 GcodeDriver DEBUG: [serial://COM3] >> M204 S1183.31 G1 X92.3635 Y43.2403 F685.34 ; move to target, 25000 2021-11-15 17:17:33.894 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:33.894 GcodeDriver TRACE: [serial://COM3] confirmed M204S1183.31G1X92.3635Y43.2403F685.34 2021-11-15 17:17:33.894 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.5898 Y43.1603 F293.11 ; move to target, 25000 2021-11-15 17:17:33.895 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:33.895 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.5898Y43.1603F293.11 2021-11-15 17:17:37.363 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:17:37.364 ReferenceHead DEBUG: H1.moveToSafeZ(0.88) 2021-11-15 17:17:37.364 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.88) 2021-11-15 17:17:37.364 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.88) 2021-11-15 17:17:37.364 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.88) 2021-11-15 17:17:37.364 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.88) 2021-11-15 17:17:37.364 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.88) 2021-11-15 17:17:37.364 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:37.365 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:37.365 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:37.366 GcodeDriver DEBUG: [serial://COM3] >> M204 S1162.91 G1 X93.0324 Y43.1393 F1362.01 ; move to target, 25000 2021-11-15 17:17:37.368 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:37.368 GcodeDriver TRACE: [serial://COM3] confirmed M204S1162.91G1X93.0324Y43.1393F1362.01 2021-11-15 17:17:37.368 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.2587 Y43.2193 F293.11 ; move to target, 25000 2021-11-15 17:17:37.370 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:37.370 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.2587Y43.2193F293.11 2021-11-15 17:17:37.370 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:37.480 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:37.480 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:37.480 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.489 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.492 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.499 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.502 AbstractCamera TRACE: autoSettleAndCapture t=22 auto settle score: 100.0 2021-11-15 17:17:37.502 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.543 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.546 AbstractCamera TRACE: autoSettleAndCapture t=66 auto settle score: 100.0 2021-11-15 17:17:37.546 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.575 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.578 AbstractCamera TRACE: autoSettleAndCapture t=98 auto settle score: 100.0 2021-11-15 17:17:37.578 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.623 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.627 AbstractCamera TRACE: autoSettleAndCapture t=147 auto settle score: 100.0 2021-11-15 17:17:37.627 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.655 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.659 AbstractCamera TRACE: autoSettleAndCapture t=179 auto settle score: 100.0 2021-11-15 17:17:37.659 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.708 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.711 AbstractCamera TRACE: autoSettleAndCapture t=231 auto settle score: 100.0 2021-11-15 17:17:37.711 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.735 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.738 AbstractCamera TRACE: autoSettleAndCapture t=258 auto settle score: 100.0 2021-11-15 17:17:37.738 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.783 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.786 AbstractCamera TRACE: autoSettleAndCapture t=306 auto settle score: 100.0 2021-11-15 17:17:37.786 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.814 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.818 AbstractCamera TRACE: autoSettleAndCapture t=338 auto settle score: 100.0 2021-11-15 17:17:37.818 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.863 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.866 AbstractCamera TRACE: autoSettleAndCapture t=386 auto settle score: 100.0 2021-11-15 17:17:37.866 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.895 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.898 AbstractCamera TRACE: autoSettleAndCapture t=418 auto settle score: 100.0 2021-11-15 17:17:37.899 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.943 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.947 AbstractCamera TRACE: autoSettleAndCapture t=467 auto settle score: 100.0 2021-11-15 17:17:37.947 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:37.976 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:37.980 AbstractCamera TRACE: autoSettleAndCapture t=500 auto settle score: 100.0 2021-11-15 17:17:37.980 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.023 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.026 AbstractCamera TRACE: autoSettleAndCapture t=546 auto settle score: 100.0 2021-11-15 17:17:38.026 AbstractCamera DEBUG: autoSettleAndCapture in 546 ms 2021-11-15 17:17:38.026 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:38.079 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.471766, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:17:38.079 AbstractHeadMountable DEBUG: DOWN.moveTo((92.471766, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:38.080 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:38.081 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.60 G1 X92.6980 F1531.18 ; move to target, 25000 2021-11-15 17:17:38.083 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:38.083 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.6G1X92.698F1531.18 2021-11-15 17:17:38.083 GcodeDriver DEBUG: [serial://COM3] >> M204 S93.75 G1 X92.4718 F276.35 ; move to target, 25000 2021-11-15 17:17:38.083 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:38.083 GcodeDriver TRACE: [serial://COM3] confirmed M204S93.75G1X92.4718F276.35 2021-11-15 17:17:38.084 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:38.199 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:38.199 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:38.199 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.208 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.210 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.217 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.220 AbstractCamera TRACE: autoSettleAndCapture t=21 auto settle score: 100.0 2021-11-15 17:17:38.221 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.263 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.266 AbstractCamera TRACE: autoSettleAndCapture t=67 auto settle score: 100.0 2021-11-15 17:17:38.266 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.296 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.299 AbstractCamera TRACE: autoSettleAndCapture t=100 auto settle score: 100.0 2021-11-15 17:17:38.299 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.343 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.345 AbstractCamera TRACE: autoSettleAndCapture t=146 auto settle score: 100.0 2021-11-15 17:17:38.346 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.375 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.377 AbstractCamera TRACE: autoSettleAndCapture t=178 auto settle score: 100.0 2021-11-15 17:17:38.378 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.406 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.409 AbstractCamera TRACE: autoSettleAndCapture t=210 auto settle score: 100.0 2021-11-15 17:17:38.409 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.456 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.459 AbstractCamera TRACE: autoSettleAndCapture t=260 auto settle score: 100.0 2021-11-15 17:17:38.459 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.486 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.489 AbstractCamera TRACE: autoSettleAndCapture t=290 auto settle score: 100.0 2021-11-15 17:17:38.489 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.535 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.538 AbstractCamera TRACE: autoSettleAndCapture t=339 auto settle score: 100.0 2021-11-15 17:17:38.538 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.567 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.570 AbstractCamera TRACE: autoSettleAndCapture t=371 auto settle score: 100.0 2021-11-15 17:17:38.570 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.615 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.618 AbstractCamera TRACE: autoSettleAndCapture t=419 auto settle score: 100.0 2021-11-15 17:17:38.618 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.647 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.650 AbstractCamera TRACE: autoSettleAndCapture t=451 auto settle score: 100.0 2021-11-15 17:17:38.650 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.694 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.697 AbstractCamera TRACE: autoSettleAndCapture t=498 auto settle score: 100.0 2021-11-15 17:17:38.698 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.731 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.734 AbstractCamera TRACE: autoSettleAndCapture t=535 auto settle score: 100.0 2021-11-15 17:17:38.734 AbstractCamera DEBUG: autoSettleAndCapture in 535 ms 2021-11-15 17:17:38.734 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:38.789 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (90.740527, 46.679297, 0.000000, 0.000000 mm) 2021-11-15 17:17:38.789 AbstractHeadMountable DEBUG: DOWN.moveTo((90.740527, 46.679297, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:38.790 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:38.791 GcodeDriver DEBUG: [serial://COM3] >> M204 S1269.14 G1 X90.9668 Y46.6386 F4131.42 ; move to target, 25000 2021-11-15 17:17:38.792 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:38.792 GcodeDriver TRACE: [serial://COM3] confirmed M204S1269.14G1X90.9668Y46.6386F4131.42 2021-11-15 17:17:38.792 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X90.7405 Y46.7186 F293.11 ; move to target, 25000 2021-11-15 17:17:38.793 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:38.794 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X90.7405Y46.7186F293.11 2021-11-15 17:17:38.794 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:38.974 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:38.974 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:38.974 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:38.980 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:38.982 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.015 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.018 AbstractCamera TRACE: autoSettleAndCapture t=44 auto settle score: 100.0 2021-11-15 17:17:39.018 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.048 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.051 AbstractCamera TRACE: autoSettleAndCapture t=77 auto settle score: 100.0 2021-11-15 17:17:39.051 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.094 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.098 AbstractCamera TRACE: autoSettleAndCapture t=124 auto settle score: 100.0 2021-11-15 17:17:39.098 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.126 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.130 AbstractCamera TRACE: autoSettleAndCapture t=156 auto settle score: 100.0 2021-11-15 17:17:39.130 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.174 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.177 AbstractCamera TRACE: autoSettleAndCapture t=203 auto settle score: 100.0 2021-11-15 17:17:39.177 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.206 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.209 AbstractCamera TRACE: autoSettleAndCapture t=235 auto settle score: 100.0 2021-11-15 17:17:39.209 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.254 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.257 AbstractCamera TRACE: autoSettleAndCapture t=283 auto settle score: 100.0 2021-11-15 17:17:39.257 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.287 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.290 AbstractCamera TRACE: autoSettleAndCapture t=316 auto settle score: 100.0 2021-11-15 17:17:39.291 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.334 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.337 AbstractCamera TRACE: autoSettleAndCapture t=363 auto settle score: 100.0 2021-11-15 17:17:39.338 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.366 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.369 AbstractCamera TRACE: autoSettleAndCapture t=395 auto settle score: 100.0 2021-11-15 17:17:39.369 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.415 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.418 AbstractCamera TRACE: autoSettleAndCapture t=444 auto settle score: 100.0 2021-11-15 17:17:39.418 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.446 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.449 AbstractCamera TRACE: autoSettleAndCapture t=475 auto settle score: 100.0 2021-11-15 17:17:39.449 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:39.495 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:39.499 AbstractCamera TRACE: autoSettleAndCapture t=525 auto settle score: 100.0 2021-11-15 17:17:39.499 AbstractCamera DEBUG: autoSettleAndCapture in 525 ms 2021-11-15 17:17:39.499 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:39.552 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.589805, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:17:39.553 AbstractHeadMountable DEBUG: DOWN.moveTo((92.589805, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:39.554 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (91.665166, 44.929648, 0.000000, 0.000000 mm) 2021-11-15 17:17:39.554 AbstractHeadMountable DEBUG: DOWN.moveTo((91.665166, 44.929648, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:39.554 GcodeDriver DEBUG: [serial://COM3] >> M204 S1294.59 G1 X92.4422 Y43.2600 F4238.44 ; move to target, 25000 2021-11-15 17:17:39.556 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:39.556 GcodeDriver TRACE: [serial://COM3] confirmed M204S1294.59G1X92.4422Y43.26F4238.44 2021-11-15 17:17:39.556 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.6685 Y43.1800 F293.11 ; move to target, 25000 2021-11-15 17:17:39.557 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:39.557 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.6685Y43.18F293.11 2021-11-15 17:17:39.558 GcodeDriver DEBUG: [serial://COM3] >> M204 S1276.04 G1 X91.8914 Y44.8890 F2936.67 ; move to target, 25000 2021-11-15 17:17:39.559 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:39.559 GcodeDriver TRACE: [serial://COM3] confirmed M204S1276.04G1X91.8914Y44.889F2936.67 2021-11-15 17:17:39.559 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X91.6652 Y44.9690 F293.11 ; move to target, 25000 2021-11-15 17:17:39.561 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:39.561 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X91.6652Y44.969F293.11 2021-11-15 17:17:39.562 GcodeDriver DEBUG: [serial://COM3] >> G92 X93.1800 Y43.2193 ; reset coordinates, -1 2021-11-15 17:17:39.562 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:39.562 GcodeDriver TRACE: [serial://COM3] confirmed G92X93.18Y43.2193 2021-11-15 17:17:43.524 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:17:43.525 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:43.525 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:43.525 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:43.526 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:43.526 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:43.526 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.533 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.535 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.542 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.545 AbstractCamera TRACE: autoSettleAndCapture t=19 auto settle score: 100.0 2021-11-15 17:17:43.545 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.559 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.562 AbstractCamera TRACE: autoSettleAndCapture t=36 auto settle score: 100.0 2021-11-15 17:17:43.562 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.607 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.610 AbstractCamera TRACE: autoSettleAndCapture t=84 auto settle score: 100.0 2021-11-15 17:17:43.610 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.639 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.642 AbstractCamera TRACE: autoSettleAndCapture t=116 auto settle score: 100.0 2021-11-15 17:17:43.642 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.688 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.692 AbstractCamera TRACE: autoSettleAndCapture t=166 auto settle score: 100.0 2021-11-15 17:17:43.692 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.719 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.722 AbstractCamera TRACE: autoSettleAndCapture t=196 auto settle score: 100.0 2021-11-15 17:17:43.722 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.767 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.770 AbstractCamera TRACE: autoSettleAndCapture t=244 auto settle score: 100.0 2021-11-15 17:17:43.770 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.799 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.802 AbstractCamera TRACE: autoSettleAndCapture t=276 auto settle score: 100.0 2021-11-15 17:17:43.803 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.847 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.850 AbstractCamera TRACE: autoSettleAndCapture t=324 auto settle score: 100.0 2021-11-15 17:17:43.850 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.880 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.882 AbstractCamera TRACE: autoSettleAndCapture t=356 auto settle score: 100.0 2021-11-15 17:17:43.883 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.911 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.914 AbstractCamera TRACE: autoSettleAndCapture t=388 auto settle score: 100.0 2021-11-15 17:17:43.914 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.959 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.962 AbstractCamera TRACE: autoSettleAndCapture t=436 auto settle score: 100.0 2021-11-15 17:17:43.962 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:43.991 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:43.994 AbstractCamera TRACE: autoSettleAndCapture t=468 auto settle score: 100.0 2021-11-15 17:17:43.994 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.039 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.042 AbstractCamera TRACE: autoSettleAndCapture t=516 auto settle score: 100.0 2021-11-15 17:17:44.042 AbstractCamera DEBUG: autoSettleAndCapture in 516 ms 2021-11-15 17:17:44.042 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:44.096 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (94.084966, 41.371375, 0.000000, 0.000000 mm) 2021-11-15 17:17:44.096 AbstractHeadMountable DEBUG: DOWN.moveTo((94.084966, 41.371375, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:44.097 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:44.098 GcodeDriver DEBUG: [serial://COM3] >> M204 S1263.70 G1 X93.9374 Y41.4514 F2958.03 ; move to target, 25000 2021-11-15 17:17:44.099 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:44.099 GcodeDriver TRACE: [serial://COM3] confirmed M204S1263.7G1X93.9374Y41.4514F2958.03 2021-11-15 17:17:44.099 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X94.1637 Y41.3714 F293.11 ; move to target, 25000 2021-11-15 17:17:44.101 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:44.101 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X94.1637Y41.3714F293.11 2021-11-15 17:17:44.101 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:44.250 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:44.250 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:44.250 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.260 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.262 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.278 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.281 AbstractCamera TRACE: autoSettleAndCapture t=31 auto settle score: 100.0 2021-11-15 17:17:44.281 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.310 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.317 AbstractCamera TRACE: autoSettleAndCapture t=67 auto settle score: 100.0 2021-11-15 17:17:44.317 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.358 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.362 AbstractCamera TRACE: autoSettleAndCapture t=112 auto settle score: 100.0 2021-11-15 17:17:44.362 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.390 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.394 AbstractCamera TRACE: autoSettleAndCapture t=144 auto settle score: 100.0 2021-11-15 17:17:44.394 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.438 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.442 AbstractCamera TRACE: autoSettleAndCapture t=192 auto settle score: 100.0 2021-11-15 17:17:44.442 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.470 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.473 AbstractCamera TRACE: autoSettleAndCapture t=223 auto settle score: 100.0 2021-11-15 17:17:44.473 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.519 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.522 AbstractCamera TRACE: autoSettleAndCapture t=272 auto settle score: 100.0 2021-11-15 17:17:44.522 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.550 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.553 AbstractCamera TRACE: autoSettleAndCapture t=303 auto settle score: 100.0 2021-11-15 17:17:44.553 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.600 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.604 AbstractCamera TRACE: autoSettleAndCapture t=354 auto settle score: 100.0 2021-11-15 17:17:44.604 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.632 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.635 AbstractCamera TRACE: autoSettleAndCapture t=385 auto settle score: 100.0 2021-11-15 17:17:44.635 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.679 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.682 AbstractCamera TRACE: autoSettleAndCapture t=432 auto settle score: 100.0 2021-11-15 17:17:44.682 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.712 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.715 AbstractCamera TRACE: autoSettleAndCapture t=465 auto settle score: 100.0 2021-11-15 17:17:44.715 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:44.760 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:44.763 AbstractCamera TRACE: autoSettleAndCapture t=513 auto settle score: 100.0 2021-11-15 17:17:44.764 AbstractCamera DEBUG: autoSettleAndCapture in 514 ms 2021-11-15 17:17:44.764 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:44.817 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (95.619473, 47.269066, 0.000000, 0.000000 mm) 2021-11-15 17:17:44.817 AbstractHeadMountable DEBUG: DOWN.moveTo((95.619473, 47.269066, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:44.819 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:44.819 GcodeDriver DEBUG: [serial://COM3] >> M204 S1190.22 G1 X95.4719 Y47.2284 F5070.95 ; move to target, 25000 2021-11-15 17:17:44.820 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:44.821 GcodeDriver TRACE: [serial://COM3] confirmed M204S1190.22G1X95.4719Y47.2284F5070.95 2021-11-15 17:17:44.821 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X95.6982 Y47.3084 F293.11 ; move to target, 25000 2021-11-15 17:17:44.822 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:44.822 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X95.6982Y47.3084F293.11 2021-11-15 17:17:44.822 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:45.035 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:45.035 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:45.035 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.042 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.044 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.080 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.084 AbstractCamera TRACE: autoSettleAndCapture t=49 auto settle score: 100.0 2021-11-15 17:17:45.084 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.112 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.116 AbstractCamera TRACE: autoSettleAndCapture t=81 auto settle score: 100.0 2021-11-15 17:17:45.116 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.160 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.163 AbstractCamera TRACE: autoSettleAndCapture t=128 auto settle score: 100.0 2021-11-15 17:17:45.163 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.192 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.195 AbstractCamera TRACE: autoSettleAndCapture t=160 auto settle score: 100.0 2021-11-15 17:17:45.195 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.240 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.244 AbstractCamera TRACE: autoSettleAndCapture t=209 auto settle score: 100.0 2021-11-15 17:17:45.244 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.271 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.275 AbstractCamera TRACE: autoSettleAndCapture t=240 auto settle score: 100.0 2021-11-15 17:17:45.275 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.320 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.323 AbstractCamera TRACE: autoSettleAndCapture t=288 auto settle score: 100.0 2021-11-15 17:17:45.323 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.355 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.357 AbstractCamera TRACE: autoSettleAndCapture t=322 auto settle score: 100.0 2021-11-15 17:17:45.358 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.406 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.410 AbstractCamera TRACE: autoSettleAndCapture t=375 auto settle score: 100.0 2021-11-15 17:17:45.411 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.438 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.441 AbstractCamera TRACE: autoSettleAndCapture t=406 auto settle score: 100.0 2021-11-15 17:17:45.441 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.479 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.483 AbstractCamera TRACE: autoSettleAndCapture t=448 auto settle score: 100.0 2021-11-15 17:17:45.483 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.511 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.515 AbstractCamera TRACE: autoSettleAndCapture t=480 auto settle score: 100.0 2021-11-15 17:17:45.515 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:45.559 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:45.562 AbstractCamera TRACE: autoSettleAndCapture t=526 auto settle score: 100.0 2021-11-15 17:17:45.562 AbstractCamera DEBUG: autoSettleAndCapture in 527 ms 2021-11-15 17:17:45.562 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:45.615 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (93.966927, 41.489328, 0.000000, 0.000000 mm) 2021-11-15 17:17:45.615 AbstractHeadMountable DEBUG: DOWN.moveTo((93.966927, 41.489328, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:45.616 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (94.793200, 44.379197, 0.000000, 0.000000 mm) 2021-11-15 17:17:45.616 AbstractHeadMountable DEBUG: DOWN.moveTo((94.793200, 44.379197, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:45.617 GcodeDriver DEBUG: [serial://COM3] >> M204 S1200.88 G1 X94.1932 Y41.5693 F5064.55 ; move to target, 25000 2021-11-15 17:17:45.618 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:45.618 GcodeDriver TRACE: [serial://COM3] confirmed M204S1200.88G1X94.1932Y41.5693F5064.55 2021-11-15 17:17:45.618 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.9669 Y41.4893 F293.11 ; move to target, 25000 2021-11-15 17:17:45.620 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:45.620 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.9669Y41.4893F293.11 2021-11-15 17:17:45.620 GcodeDriver DEBUG: [serial://COM3] >> M204 S1194.10 G1 X94.6456 Y44.3385 F3548.33 ; move to target, 25000 2021-11-15 17:17:45.622 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:45.622 GcodeDriver TRACE: [serial://COM3] confirmed M204S1194.1G1X94.6456Y44.3385F3548.33 2021-11-15 17:17:45.622 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X94.8719 Y44.4185 F293.11 ; move to target, 25000 2021-11-15 17:17:45.623 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:45.623 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X94.8719Y44.4185F293.11 2021-11-15 17:17:45.623 GcodeDriver DEBUG: [serial://COM3] >> G92 X93.2587 Y43.2193 ; reset coordinates, -1 2021-11-15 17:17:45.623 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:45.624 GcodeDriver TRACE: [serial://COM3] confirmed G92X93.2587Y43.2193 2021-11-15 17:17:48.974 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:17:48.975 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:48.975 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:48.976 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:48.976 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:48.976 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:48.977 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:48.983 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:48.985 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:48.992 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:48.996 AbstractCamera TRACE: autoSettleAndCapture t=19 auto settle score: 100.0 2021-11-15 17:17:48.996 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.031 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.034 AbstractCamera TRACE: autoSettleAndCapture t=57 auto settle score: 100.0 2021-11-15 17:17:49.034 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.062 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.065 AbstractCamera TRACE: autoSettleAndCapture t=88 auto settle score: 100.0 2021-11-15 17:17:49.065 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.110 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.113 AbstractCamera TRACE: autoSettleAndCapture t=136 auto settle score: 100.0 2021-11-15 17:17:49.113 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.143 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.146 AbstractCamera TRACE: autoSettleAndCapture t=169 auto settle score: 100.0 2021-11-15 17:17:49.146 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.191 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.194 AbstractCamera TRACE: autoSettleAndCapture t=217 auto settle score: 100.0 2021-11-15 17:17:49.194 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.222 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.226 AbstractCamera TRACE: autoSettleAndCapture t=249 auto settle score: 100.0 2021-11-15 17:17:49.226 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.272 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.275 AbstractCamera TRACE: autoSettleAndCapture t=298 auto settle score: 100.0 2021-11-15 17:17:49.275 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.302 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.305 AbstractCamera TRACE: autoSettleAndCapture t=328 auto settle score: 100.0 2021-11-15 17:17:49.305 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.351 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.354 AbstractCamera TRACE: autoSettleAndCapture t=377 auto settle score: 100.0 2021-11-15 17:17:49.354 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.383 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.386 AbstractCamera TRACE: autoSettleAndCapture t=409 auto settle score: 100.0 2021-11-15 17:17:49.386 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.415 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.418 AbstractCamera TRACE: autoSettleAndCapture t=441 auto settle score: 100.0 2021-11-15 17:17:49.418 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.463 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.466 AbstractCamera TRACE: autoSettleAndCapture t=489 auto settle score: 100.0 2021-11-15 17:17:49.467 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.495 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.498 AbstractCamera TRACE: autoSettleAndCapture t=521 auto settle score: 100.0 2021-11-15 17:17:49.498 AbstractCamera DEBUG: autoSettleAndCapture in 521 ms 2021-11-15 17:17:49.498 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:49.550 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.393073, 40.231154, 0.000000, 0.000000 mm) 2021-11-15 17:17:49.550 AbstractHeadMountable DEBUG: DOWN.moveTo((92.393073, 40.231154, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:49.551 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:49.552 GcodeDriver DEBUG: [serial://COM3] >> M204 S1189.34 G1 X92.6193 Y40.3112 F3570.58 ; move to target, 25000 2021-11-15 17:17:49.553 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:49.553 GcodeDriver TRACE: [serial://COM3] confirmed M204S1189.34G1X92.6193Y40.3112F3570.58 2021-11-15 17:17:49.554 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.3931 Y40.2312 F293.11 ; move to target, 25000 2021-11-15 17:17:49.555 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:49.555 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.3931Y40.2312F293.11 2021-11-15 17:17:49.555 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:49.726 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:49.726 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:49.727 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.733 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.736 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.743 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.746 AbstractCamera TRACE: autoSettleAndCapture t=19 auto settle score: 100.0 2021-11-15 17:17:49.746 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.782 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.786 AbstractCamera TRACE: autoSettleAndCapture t=59 auto settle score: 100.0 2021-11-15 17:17:49.786 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.814 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.817 AbstractCamera TRACE: autoSettleAndCapture t=90 auto settle score: 100.0 2021-11-15 17:17:49.817 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.862 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.866 AbstractCamera TRACE: autoSettleAndCapture t=139 auto settle score: 100.0 2021-11-15 17:17:49.866 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.894 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.899 AbstractCamera TRACE: autoSettleAndCapture t=172 auto settle score: 100.0 2021-11-15 17:17:49.899 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.943 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.946 AbstractCamera TRACE: autoSettleAndCapture t=219 auto settle score: 100.0 2021-11-15 17:17:49.946 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:49.974 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:49.978 AbstractCamera TRACE: autoSettleAndCapture t=251 auto settle score: 100.0 2021-11-15 17:17:49.978 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.023 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.025 AbstractCamera TRACE: autoSettleAndCapture t=298 auto settle score: 100.0 2021-11-15 17:17:50.026 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.055 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.058 AbstractCamera TRACE: autoSettleAndCapture t=331 auto settle score: 100.0 2021-11-15 17:17:50.058 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.103 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.106 AbstractCamera TRACE: autoSettleAndCapture t=379 auto settle score: 100.0 2021-11-15 17:17:50.107 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.134 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.137 AbstractCamera TRACE: autoSettleAndCapture t=410 auto settle score: 100.0 2021-11-15 17:17:50.138 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.183 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.186 AbstractCamera TRACE: autoSettleAndCapture t=459 auto settle score: 100.0 2021-11-15 17:17:50.186 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.215 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.218 AbstractCamera TRACE: autoSettleAndCapture t=491 auto settle score: 100.0 2021-11-15 17:17:50.218 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.262 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.265 AbstractCamera TRACE: autoSettleAndCapture t=538 auto settle score: 100.0 2021-11-15 17:17:50.265 AbstractCamera DEBUG: autoSettleAndCapture in 538 ms 2021-11-15 17:17:50.265 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:50.319 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.904576, 44.634764, 0.000000, 0.000000 mm) 2021-11-15 17:17:50.319 AbstractHeadMountable DEBUG: DOWN.moveTo((92.904576, 44.634764, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:50.321 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:50.321 GcodeDriver DEBUG: [serial://COM3] >> M204 S1165.63 G1 X92.7570 Y44.5941 F4286.22 ; move to target, 25000 2021-11-15 17:17:50.322 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:50.322 GcodeDriver TRACE: [serial://COM3] confirmed M204S1165.63G1X92.757Y44.5941F4286.22 2021-11-15 17:17:50.322 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.9833 Y44.6741 F293.11 ; move to target, 25000 2021-11-15 17:17:50.323 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:50.323 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.9833Y44.6741F293.11 2021-11-15 17:17:50.323 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:50.517 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:50.517 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:50.517 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.525 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.528 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.536 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.539 AbstractCamera TRACE: autoSettleAndCapture t=22 auto settle score: 100.0 2021-11-15 17:17:50.539 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.583 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.585 AbstractCamera TRACE: autoSettleAndCapture t=68 auto settle score: 100.0 2021-11-15 17:17:50.586 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.615 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.618 AbstractCamera TRACE: autoSettleAndCapture t=101 auto settle score: 100.0 2021-11-15 17:17:50.618 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.663 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.666 AbstractCamera TRACE: autoSettleAndCapture t=149 auto settle score: 100.0 2021-11-15 17:17:50.666 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.695 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.698 AbstractCamera TRACE: autoSettleAndCapture t=181 auto settle score: 100.0 2021-11-15 17:17:50.698 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.743 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.746 AbstractCamera TRACE: autoSettleAndCapture t=229 auto settle score: 100.0 2021-11-15 17:17:50.746 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.775 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.778 AbstractCamera TRACE: autoSettleAndCapture t=261 auto settle score: 100.0 2021-11-15 17:17:50.778 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.823 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.826 AbstractCamera TRACE: autoSettleAndCapture t=309 auto settle score: 100.0 2021-11-15 17:17:50.826 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.855 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.858 AbstractCamera TRACE: autoSettleAndCapture t=341 auto settle score: 100.0 2021-11-15 17:17:50.858 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.903 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.906 AbstractCamera TRACE: autoSettleAndCapture t=389 auto settle score: 100.0 2021-11-15 17:17:50.906 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.935 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.938 AbstractCamera TRACE: autoSettleAndCapture t=421 auto settle score: 100.0 2021-11-15 17:17:50.938 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:50.983 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:50.986 AbstractCamera TRACE: autoSettleAndCapture t=469 auto settle score: 100.0 2021-11-15 17:17:50.986 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:51.015 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:51.018 AbstractCamera TRACE: autoSettleAndCapture t=501 auto settle score: 100.0 2021-11-15 17:17:51.018 AbstractCamera DEBUG: autoSettleAndCapture in 501 ms 2021-11-15 17:17:51.018 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:51.070 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.432420, 40.231154, 0.000000, 0.000000 mm) 2021-11-15 17:17:51.070 AbstractHeadMountable DEBUG: DOWN.moveTo((92.432420, 40.231154, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:51.071 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (92.668498, 42.432959, 0.000000, 0.000000 mm) 2021-11-15 17:17:51.071 AbstractHeadMountable DEBUG: DOWN.moveTo((92.668498, 42.432959, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:51.072 GcodeDriver DEBUG: [serial://COM3] >> M204 S1164.81 G1 X92.6587 Y40.3112 F4283.19 ; move to target, 25000 2021-11-15 17:17:51.073 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:51.073 GcodeDriver TRACE: [serial://COM3] confirmed M204S1164.81G1X92.6587Y40.3112F4283.19 2021-11-15 17:17:51.074 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.4324 Y40.2312 F293.11 ; move to target, 25000 2021-11-15 17:17:51.075 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:51.075 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.4324Y40.2312F293.11 2021-11-15 17:17:51.075 GcodeDriver DEBUG: [serial://COM3] >> M204 S1162.57 G1 X92.5209 Y42.3923 F3008.73 ; move to target, 25000 2021-11-15 17:17:51.077 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:51.077 GcodeDriver TRACE: [serial://COM3] confirmed M204S1162.57G1X92.5209Y42.3923F3008.73 2021-11-15 17:17:51.077 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.7472 Y42.4723 F293.11 ; move to target, 25000 2021-11-15 17:17:51.078 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:51.078 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.7472Y42.4723F293.11 2021-11-15 17:17:56.670 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:17:56.671 ReferenceHead DEBUG: H1.moveToSafeZ(0.88) 2021-11-15 17:17:56.671 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.88) 2021-11-15 17:17:56.671 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.88) 2021-11-15 17:17:56.671 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.88) 2021-11-15 17:17:56.671 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.88) 2021-11-15 17:17:56.671 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.88) 2021-11-15 17:17:56.671 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:56.672 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:56.672 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:56.673 GcodeDriver DEBUG: [serial://COM3] >> M204 S1263.34 G1 X93.0324 Y43.1393 F1816.43 ; move to target, 25000 2021-11-15 17:17:56.674 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:56.674 GcodeDriver TRACE: [serial://COM3] confirmed M204S1263.34G1X93.0324Y43.1393F1816.43 2021-11-15 17:17:56.674 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.2587 Y43.2193 F293.11 ; move to target, 25000 2021-11-15 17:17:56.676 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:56.676 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.2587Y43.2193F293.11 2021-11-15 17:17:56.676 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:56.795 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:56.795 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:56.796 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:56.803 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:56.806 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:56.813 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:56.816 AbstractCamera TRACE: autoSettleAndCapture t=20 auto settle score: 100.0 2021-11-15 17:17:56.816 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:56.839 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:56.842 AbstractCamera TRACE: autoSettleAndCapture t=46 auto settle score: 100.0 2021-11-15 17:17:56.842 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:56.887 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:56.891 AbstractCamera TRACE: autoSettleAndCapture t=95 auto settle score: 100.0 2021-11-15 17:17:56.891 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:56.919 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:56.922 AbstractCamera TRACE: autoSettleAndCapture t=126 auto settle score: 100.0 2021-11-15 17:17:56.922 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:56.967 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:56.970 AbstractCamera TRACE: autoSettleAndCapture t=174 auto settle score: 100.0 2021-11-15 17:17:56.970 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:56.999 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.002 AbstractCamera TRACE: autoSettleAndCapture t=206 auto settle score: 100.0 2021-11-15 17:17:57.002 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.047 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.050 AbstractCamera TRACE: autoSettleAndCapture t=254 auto settle score: 100.0 2021-11-15 17:17:57.050 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.079 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.082 AbstractCamera TRACE: autoSettleAndCapture t=286 auto settle score: 100.0 2021-11-15 17:17:57.082 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.128 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.131 AbstractCamera TRACE: autoSettleAndCapture t=335 auto settle score: 100.0 2021-11-15 17:17:57.131 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.159 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.162 AbstractCamera TRACE: autoSettleAndCapture t=366 auto settle score: 100.0 2021-11-15 17:17:57.162 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.208 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.211 AbstractCamera TRACE: autoSettleAndCapture t=415 auto settle score: 100.0 2021-11-15 17:17:57.211 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.239 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.242 AbstractCamera TRACE: autoSettleAndCapture t=446 auto settle score: 100.0 2021-11-15 17:17:57.242 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.287 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.290 AbstractCamera TRACE: autoSettleAndCapture t=494 auto settle score: 100.0 2021-11-15 17:17:57.290 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.319 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.322 AbstractCamera TRACE: autoSettleAndCapture t=526 auto settle score: 100.0 2021-11-15 17:17:57.322 AbstractCamera DEBUG: autoSettleAndCapture in 526 ms 2021-11-15 17:17:57.322 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:57.375 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.393073, 40.231154, 0.000000, 0.000000 mm) 2021-11-15 17:17:57.376 AbstractHeadMountable DEBUG: DOWN.moveTo((92.393073, 40.231154, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:57.378 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:57.379 GcodeDriver DEBUG: [serial://COM3] >> M204 S1189.34 G1 X92.6193 Y40.3112 F3570.58 ; move to target, 25000 2021-11-15 17:17:57.380 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:57.380 GcodeDriver TRACE: [serial://COM3] confirmed M204S1189.34G1X92.6193Y40.3112F3570.58 2021-11-15 17:17:57.380 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.3931 Y40.2312 F293.11 ; move to target, 25000 2021-11-15 17:17:57.382 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:57.382 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.3931Y40.2312F293.11 2021-11-15 17:17:57.382 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:57.552 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:57.552 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:57.552 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.559 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.562 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.569 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.572 AbstractCamera TRACE: autoSettleAndCapture t=20 auto settle score: 100.0 2021-11-15 17:17:57.572 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.606 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.609 AbstractCamera TRACE: autoSettleAndCapture t=57 auto settle score: 100.0 2021-11-15 17:17:57.609 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.638 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.641 AbstractCamera TRACE: autoSettleAndCapture t=89 auto settle score: 100.0 2021-11-15 17:17:57.641 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.670 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.673 AbstractCamera TRACE: autoSettleAndCapture t=121 auto settle score: 100.0 2021-11-15 17:17:57.673 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.719 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.722 AbstractCamera TRACE: autoSettleAndCapture t=170 auto settle score: 100.0 2021-11-15 17:17:57.722 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.750 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.753 AbstractCamera TRACE: autoSettleAndCapture t=201 auto settle score: 100.0 2021-11-15 17:17:57.753 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.799 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.801 AbstractCamera TRACE: autoSettleAndCapture t=249 auto settle score: 100.0 2021-11-15 17:17:57.801 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.831 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.835 AbstractCamera TRACE: autoSettleAndCapture t=283 auto settle score: 100.0 2021-11-15 17:17:57.835 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.878 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.882 AbstractCamera TRACE: autoSettleAndCapture t=330 auto settle score: 100.0 2021-11-15 17:17:57.882 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.911 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.914 AbstractCamera TRACE: autoSettleAndCapture t=362 auto settle score: 100.0 2021-11-15 17:17:57.916 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.958 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.961 AbstractCamera TRACE: autoSettleAndCapture t=409 auto settle score: 100.0 2021-11-15 17:17:57.961 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:57.990 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:57.995 AbstractCamera TRACE: autoSettleAndCapture t=442 auto settle score: 100.0 2021-11-15 17:17:57.995 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.038 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.041 AbstractCamera TRACE: autoSettleAndCapture t=489 auto settle score: 100.0 2021-11-15 17:17:58.042 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.070 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.073 AbstractCamera TRACE: autoSettleAndCapture t=521 auto settle score: 100.0 2021-11-15 17:17:58.073 AbstractCamera DEBUG: autoSettleAndCapture in 521 ms 2021-11-15 17:17:58.073 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:58.125 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.235688, 37.125037, 0.000000, 0.000000 mm) 2021-11-15 17:17:58.125 AbstractHeadMountable DEBUG: DOWN.moveTo((92.235688, 37.125037, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:58.127 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:17:58.127 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.60 G1 Y37.2050 F3557.32 ; move to target, 25000 2021-11-15 17:17:58.128 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:58.128 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.6G1Y37.205F3557.32 2021-11-15 17:17:58.129 GcodeDriver DEBUG: [serial://COM3] >> M204 S105.17 G1 X92.2357 Y37.1250 F258.54 ; move to target, 25000 2021-11-15 17:17:58.130 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:58.130 GcodeDriver TRACE: [serial://COM3] confirmed M204S105.17G1X92.2357Y37.125F258.54 2021-11-15 17:17:58.130 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:58.291 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:58.291 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:58.292 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.301 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.304 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.312 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.315 AbstractCamera TRACE: autoSettleAndCapture t=23 auto settle score: 100.0 2021-11-15 17:17:58.315 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.358 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.361 AbstractCamera TRACE: autoSettleAndCapture t=69 auto settle score: 100.0 2021-11-15 17:17:58.361 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.390 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.393 AbstractCamera TRACE: autoSettleAndCapture t=101 auto settle score: 100.0 2021-11-15 17:17:58.393 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.443 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.446 AbstractCamera TRACE: autoSettleAndCapture t=154 auto settle score: 100.0 2021-11-15 17:17:58.446 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.470 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.473 AbstractCamera TRACE: autoSettleAndCapture t=181 auto settle score: 100.0 2021-11-15 17:17:58.473 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.518 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.521 AbstractCamera TRACE: autoSettleAndCapture t=229 auto settle score: 100.0 2021-11-15 17:17:58.521 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.550 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.553 AbstractCamera TRACE: autoSettleAndCapture t=261 auto settle score: 100.0 2021-11-15 17:17:58.553 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.598 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.601 AbstractCamera TRACE: autoSettleAndCapture t=309 auto settle score: 100.0 2021-11-15 17:17:58.601 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.630 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.634 AbstractCamera TRACE: autoSettleAndCapture t=342 auto settle score: 100.0 2021-11-15 17:17:58.634 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.678 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.681 AbstractCamera TRACE: autoSettleAndCapture t=389 auto settle score: 100.0 2021-11-15 17:17:58.681 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.711 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.713 AbstractCamera TRACE: autoSettleAndCapture t=421 auto settle score: 100.0 2021-11-15 17:17:58.713 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.758 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.761 AbstractCamera TRACE: autoSettleAndCapture t=469 auto settle score: 100.0 2021-11-15 17:17:58.761 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:17:58.793 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:17:58.796 AbstractCamera TRACE: autoSettleAndCapture t=504 auto settle score: 100.0 2021-11-15 17:17:58.797 AbstractCamera DEBUG: autoSettleAndCapture in 505 ms 2021-11-15 17:17:58.797 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:17:58.847 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.511112, 40.152518, 0.000000, 0.000000 mm) 2021-11-15 17:17:58.848 AbstractHeadMountable DEBUG: DOWN.moveTo((92.511112, 40.152518, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:58.849 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (92.373400, 38.638778, 0.000000, 0.000000 mm) 2021-11-15 17:17:58.849 AbstractHeadMountable DEBUG: DOWN.moveTo((92.373400, 38.638778, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:17:58.849 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: Fiducial Fiducial for visual homing detected too far away. at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getFiducialLocation(ReferenceFiducialLocator.java:454) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getHomeFiducialLocation(ReferenceFiducialLocator.java:302) at org.openpnp.machine.reference.ReferenceHead.visualHome(ReferenceHead.java:75) at org.openpnp.machine.reference.wizards.ReferenceHeadConfigurationWizard$3.lambda$actionPerformed$0(ReferenceHeadConfigurationWizard.java:147) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:17:58.850 GcodeDriver DEBUG: [serial://COM3] >> M204 S1162.66 G1 X92.3635 Y40.1118 F3537.37 ; move to target, 25000 2021-11-15 17:17:58.851 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:58.851 GcodeDriver TRACE: [serial://COM3] confirmed M204S1162.66G1X92.3635Y40.1118F3537.37 2021-11-15 17:17:58.851 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.5898 Y40.1918 F293.11 ; move to target, 25000 2021-11-15 17:17:58.853 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:58.853 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.5898Y40.1918F293.11 2021-11-15 17:17:58.853 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.63 G1 X92.5997 Y38.7188 F2481.99 ; move to target, 25000 2021-11-15 17:17:58.855 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:58.855 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.63G1X92.5997Y38.7188F2481.99 2021-11-15 17:17:58.855 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.3734 Y38.6388 F293.11 ; move to target, 25000 2021-11-15 17:17:58.857 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:58.857 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.3734Y38.6388F293.11 2021-11-15 17:17:58.857 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:17:59.148 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:17:59.149 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:17:59.149 MessageBoxes DEBUG: Error: java.lang.Exception: Fiducial Fiducial for visual homing detected too far away. 2021-11-15 17:18:03.295 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:18:03.295 ReferenceHead DEBUG: H1.moveToSafeZ(0.88) 2021-11-15 17:18:03.296 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.88) 2021-11-15 17:18:03.296 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.88) 2021-11-15 17:18:03.296 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.88) 2021-11-15 17:18:03.296 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.88) 2021-11-15 17:18:03.296 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.88) 2021-11-15 17:18:03.296 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:03.297 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:03.297 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:18:03.298 GcodeDriver DEBUG: [serial://COM3] >> M204 S1173.99 G1 X93.0324 Y43.1393 F4384.49 ; move to target, 25000 2021-11-15 17:18:03.300 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:03.300 GcodeDriver TRACE: [serial://COM3] confirmed M204S1173.99G1X93.0324Y43.1393F4384.49 2021-11-15 17:18:03.300 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.2587 Y43.2193 F293.11 ; move to target, 25000 2021-11-15 17:18:03.301 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:03.301 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.2587Y43.2193F293.11 2021-11-15 17:18:03.302 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:03.496 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:03.496 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:03.496 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.503 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.505 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.542 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.545 AbstractCamera TRACE: autoSettleAndCapture t=49 auto settle score: 100.0 2021-11-15 17:18:03.546 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.574 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.577 AbstractCamera TRACE: autoSettleAndCapture t=81 auto settle score: 100.0 2021-11-15 17:18:03.578 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.623 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.626 AbstractCamera TRACE: autoSettleAndCapture t=130 auto settle score: 100.0 2021-11-15 17:18:03.626 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.654 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.657 AbstractCamera TRACE: autoSettleAndCapture t=161 auto settle score: 100.0 2021-11-15 17:18:03.657 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.702 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.705 AbstractCamera TRACE: autoSettleAndCapture t=209 auto settle score: 100.0 2021-11-15 17:18:03.705 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.734 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.737 AbstractCamera TRACE: autoSettleAndCapture t=241 auto settle score: 100.0 2021-11-15 17:18:03.737 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.783 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.785 AbstractCamera TRACE: autoSettleAndCapture t=289 auto settle score: 100.0 2021-11-15 17:18:03.786 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.815 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.818 AbstractCamera TRACE: autoSettleAndCapture t=322 auto settle score: 100.0 2021-11-15 17:18:03.818 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.863 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.866 AbstractCamera TRACE: autoSettleAndCapture t=370 auto settle score: 100.0 2021-11-15 17:18:03.866 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.894 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.897 AbstractCamera TRACE: autoSettleAndCapture t=401 auto settle score: 100.0 2021-11-15 17:18:03.898 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.942 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.945 AbstractCamera TRACE: autoSettleAndCapture t=449 auto settle score: 100.0 2021-11-15 17:18:03.945 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:03.974 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:03.977 AbstractCamera TRACE: autoSettleAndCapture t=481 auto settle score: 100.0 2021-11-15 17:18:03.977 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.022 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.025 AbstractCamera TRACE: autoSettleAndCapture t=529 auto settle score: 100.0 2021-11-15 17:18:04.025 AbstractCamera DEBUG: autoSettleAndCapture in 529 ms 2021-11-15 17:18:04.025 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:18:04.077 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (89.127327, 40.624334, 0.000000, 0.000000 mm) 2021-11-15 17:18:04.077 AbstractHeadMountable DEBUG: DOWN.moveTo((89.127327, 40.624334, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:04.079 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:18:04.080 GcodeDriver DEBUG: [serial://COM3] >> M204 S1381.66 G1 X89.3536 Y40.7043 F4806.60 ; move to target, 25000 2021-11-15 17:18:04.081 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:04.081 GcodeDriver TRACE: [serial://COM3] confirmed M204S1381.66G1X89.3536Y40.7043F4806.6 2021-11-15 17:18:04.081 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X89.1273 Y40.6243 F293.11 ; move to target, 25000 2021-11-15 17:18:04.083 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:04.083 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X89.1273Y40.6243F293.11 2021-11-15 17:18:04.083 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:04.271 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:04.271 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:04.271 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.277 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.280 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.294 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.297 AbstractCamera TRACE: autoSettleAndCapture t=26 auto settle score: 100.0 2021-11-15 17:18:04.298 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.342 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.345 AbstractCamera TRACE: autoSettleAndCapture t=74 auto settle score: 100.0 2021-11-15 17:18:04.345 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.374 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.377 AbstractCamera TRACE: autoSettleAndCapture t=106 auto settle score: 100.0 2021-11-15 17:18:04.378 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.422 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.425 AbstractCamera TRACE: autoSettleAndCapture t=154 auto settle score: 100.0 2021-11-15 17:18:04.425 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.454 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.458 AbstractCamera TRACE: autoSettleAndCapture t=187 auto settle score: 100.0 2021-11-15 17:18:04.458 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.502 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.505 AbstractCamera TRACE: autoSettleAndCapture t=234 auto settle score: 100.0 2021-11-15 17:18:04.505 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.534 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.537 AbstractCamera TRACE: autoSettleAndCapture t=266 auto settle score: 100.0 2021-11-15 17:18:04.537 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.584 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.587 AbstractCamera TRACE: autoSettleAndCapture t=316 auto settle score: 100.0 2021-11-15 17:18:04.587 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.615 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.618 AbstractCamera TRACE: autoSettleAndCapture t=347 auto settle score: 100.0 2021-11-15 17:18:04.618 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.663 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.666 AbstractCamera TRACE: autoSettleAndCapture t=395 auto settle score: 100.0 2021-11-15 17:18:04.666 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.695 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.698 AbstractCamera TRACE: autoSettleAndCapture t=427 auto settle score: 100.0 2021-11-15 17:18:04.698 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.743 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.746 AbstractCamera TRACE: autoSettleAndCapture t=475 auto settle score: 100.0 2021-11-15 17:18:04.746 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:04.775 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:04.778 AbstractCamera TRACE: autoSettleAndCapture t=507 auto settle score: 100.0 2021-11-15 17:18:04.778 AbstractCamera DEBUG: autoSettleAndCapture in 507 ms 2021-11-15 17:18:04.778 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:18:04.833 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (88.733863, 44.123631, 0.000000, 0.000000 mm) 2021-11-15 17:18:04.833 AbstractHeadMountable DEBUG: DOWN.moveTo((88.733863, 44.123631, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:04.835 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:18:04.835 GcodeDriver DEBUG: [serial://COM3] >> M204 S1162.96 G1 X88.9601 Y44.0829 F3807.48 ; move to target, 25000 2021-11-15 17:18:04.837 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:04.837 GcodeDriver TRACE: [serial://COM3] confirmed M204S1162.96G1X88.9601Y44.0829F3807.48 2021-11-15 17:18:04.837 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X88.7339 Y44.1629 F293.11 ; move to target, 25000 2021-11-15 17:18:04.838 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:04.838 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X88.7339Y44.1629F293.11 2021-11-15 17:18:04.839 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:05.018 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:05.018 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:05.018 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.025 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.027 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.064 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.067 AbstractCamera TRACE: autoSettleAndCapture t=49 auto settle score: 100.0 2021-11-15 17:18:05.067 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.094 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.097 AbstractCamera TRACE: autoSettleAndCapture t=79 auto settle score: 100.0 2021-11-15 17:18:05.097 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.142 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.146 AbstractCamera TRACE: autoSettleAndCapture t=128 auto settle score: 100.0 2021-11-15 17:18:05.146 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.175 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.178 AbstractCamera TRACE: autoSettleAndCapture t=160 auto settle score: 100.0 2021-11-15 17:18:05.178 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.223 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.226 AbstractCamera TRACE: autoSettleAndCapture t=208 auto settle score: 100.0 2021-11-15 17:18:05.226 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.255 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.258 AbstractCamera TRACE: autoSettleAndCapture t=240 auto settle score: 100.0 2021-11-15 17:18:05.258 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.303 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.306 AbstractCamera TRACE: autoSettleAndCapture t=288 auto settle score: 100.0 2021-11-15 17:18:05.306 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.335 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.337 AbstractCamera TRACE: autoSettleAndCapture t=319 auto settle score: 100.0 2021-11-15 17:18:05.337 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.383 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.386 AbstractCamera TRACE: autoSettleAndCapture t=368 auto settle score: 100.0 2021-11-15 17:18:05.386 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.415 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.417 AbstractCamera TRACE: autoSettleAndCapture t=399 auto settle score: 100.0 2021-11-15 17:18:05.417 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.463 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.465 AbstractCamera TRACE: autoSettleAndCapture t=447 auto settle score: 100.0 2021-11-15 17:18:05.465 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.495 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.498 AbstractCamera TRACE: autoSettleAndCapture t=479 auto settle score: 100.0 2021-11-15 17:18:05.498 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:05.543 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:05.546 AbstractCamera TRACE: autoSettleAndCapture t=528 auto settle score: 100.0 2021-11-15 17:18:05.546 AbstractCamera DEBUG: autoSettleAndCapture in 528 ms 2021-11-15 17:18:05.546 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:18:05.603 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (86.137005, 41.607282, 0.000000, 0.000000 mm) 2021-11-15 17:18:05.603 AbstractHeadMountable DEBUG: DOWN.moveTo((86.137005, 41.607282, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:05.604 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (87.435434, 42.865456, 0.000000, 0.000000 mm) 2021-11-15 17:18:05.604 AbstractHeadMountable DEBUG: DOWN.moveTo((87.435434, 42.865456, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:05.605 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: Fiducial Fiducial for visual homing detected too far away. at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getFiducialLocation(ReferenceFiducialLocator.java:454) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getHomeFiducialLocation(ReferenceFiducialLocator.java:302) at org.openpnp.machine.reference.ReferenceHead.visualHome(ReferenceHead.java:75) at org.openpnp.machine.reference.wizards.ReferenceHeadConfigurationWizard$3.lambda$actionPerformed$0(ReferenceHeadConfigurationWizard.java:147) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:18:05.605 GcodeDriver DEBUG: [serial://COM3] >> M204 S1608.26 G1 X86.3633 Y41.6873 F4454.78 ; move to target, 25000 2021-11-15 17:18:05.606 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:05.606 GcodeDriver TRACE: [serial://COM3] confirmed M204S1608.26G1X86.3633Y41.6873F4454.78 2021-11-15 17:18:05.607 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X86.1370 Y41.6073 F293.11 ; move to target, 25000 2021-11-15 17:18:05.608 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:05.608 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X86.137Y41.6073F293.11 2021-11-15 17:18:05.608 GcodeDriver DEBUG: [serial://COM3] >> M204 S1598.42 G1 X87.2879 Y42.8248 F3104.90 ; move to target, 25000 2021-11-15 17:18:05.610 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:05.610 GcodeDriver TRACE: [serial://COM3] confirmed M204S1598.42G1X87.2879Y42.8248F3104.9 2021-11-15 17:18:05.610 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X87.5141 Y42.9048 F293.11 ; move to target, 25000 2021-11-15 17:18:05.611 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:05.611 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X87.5141Y42.9048F293.11 2021-11-15 17:18:05.611 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:05.899 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:05.900 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:05.900 MessageBoxes DEBUG: Error: java.lang.Exception: Fiducial Fiducial for visual homing detected too far away. 2021-11-15 17:18:12.113 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:18:12.113 ReferenceHead DEBUG: H1.moveToSafeZ(0.88) 2021-11-15 17:18:12.113 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.88) 2021-11-15 17:18:12.113 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.88) 2021-11-15 17:18:12.113 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.88) 2021-11-15 17:18:12.113 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.88) 2021-11-15 17:18:12.113 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.88) 2021-11-15 17:18:12.113 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:12.114 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:12.115 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:18:12.115 GcodeDriver DEBUG: [serial://COM3] >> M204 S1162.65 G1 X93.0324 Y43.1393 F4808.10 ; move to target, 25000 2021-11-15 17:18:12.117 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:12.117 GcodeDriver TRACE: [serial://COM3] confirmed M204S1162.65G1X93.0324Y43.1393F4808.1 2021-11-15 17:18:12.117 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.2587 Y43.2193 F293.11 ; move to target, 25000 2021-11-15 17:18:12.118 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:12.118 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.2587Y43.2193F293.11 2021-11-15 17:18:12.119 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:12.327 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:12.327 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:12.327 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.336 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.338 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.358 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.361 AbstractCamera TRACE: autoSettleAndCapture t=34 auto settle score: 100.0 2021-11-15 17:18:12.361 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.391 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.394 AbstractCamera TRACE: autoSettleAndCapture t=67 auto settle score: 100.0 2021-11-15 17:18:12.394 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.438 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.441 AbstractCamera TRACE: autoSettleAndCapture t=114 auto settle score: 100.0 2021-11-15 17:18:12.441 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.470 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.473 AbstractCamera TRACE: autoSettleAndCapture t=146 auto settle score: 100.0 2021-11-15 17:18:12.473 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.518 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.521 AbstractCamera TRACE: autoSettleAndCapture t=194 auto settle score: 100.0 2021-11-15 17:18:12.521 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.551 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.554 AbstractCamera TRACE: autoSettleAndCapture t=227 auto settle score: 100.0 2021-11-15 17:18:12.554 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.599 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.603 AbstractCamera TRACE: autoSettleAndCapture t=276 auto settle score: 100.0 2021-11-15 17:18:12.603 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.630 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.633 AbstractCamera TRACE: autoSettleAndCapture t=306 auto settle score: 100.0 2021-11-15 17:18:12.633 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.678 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.681 AbstractCamera TRACE: autoSettleAndCapture t=354 auto settle score: 100.0 2021-11-15 17:18:12.681 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.710 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.713 AbstractCamera TRACE: autoSettleAndCapture t=386 auto settle score: 100.0 2021-11-15 17:18:12.713 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.758 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.762 AbstractCamera TRACE: autoSettleAndCapture t=435 auto settle score: 100.0 2021-11-15 17:18:12.762 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.791 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.794 AbstractCamera TRACE: autoSettleAndCapture t=467 auto settle score: 100.0 2021-11-15 17:18:12.794 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:12.839 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:12.842 AbstractCamera TRACE: autoSettleAndCapture t=515 auto settle score: 100.0 2021-11-15 17:18:12.842 AbstractCamera DEBUG: autoSettleAndCapture in 515 ms 2021-11-15 17:18:12.842 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:18:12.894 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.353727, 40.231154, 0.000000, 0.000000 mm) 2021-11-15 17:18:12.894 AbstractHeadMountable DEBUG: DOWN.moveTo((92.353727, 40.231154, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:12.896 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:18:12.896 GcodeDriver DEBUG: [serial://COM3] >> M204 S1192.81 G1 X92.5800 Y40.3112 F3581.00 ; move to target, 25000 2021-11-15 17:18:12.897 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:12.897 GcodeDriver TRACE: [serial://COM3] confirmed M204S1192.81G1X92.58Y40.3112F3581 2021-11-15 17:18:12.898 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.3537 Y40.2312 F293.11 ; move to target, 25000 2021-11-15 17:18:12.899 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:12.899 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.3537Y40.2312F293.11 2021-11-15 17:18:12.899 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:13.070 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:13.070 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:13.071 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.080 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.083 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.089 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.092 AbstractCamera TRACE: autoSettleAndCapture t=21 auto settle score: 100.0 2021-11-15 17:18:13.092 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.110 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.113 AbstractCamera TRACE: autoSettleAndCapture t=42 auto settle score: 100.0 2021-11-15 17:18:13.113 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.158 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.161 AbstractCamera TRACE: autoSettleAndCapture t=90 auto settle score: 100.0 2021-11-15 17:18:13.161 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.190 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.193 AbstractCamera TRACE: autoSettleAndCapture t=122 auto settle score: 100.0 2021-11-15 17:18:13.194 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.239 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.242 AbstractCamera TRACE: autoSettleAndCapture t=171 auto settle score: 100.0 2021-11-15 17:18:13.242 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.271 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.274 AbstractCamera TRACE: autoSettleAndCapture t=203 auto settle score: 100.0 2021-11-15 17:18:13.274 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.318 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.321 AbstractCamera TRACE: autoSettleAndCapture t=250 auto settle score: 100.0 2021-11-15 17:18:13.321 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.350 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.353 AbstractCamera TRACE: autoSettleAndCapture t=282 auto settle score: 100.0 2021-11-15 17:18:13.353 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.398 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.403 AbstractCamera TRACE: autoSettleAndCapture t=332 auto settle score: 100.0 2021-11-15 17:18:13.403 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.431 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.434 AbstractCamera TRACE: autoSettleAndCapture t=363 auto settle score: 100.0 2021-11-15 17:18:13.435 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.478 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.481 AbstractCamera TRACE: autoSettleAndCapture t=410 auto settle score: 100.0 2021-11-15 17:18:13.481 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.510 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.513 AbstractCamera TRACE: autoSettleAndCapture t=442 auto settle score: 100.0 2021-11-15 17:18:13.513 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.558 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.561 AbstractCamera TRACE: autoSettleAndCapture t=490 auto settle score: 100.0 2021-11-15 17:18:13.561 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.591 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.594 AbstractCamera TRACE: autoSettleAndCapture t=523 auto settle score: 100.0 2021-11-15 17:18:13.595 AbstractCamera DEBUG: autoSettleAndCapture in 524 ms 2021-11-15 17:18:13.595 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:18:13.648 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (95.107971, 41.017513, 0.000000, 0.000000 mm) 2021-11-15 17:18:13.648 AbstractHeadMountable DEBUG: DOWN.moveTo((95.107971, 41.017513, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:13.649 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:18:13.650 GcodeDriver DEBUG: [serial://COM3] >> M204 S1208.19 G1 X94.9604 Y40.9768 F3434.01 ; move to target, 25000 2021-11-15 17:18:13.651 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:13.651 GcodeDriver TRACE: [serial://COM3] confirmed M204S1208.19G1X94.9604Y40.9768F3434.01 2021-11-15 17:18:13.652 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X95.1867 Y41.0568 F293.11 ; move to target, 25000 2021-11-15 17:18:13.653 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:13.653 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X95.1867Y41.0568F293.11 2021-11-15 17:18:13.654 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:13.821 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:13.822 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:13.822 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.830 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.833 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.841 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.844 AbstractCamera TRACE: autoSettleAndCapture t=22 auto settle score: 100.0 2021-11-15 17:18:13.844 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.879 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.882 AbstractCamera TRACE: autoSettleAndCapture t=60 auto settle score: 100.0 2021-11-15 17:18:13.882 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.911 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.914 AbstractCamera TRACE: autoSettleAndCapture t=92 auto settle score: 100.0 2021-11-15 17:18:13.914 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.959 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.962 AbstractCamera TRACE: autoSettleAndCapture t=140 auto settle score: 100.0 2021-11-15 17:18:13.962 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:13.991 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:13.994 AbstractCamera TRACE: autoSettleAndCapture t=172 auto settle score: 100.0 2021-11-15 17:18:13.994 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:14.040 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:14.043 AbstractCamera TRACE: autoSettleAndCapture t=221 auto settle score: 100.0 2021-11-15 17:18:14.043 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:14.072 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:14.075 AbstractCamera TRACE: autoSettleAndCapture t=253 auto settle score: 100.0 2021-11-15 17:18:14.075 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:14.103 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:14.106 AbstractCamera TRACE: autoSettleAndCapture t=284 auto settle score: 100.0 2021-11-15 17:18:14.106 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:14.151 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:14.154 AbstractCamera TRACE: autoSettleAndCapture t=332 auto settle score: 100.0 2021-11-15 17:18:14.154 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:14.183 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:14.186 AbstractCamera TRACE: autoSettleAndCapture t=364 auto settle score: 100.0 2021-11-15 17:18:14.186 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:14.231 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:14.234 AbstractCamera TRACE: autoSettleAndCapture t=412 auto settle score: 100.0 2021-11-15 17:18:14.234 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:14.263 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:14.266 AbstractCamera TRACE: autoSettleAndCapture t=444 auto settle score: 100.0 2021-11-15 17:18:14.266 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:14.311 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:14.314 AbstractCamera TRACE: autoSettleAndCapture t=492 auto settle score: 100.0 2021-11-15 17:18:14.314 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:14.343 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:14.346 AbstractCamera TRACE: autoSettleAndCapture t=524 auto settle score: 100.0 2021-11-15 17:18:14.346 AbstractCamera DEBUG: autoSettleAndCapture in 524 ms 2021-11-15 17:18:14.346 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:18:14.395 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.393073, 40.231154, 0.000000, 0.000000 mm) 2021-11-15 17:18:14.396 AbstractHeadMountable DEBUG: DOWN.moveTo((92.393073, 40.231154, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:14.397 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (93.750522, 40.624334, 0.000000, 0.000000 mm) 2021-11-15 17:18:14.397 AbstractHeadMountable DEBUG: DOWN.moveTo((93.750522, 40.624334, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:14.398 GcodeDriver DEBUG: [serial://COM3] >> M204 S1209.61 G1 X92.6193 Y40.3112 F3411.98 ; move to target, 25000 2021-11-15 17:18:14.399 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:14.399 GcodeDriver TRACE: [serial://COM3] confirmed M204S1209.61G1X92.6193Y40.3112F3411.98 2021-11-15 17:18:14.400 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.3931 Y40.2312 F293.11 ; move to target, 25000 2021-11-15 17:18:14.401 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:14.401 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.3931Y40.2312F293.11 2021-11-15 17:18:14.401 GcodeDriver DEBUG: [serial://COM3] >> M204 S1209.90 G1 X93.6029 Y40.5837 F2342.83 ; move to target, 25000 2021-11-15 17:18:14.403 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:14.403 GcodeDriver TRACE: [serial://COM3] confirmed M204S1209.9G1X93.6029Y40.5837F2342.83 2021-11-15 17:18:14.403 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.8292 Y40.6637 F293.11 ; move to target, 25000 2021-11-15 17:18:14.405 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:14.405 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.8292Y40.6637F293.11 2021-11-15 17:18:16.954 ReferenceMachine DEBUG: homing machine 2021-11-15 17:18:16.955 GcodeDriver DEBUG: [serial://COM3] >> M204 S1000 ; set a low acceleration, -1 2021-11-15 17:18:16.955 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:16.955 GcodeDriver TRACE: [serial://COM3] confirmed M204S1000 2021-11-15 17:18:16.956 GcodeDriver DEBUG: [serial://COM3] >> G28 A0 Z0, -1 2021-11-15 17:18:17.938 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:17.938 GcodeDriver TRACE: [serial://COM3] confirmed G28A0Z0 2021-11-15 17:18:17.938 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:18:17.939 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:17.939 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:17.939 GcodeDriver DEBUG: [serial://COM3] >> G28 X0 Y0 B0 C0; Home all axes, -1 2021-11-15 17:18:20.056 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:20.056 GcodeDriver TRACE: [serial://COM3] confirmed G28X0Y0B0C0 2021-11-15 17:18:20.056 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:18:20.057 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:20.057 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:20.057 GcodeDriver DEBUG: [serial://COM3] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero., -1 2021-11-15 17:18:20.058 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:20.058 GcodeDriver TRACE: [serial://COM3] confirmed G92X0Y0Z0A0B0C0 2021-11-15 17:18:20.058 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:18:20.059 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:20.059 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:20.059 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM4 IO Error on reading from the controller. at org.openpnp.machine.reference.driver.GcodeDriver.bailOnError(GcodeDriver.java:1095) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.bailOnError(GcodeAsyncDriver.java:293) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.sendCommand(GcodeAsyncDriver.java:310) at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:1033) at org.openpnp.machine.reference.driver.GcodeDriver.home(GcodeDriver.java:495) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.home(GcodeAsyncDriver.java:119) at org.openpnp.machine.reference.driver.AbstractMotionPlanner.home(AbstractMotionPlanner.java:99) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:425) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:327) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:18:20.059 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:20.060 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:20.060 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:20.060 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:93.750522, y:40.624334, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) to (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2021-11-15 17:18:20.060 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM4 IO Error on reading from the controller. 2021-11-15 17:18:24.555 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:18:24.556 ReferenceHead DEBUG: H1.moveToSafeZ(0.88) 2021-11-15 17:18:24.556 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.88) 2021-11-15 17:18:24.556 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.88) 2021-11-15 17:18:24.556 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.88) 2021-11-15 17:18:24.556 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.88) 2021-11-15 17:18:24.556 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.88) 2021-11-15 17:18:24.556 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:24.557 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:24.557 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:18:24.558 GcodeDriver DEBUG: [serial://COM3] >> M204 S1280.41 G1 X93.0324 Y43.1393 F21741.44 ; move to target, 25000 2021-11-15 17:18:24.559 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:24.559 GcodeDriver TRACE: [serial://COM3] confirmed M204S1280.41G1X93.0324Y43.1393F21741.44 2021-11-15 17:18:24.560 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.2587 Y43.2193 F293.11 ; move to target, 25000 2021-11-15 17:18:24.561 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:24.561 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.2587Y43.2193F293.11 2021-11-15 17:18:24.561 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:25.199 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:25.199 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:25.199 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.205 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.207 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.238 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.241 AbstractCamera TRACE: autoSettleAndCapture t=42 auto settle score: 100.0 2021-11-15 17:18:25.242 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.271 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.274 AbstractCamera TRACE: autoSettleAndCapture t=75 auto settle score: 100.0 2021-11-15 17:18:25.274 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.319 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.322 AbstractCamera TRACE: autoSettleAndCapture t=123 auto settle score: 100.0 2021-11-15 17:18:25.322 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.355 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.358 AbstractCamera TRACE: autoSettleAndCapture t=159 auto settle score: 100.0 2021-11-15 17:18:25.358 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.398 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.400 AbstractCamera TRACE: autoSettleAndCapture t=201 auto settle score: 100.0 2021-11-15 17:18:25.400 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.430 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.433 AbstractCamera TRACE: autoSettleAndCapture t=234 auto settle score: 100.0 2021-11-15 17:18:25.433 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.478 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.481 AbstractCamera TRACE: autoSettleAndCapture t=282 auto settle score: 100.0 2021-11-15 17:18:25.481 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.510 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.514 AbstractCamera TRACE: autoSettleAndCapture t=314 auto settle score: 100.0 2021-11-15 17:18:25.514 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.558 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.561 AbstractCamera TRACE: autoSettleAndCapture t=362 auto settle score: 100.0 2021-11-15 17:18:25.561 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.590 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.594 AbstractCamera TRACE: autoSettleAndCapture t=394 auto settle score: 100.0 2021-11-15 17:18:25.594 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.638 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.641 AbstractCamera TRACE: autoSettleAndCapture t=442 auto settle score: 100.0 2021-11-15 17:18:25.641 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.670 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.673 AbstractCamera TRACE: autoSettleAndCapture t=474 auto settle score: 100.0 2021-11-15 17:18:25.673 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.719 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.723 AbstractCamera TRACE: autoSettleAndCapture t=524 auto settle score: 100.0 2021-11-15 17:18:25.723 AbstractCamera DEBUG: autoSettleAndCapture in 524 ms 2021-11-15 17:18:25.723 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:18:25.774 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (95.462088, 45.892938, 0.000000, 0.000000 mm) 2021-11-15 17:18:25.774 AbstractHeadMountable DEBUG: DOWN.moveTo((95.462088, 45.892938, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:25.775 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:18:25.776 GcodeDriver DEBUG: [serial://COM3] >> M204 S1473.75 G1 X95.3145 Y45.8523 F4209.85 ; move to target, 25000 2021-11-15 17:18:25.778 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:25.778 GcodeDriver TRACE: [serial://COM3] confirmed M204S1473.75G1X95.3145Y45.8523F4209.85 2021-11-15 17:18:25.778 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X95.5408 Y45.9323 F293.11 ; move to target, 25000 2021-11-15 17:18:25.779 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:25.780 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X95.5408Y45.9323F293.11 2021-11-15 17:18:25.780 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:25.946 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:25.946 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:25.947 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.954 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.957 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.964 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.967 AbstractCamera TRACE: autoSettleAndCapture t=20 auto settle score: 100.0 2021-11-15 17:18:25.968 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:25.991 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:25.994 AbstractCamera TRACE: autoSettleAndCapture t=47 auto settle score: 100.0 2021-11-15 17:18:25.994 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.039 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.042 AbstractCamera TRACE: autoSettleAndCapture t=95 auto settle score: 100.0 2021-11-15 17:18:26.042 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.070 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.073 AbstractCamera TRACE: autoSettleAndCapture t=126 auto settle score: 100.0 2021-11-15 17:18:26.073 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.119 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.121 AbstractCamera TRACE: autoSettleAndCapture t=174 auto settle score: 100.0 2021-11-15 17:18:26.122 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.150 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.153 AbstractCamera TRACE: autoSettleAndCapture t=206 auto settle score: 100.0 2021-11-15 17:18:26.153 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.199 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.201 AbstractCamera TRACE: autoSettleAndCapture t=254 auto settle score: 100.0 2021-11-15 17:18:26.202 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.235 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.238 AbstractCamera TRACE: autoSettleAndCapture t=291 auto settle score: 100.0 2021-11-15 17:18:26.238 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.279 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.282 AbstractCamera TRACE: autoSettleAndCapture t=335 auto settle score: 100.0 2021-11-15 17:18:26.282 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.311 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.314 AbstractCamera TRACE: autoSettleAndCapture t=367 auto settle score: 100.0 2021-11-15 17:18:26.314 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.359 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.362 AbstractCamera TRACE: autoSettleAndCapture t=415 auto settle score: 100.0 2021-11-15 17:18:26.362 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.390 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.393 AbstractCamera TRACE: autoSettleAndCapture t=446 auto settle score: 100.0 2021-11-15 17:18:26.393 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.439 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.441 AbstractCamera TRACE: autoSettleAndCapture t=494 auto settle score: 100.0 2021-11-15 17:18:26.442 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.470 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.473 AbstractCamera TRACE: autoSettleAndCapture t=526 auto settle score: 100.0 2021-11-15 17:18:26.473 AbstractCamera DEBUG: autoSettleAndCapture in 526 ms 2021-11-15 17:18:26.474 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:18:26.523 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (93.573463, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:18:26.523 AbstractHeadMountable DEBUG: DOWN.moveTo((93.573463, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:26.525 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:18:26.526 GcodeDriver DEBUG: [serial://COM3] >> M204 S1386.39 G1 X93.7997 Y43.2600 F3989.77 ; move to target, 25000 2021-11-15 17:18:26.527 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:26.527 GcodeDriver TRACE: [serial://COM3] confirmed M204S1386.39G1X93.7997Y43.26F3989.77 2021-11-15 17:18:26.527 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.5735 Y43.1800 F293.11 ; move to target, 25000 2021-11-15 17:18:26.528 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:26.528 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.5735Y43.18F293.11 2021-11-15 17:18:26.528 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:26.695 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:26.695 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:26.695 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.707 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.709 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.715 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.718 AbstractCamera TRACE: autoSettleAndCapture t=23 auto settle score: 100.0 2021-11-15 17:18:26.718 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.759 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.763 AbstractCamera TRACE: autoSettleAndCapture t=68 auto settle score: 100.0 2021-11-15 17:18:26.763 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.795 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.798 AbstractCamera TRACE: autoSettleAndCapture t=103 auto settle score: 100.0 2021-11-15 17:18:26.798 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.838 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.841 AbstractCamera TRACE: autoSettleAndCapture t=146 auto settle score: 100.0 2021-11-15 17:18:26.841 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.872 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.875 AbstractCamera TRACE: autoSettleAndCapture t=180 auto settle score: 100.0 2021-11-15 17:18:26.875 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.919 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.923 AbstractCamera TRACE: autoSettleAndCapture t=228 auto settle score: 100.0 2021-11-15 17:18:26.924 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.951 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:26.954 AbstractCamera TRACE: autoSettleAndCapture t=259 auto settle score: 100.0 2021-11-15 17:18:26.954 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:26.999 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:27.003 AbstractCamera TRACE: autoSettleAndCapture t=308 auto settle score: 100.0 2021-11-15 17:18:27.003 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:27.030 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:27.033 AbstractCamera TRACE: autoSettleAndCapture t=338 auto settle score: 100.0 2021-11-15 17:18:27.033 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:27.078 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:27.081 AbstractCamera TRACE: autoSettleAndCapture t=386 auto settle score: 100.0 2021-11-15 17:18:27.081 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:27.110 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:27.113 AbstractCamera TRACE: autoSettleAndCapture t=418 auto settle score: 100.0 2021-11-15 17:18:27.114 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:27.164 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:27.166 AbstractCamera TRACE: autoSettleAndCapture t=471 auto settle score: 100.0 2021-11-15 17:18:27.166 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:27.191 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:27.195 AbstractCamera TRACE: autoSettleAndCapture t=500 auto settle score: 100.0 2021-11-15 17:18:27.195 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:18:27.239 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:18:27.242 AbstractCamera TRACE: autoSettleAndCapture t=547 auto settle score: 100.0 2021-11-15 17:18:27.242 AbstractCamera DEBUG: autoSettleAndCapture in 547 ms 2021-11-15 17:18:27.242 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:18:27.294 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (93.612810, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:18:27.294 AbstractHeadMountable DEBUG: DOWN.moveTo((93.612810, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:27.296 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (93.593137, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:18:27.296 AbstractHeadMountable DEBUG: DOWN.moveTo((93.593137, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:18:27.297 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.60 G1 X93.4652 F672.77 ; move to target, 25000 2021-11-15 17:18:27.298 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:27.298 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.6G1X93.4652F672.77 2021-11-15 17:18:27.298 GcodeDriver DEBUG: [serial://COM3] >> M204 S93.75 G1 X93.6915 F276.35 ; move to target, 25000 2021-11-15 17:18:27.300 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:27.300 GcodeDriver TRACE: [serial://COM3] confirmed M204S93.75G1X93.6915F276.35 2021-11-15 17:18:27.300 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.60 G1 X93.8194 F731.36 ; move to target, 25000 2021-11-15 17:18:27.302 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:27.302 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.6G1X93.8194F731.36 2021-11-15 17:18:27.302 GcodeDriver DEBUG: [serial://COM3] >> M204 S93.75 G1 X93.5931 F276.35 ; move to target, 25000 2021-11-15 17:18:27.303 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:27.303 GcodeDriver TRACE: [serial://COM3] confirmed M204S93.75G1X93.5931F276.35 2021-11-15 17:18:53.024 ReferenceMachine DEBUG: homing machine 2021-11-15 17:18:53.025 GcodeDriver DEBUG: [serial://COM3] >> M204 S1000 ; set a low acceleration, -1 2021-11-15 17:18:53.026 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:53.026 GcodeDriver TRACE: [serial://COM3] confirmed M204S1000 2021-11-15 17:18:53.026 GcodeDriver DEBUG: [serial://COM3] >> G28 A0 Z0, -1 2021-11-15 17:18:54.012 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:54.012 GcodeDriver TRACE: [serial://COM3] confirmed G28A0Z0 2021-11-15 17:18:54.012 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:18:54.013 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:54.013 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:54.013 GcodeDriver DEBUG: [serial://COM3] >> G28 X0 Y0 B0 C0; Home all axes, -1 2021-11-15 17:18:56.113 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:56.113 GcodeDriver TRACE: [serial://COM3] confirmed G28X0Y0B0C0 2021-11-15 17:18:56.113 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:18:56.114 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:56.114 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:56.114 GcodeDriver DEBUG: [serial://COM3] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero., -1 2021-11-15 17:18:56.115 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:56.115 GcodeDriver TRACE: [serial://COM3] confirmed G92X0Y0Z0A0B0C0 2021-11-15 17:18:56.115 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:18:56.116 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:56.116 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:56.116 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM4 IO Error on reading from the controller. at org.openpnp.machine.reference.driver.GcodeDriver.bailOnError(GcodeDriver.java:1095) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.bailOnError(GcodeAsyncDriver.java:293) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.sendCommand(GcodeAsyncDriver.java:310) at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:1033) at org.openpnp.machine.reference.driver.GcodeDriver.home(GcodeDriver.java:495) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.home(GcodeAsyncDriver.java:119) at org.openpnp.machine.reference.driver.AbstractMotionPlanner.home(AbstractMotionPlanner.java:99) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:425) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:327) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:18:56.117 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:18:56.117 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:18:56.117 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:18:56.117 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:93.593137, y:43.180000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) to (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2021-11-15 17:18:56.118 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM4 IO Error on reading from the controller. 2021-11-15 17:22:17.929 ReferenceHead DEBUG: H1.moveToSafeZ(0.88) 2021-11-15 17:22:17.930 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.88) 2021-11-15 17:22:17.930 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.88) 2021-11-15 17:22:17.930 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.88) 2021-11-15 17:22:17.930 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.88) 2021-11-15 17:22:17.930 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.88) 2021-11-15 17:22:17.930 AbstractHeadMountable DEBUG: DOWN.moveTo((260.691000, 50.301000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:22:17.931 AbstractHeadMountable DEBUG: DOWN.moveTo((260.691000, 50.301000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:22:17.931 Scripting TRACE: Scripting.on Camera.AfterPosition 2021-11-15 17:22:17.932 GcodeDriver DEBUG: [serial://COM3] >> M204 S1183.02 G1 X260.5434 Y50.2603 F33616.58 ; move to target, 25000 2021-11-15 17:22:17.933 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:17.933 GcodeDriver TRACE: [serial://COM3] confirmed M204S1183.02G1X260.5434Y50.2603F33616.58 2021-11-15 17:22:17.933 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X260.7697 Y50.3403 F293.11 ; move to target, 25000 2021-11-15 17:22:17.935 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:17.935 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X260.7697Y50.3403F293.11 2021-11-15 17:22:37.736 ReferenceMachine DEBUG: homing machine 2021-11-15 17:22:37.737 GcodeDriver DEBUG: [serial://COM3] >> M204 S1000 ; set a low acceleration, -1 2021-11-15 17:22:37.738 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:37.739 GcodeDriver TRACE: [serial://COM3] confirmed M204S1000 2021-11-15 17:22:37.739 GcodeDriver DEBUG: [serial://COM3] >> G28 A0 Z0, -1 2021-11-15 17:22:38.721 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:38.721 GcodeDriver TRACE: [serial://COM3] confirmed G28A0Z0 2021-11-15 17:22:38.721 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:22:38.722 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:38.722 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:22:38.722 GcodeDriver DEBUG: [serial://COM3] >> G28 X0 Y0 B0 C0; Home all axes, -1 2021-11-15 17:22:42.905 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:42.905 GcodeDriver TRACE: [serial://COM3] confirmed G28X0Y0B0C0 2021-11-15 17:22:42.905 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:22:42.906 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:42.906 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:22:42.906 GcodeDriver DEBUG: [serial://COM3] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero., -1 2021-11-15 17:22:42.907 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:42.907 GcodeDriver TRACE: [serial://COM3] confirmed G92X0Y0Z0A0B0C0 2021-11-15 17:22:42.907 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:22:42.908 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:42.908 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:22:42.909 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM4 IO Error on reading from the controller. at org.openpnp.machine.reference.driver.GcodeDriver.bailOnError(GcodeDriver.java:1095) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.bailOnError(GcodeAsyncDriver.java:293) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.sendCommand(GcodeAsyncDriver.java:310) at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:1033) at org.openpnp.machine.reference.driver.GcodeDriver.home(GcodeDriver.java:495) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.home(GcodeAsyncDriver.java:119) at org.openpnp.machine.reference.driver.AbstractMotionPlanner.home(AbstractMotionPlanner.java:99) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:425) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:327) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:22:42.909 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:22:42.910 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:22:42.910 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:22:42.910 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:260.691000, y:50.301000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) to (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2021-11-15 17:22:42.911 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM4 IO Error on reading from the controller. 2021-11-15 17:23:02.749 ReferenceMachine DEBUG: homing machine 2021-11-15 17:23:02.750 GcodeDriver DEBUG: [serial://COM3] >> M204 S1000 ; set a low acceleration, -1 2021-11-15 17:23:02.751 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:23:02.751 GcodeDriver TRACE: [serial://COM3] confirmed M204S1000 2021-11-15 17:23:02.751 GcodeDriver DEBUG: [serial://COM3] >> G28 A0 Z0, -1 2021-11-15 17:23:03.734 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:23:03.735 GcodeDriver TRACE: [serial://COM3] confirmed G28A0Z0 2021-11-15 17:23:03.735 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:23:03.736 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:23:03.736 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:23:03.736 GcodeDriver DEBUG: [serial://COM3] >> G28 X0 Y0 B0 C0; Home all axes, -1 2021-11-15 17:23:04.928 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:23:04.928 GcodeDriver TRACE: [serial://COM3] confirmed G28X0Y0B0C0 2021-11-15 17:23:04.928 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:23:04.928 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:23:04.929 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:23:04.929 GcodeDriver DEBUG: [serial://COM3] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero., -1 2021-11-15 17:23:04.929 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:23:04.929 GcodeDriver TRACE: [serial://COM3] confirmed G92X0Y0Z0A0B0C0 2021-11-15 17:23:04.930 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:23:04.930 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:23:04.930 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:23:04.930 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM4 IO Error on reading from the controller. at org.openpnp.machine.reference.driver.GcodeDriver.bailOnError(GcodeDriver.java:1095) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.bailOnError(GcodeAsyncDriver.java:293) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.sendCommand(GcodeAsyncDriver.java:310) at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:1033) at org.openpnp.machine.reference.driver.GcodeDriver.home(GcodeDriver.java:495) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.home(GcodeAsyncDriver.java:119) at org.openpnp.machine.reference.driver.AbstractMotionPlanner.home(AbstractMotionPlanner.java:99) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:425) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:327) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:23:04.931 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM4 IO Error on reading from the controller. 2021-11-15 17:27:48.827 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.180000, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:27:48.827 ReferenceHead DEBUG: H1.moveToSafeZ(0.88) 2021-11-15 17:27:48.827 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.88) 2021-11-15 17:27:48.827 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.88) 2021-11-15 17:27:48.828 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.88) 2021-11-15 17:27:48.828 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.88) 2021-11-15 17:27:48.828 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.88) 2021-11-15 17:27:48.828 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:27:48.828 AbstractHeadMountable DEBUG: DOWN.moveTo((93.180000, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:27:48.828 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:27:48.829 GcodeDriver DEBUG: [serial://COM3] >> M204 S1280.41 G1 X93.0324 Y43.1393 F21741.44 ; move to target, 25000 2021-11-15 17:27:48.831 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:48.831 GcodeDriver TRACE: [serial://COM3] confirmed M204S1280.41G1X93.0324Y43.1393F21741.44 2021-11-15 17:27:48.831 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.2587 Y43.2193 F293.11 ; move to target, 25000 2021-11-15 17:27:48.832 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:48.833 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.2587Y43.2193F293.11 2021-11-15 17:27:48.833 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:27:49.470 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:49.471 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:27:49.471 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.477 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.480 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.514 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.517 AbstractCamera TRACE: autoSettleAndCapture t=46 auto settle score: 100.0 2021-11-15 17:27:49.517 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.542 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.546 AbstractCamera TRACE: autoSettleAndCapture t=74 auto settle score: 100.0 2021-11-15 17:27:49.546 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.590 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.593 AbstractCamera TRACE: autoSettleAndCapture t=122 auto settle score: 100.0 2021-11-15 17:27:49.593 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.624 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.627 AbstractCamera TRACE: autoSettleAndCapture t=156 auto settle score: 100.0 2021-11-15 17:27:49.627 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.675 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.677 AbstractCamera TRACE: autoSettleAndCapture t=206 auto settle score: 100.0 2021-11-15 17:27:49.677 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.703 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.706 AbstractCamera TRACE: autoSettleAndCapture t=235 auto settle score: 100.0 2021-11-15 17:27:49.706 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.752 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.755 AbstractCamera TRACE: autoSettleAndCapture t=284 auto settle score: 100.0 2021-11-15 17:27:49.755 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.783 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.786 AbstractCamera TRACE: autoSettleAndCapture t=315 auto settle score: 100.0 2021-11-15 17:27:49.787 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.832 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.835 AbstractCamera TRACE: autoSettleAndCapture t=364 auto settle score: 100.0 2021-11-15 17:27:49.835 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.863 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.866 AbstractCamera TRACE: autoSettleAndCapture t=395 auto settle score: 100.0 2021-11-15 17:27:49.866 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.911 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.914 AbstractCamera TRACE: autoSettleAndCapture t=443 auto settle score: 100.0 2021-11-15 17:27:49.914 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.943 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.946 AbstractCamera TRACE: autoSettleAndCapture t=475 auto settle score: 100.0 2021-11-15 17:27:49.946 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:49.992 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:49.995 AbstractCamera TRACE: autoSettleAndCapture t=524 auto settle score: 100.0 2021-11-15 17:27:49.995 AbstractCamera DEBUG: autoSettleAndCapture in 524 ms 2021-11-15 17:27:49.995 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:27:50.048 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.550459, 43.219318, 0.000000, 0.000000 mm) 2021-11-15 17:27:50.048 AbstractHeadMountable DEBUG: DOWN.moveTo((92.550459, 43.219318, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:27:50.049 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:27:50.050 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.60 G1 X92.7767 F1419.66 ; move to target, 25000 2021-11-15 17:27:50.051 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:50.051 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.6G1X92.7767F1419.66 2021-11-15 17:27:50.051 GcodeDriver DEBUG: [serial://COM3] >> M204 S95.15 G1 X92.5505 Y43.2586 F280.49 ; move to target, 25000 2021-11-15 17:27:50.052 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:50.052 GcodeDriver TRACE: [serial://COM3] confirmed M204S95.15G1X92.5505Y43.2586F280.49 2021-11-15 17:27:50.053 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:27:50.164 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:50.165 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:27:50.165 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.173 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.175 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.184 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.187 AbstractCamera TRACE: autoSettleAndCapture t=22 auto settle score: 100.0 2021-11-15 17:27:50.187 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.234 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.237 AbstractCamera TRACE: autoSettleAndCapture t=72 auto settle score: 100.0 2021-11-15 17:27:50.237 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.263 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.266 AbstractCamera TRACE: autoSettleAndCapture t=101 auto settle score: 100.0 2021-11-15 17:27:50.266 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.311 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.314 AbstractCamera TRACE: autoSettleAndCapture t=149 auto settle score: 100.0 2021-11-15 17:27:50.314 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.346 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.350 AbstractCamera TRACE: autoSettleAndCapture t=185 auto settle score: 100.0 2021-11-15 17:27:50.350 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.391 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.394 AbstractCamera TRACE: autoSettleAndCapture t=229 auto settle score: 100.0 2021-11-15 17:27:50.394 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.423 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.426 AbstractCamera TRACE: autoSettleAndCapture t=261 auto settle score: 100.0 2021-11-15 17:27:50.426 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.474 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.476 AbstractCamera TRACE: autoSettleAndCapture t=311 auto settle score: 100.0 2021-11-15 17:27:50.477 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.502 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.505 AbstractCamera TRACE: autoSettleAndCapture t=340 auto settle score: 100.0 2021-11-15 17:27:50.505 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.552 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.556 AbstractCamera TRACE: autoSettleAndCapture t=391 auto settle score: 100.0 2021-11-15 17:27:50.556 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.582 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.586 AbstractCamera TRACE: autoSettleAndCapture t=421 auto settle score: 100.0 2021-11-15 17:27:50.586 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.630 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.633 AbstractCamera TRACE: autoSettleAndCapture t=468 auto settle score: 100.0 2021-11-15 17:27:50.633 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.662 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.665 AbstractCamera TRACE: autoSettleAndCapture t=500 auto settle score: 100.0 2021-11-15 17:27:50.665 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.711 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.714 AbstractCamera TRACE: autoSettleAndCapture t=549 auto settle score: 100.0 2021-11-15 17:27:50.714 AbstractCamera DEBUG: autoSettleAndCapture in 549 ms 2021-11-15 17:27:50.714 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:27:50.766 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.668498, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:27:50.766 AbstractHeadMountable DEBUG: DOWN.moveTo((92.668498, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:27:50.768 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-11-15 17:27:50.768 GcodeDriver DEBUG: [serial://COM3] >> M204 S1162.84 G1 X92.5209 Y43.2600 F351.86 ; move to target, 25000 2021-11-15 17:27:50.769 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:50.770 GcodeDriver TRACE: [serial://COM3] confirmed M204S1162.84G1X92.5209Y43.26F351.86 2021-11-15 17:27:50.770 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.7472 Y43.1800 F293.11 ; move to target, 25000 2021-11-15 17:27:50.771 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:50.771 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.7472Y43.18F293.11 2021-11-15 17:27:50.771 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:27:50.871 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:50.871 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:27:50.871 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.878 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.880 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.903 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.906 AbstractCamera TRACE: autoSettleAndCapture t=35 auto settle score: 100.0 2021-11-15 17:27:50.906 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.951 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.953 AbstractCamera TRACE: autoSettleAndCapture t=82 auto settle score: 100.0 2021-11-15 17:27:50.953 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:50.986 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:50.989 AbstractCamera TRACE: autoSettleAndCapture t=118 auto settle score: 100.0 2021-11-15 17:27:50.989 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.031 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.034 AbstractCamera TRACE: autoSettleAndCapture t=163 auto settle score: 100.0 2021-11-15 17:27:51.034 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.062 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.065 AbstractCamera TRACE: autoSettleAndCapture t=194 auto settle score: 100.0 2021-11-15 17:27:51.066 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.111 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.114 AbstractCamera TRACE: autoSettleAndCapture t=243 auto settle score: 100.0 2021-11-15 17:27:51.115 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.143 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.146 AbstractCamera TRACE: autoSettleAndCapture t=275 auto settle score: 100.0 2021-11-15 17:27:51.146 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.174 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.177 AbstractCamera TRACE: autoSettleAndCapture t=306 auto settle score: 100.0 2021-11-15 17:27:51.177 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.222 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.225 AbstractCamera TRACE: autoSettleAndCapture t=354 auto settle score: 100.0 2021-11-15 17:27:51.225 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.254 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.257 AbstractCamera TRACE: autoSettleAndCapture t=386 auto settle score: 100.0 2021-11-15 17:27:51.257 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.305 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.307 AbstractCamera TRACE: autoSettleAndCapture t=436 auto settle score: 100.0 2021-11-15 17:27:51.308 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.334 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.337 AbstractCamera TRACE: autoSettleAndCapture t=466 auto settle score: 100.0 2021-11-15 17:27:51.337 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-11-15 17:27:51.383 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-11-15 17:27:51.386 AbstractCamera TRACE: autoSettleAndCapture t=515 auto settle score: 100.0 2021-11-15 17:27:51.386 AbstractCamera DEBUG: autoSettleAndCapture in 515 ms 2021-11-15 17:27:51.386 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-11-15 17:27:51.437 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.511112, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:27:51.437 AbstractHeadMountable DEBUG: DOWN.moveTo((92.511112, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:27:51.438 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (92.589805, 43.180000, 0.000000, 0.000000 mm) 2021-11-15 17:27:51.438 AbstractHeadMountable DEBUG: DOWN.moveTo((92.589805, 43.180000, 0.000000, 0.000000 mm), 0.88) 2021-11-15 17:27:51.439 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.60 G1 X92.7374 F202.48 ; move to target, 25000 2021-11-15 17:27:51.440 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:51.440 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.6G1X92.7374F202.48 2021-11-15 17:27:51.440 GcodeDriver DEBUG: [serial://COM3] >> M204 S93.75 G1 X92.5111 F276.35 ; move to target, 25000 2021-11-15 17:27:51.441 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:51.442 GcodeDriver TRACE: [serial://COM3] confirmed M204S93.75G1X92.5111F276.35 2021-11-15 17:27:51.442 GcodeDriver DEBUG: [serial://COM3] >> M204 S1161.60 G1 X92.4422 F536.73 ; move to target, 25000 2021-11-15 17:27:51.444 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:51.444 GcodeDriver TRACE: [serial://COM3] confirmed M204S1161.6G1X92.4422F536.73 2021-11-15 17:27:51.444 GcodeDriver DEBUG: [serial://COM3] >> M204 S93.75 G1 X92.6685 F276.35 ; move to target, 25000 2021-11-15 17:27:51.445 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:27:51.445 GcodeDriver TRACE: [serial://COM3] confirmed M204S93.75G1X92.6685F276.35 2021-11-15 17:30:40.514 ReferenceMachine DEBUG: homing machine 2021-11-15 17:30:40.514 GcodeDriver DEBUG: [serial://COM3] >> M204 S1000 ; set a low acceleration, -1 2021-11-15 17:30:40.516 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:30:40.516 GcodeDriver TRACE: [serial://COM3] confirmed M204S1000 2021-11-15 17:30:40.516 GcodeDriver DEBUG: [serial://COM3] >> G28 A0 Z0, -1 2021-11-15 17:30:41.500 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:30:41.501 GcodeDriver TRACE: [serial://COM3] confirmed G28A0Z0 2021-11-15 17:30:41.501 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:30:41.502 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:30:41.502 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:30:41.502 GcodeDriver DEBUG: [serial://COM3] >> G28 X0 Y0 B0 C0; Home all axes, -1 2021-11-15 17:30:43.604 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:30:43.604 GcodeDriver TRACE: [serial://COM3] confirmed G28X0Y0B0C0 2021-11-15 17:30:43.604 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:30:43.605 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:30:43.605 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:30:43.605 GcodeDriver DEBUG: [serial://COM3] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero., -1 2021-11-15 17:30:43.606 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:30:43.606 GcodeDriver TRACE: [serial://COM3] confirmed G92X0Y0Z0A0B0C0 2021-11-15 17:30:43.606 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:30:43.607 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:30:43.607 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:30:43.607 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM4 IO Error on reading from the controller. at org.openpnp.machine.reference.driver.GcodeDriver.bailOnError(GcodeDriver.java:1095) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.bailOnError(GcodeAsyncDriver.java:293) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.sendCommand(GcodeAsyncDriver.java:310) at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:1033) at org.openpnp.machine.reference.driver.GcodeDriver.home(GcodeDriver.java:495) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.home(GcodeAsyncDriver.java:119) at org.openpnp.machine.reference.driver.AbstractMotionPlanner.home(AbstractMotionPlanner.java:99) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:425) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:327) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:30:43.607 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 28409 2021-11-15 17:30:43.608 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:30:43.608 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:30:43.608 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:92.589805, y:43.180000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) to (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2021-11-15 17:30:43.609 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM4 IO Error on reading from the controller. 2021-11-15 17:31:27.709 ReferenceMachine DEBUG: homing machine 2021-11-15 17:31:27.710 GcodeDriver DEBUG: [serial://COM3] >> M204 S1000 ; set a low acceleration, -1 2021-11-15 17:31:27.710 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:31:27.711 GcodeDriver TRACE: [serial://COM3] confirmed M204S1000 2021-11-15 17:31:27.711 GcodeDriver DEBUG: [serial://COM3] >> G28 A0 Z0, -1 2021-11-15 17:31:28.697 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:31:28.697 GcodeDriver TRACE: [serial://COM3] confirmed G28A0Z0 2021-11-15 17:31:28.698 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:31:28.699 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:31:28.699 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:31:28.699 GcodeDriver DEBUG: [serial://COM3] >> G28 X0 Y0 B0 C0; Home all axes, -1 2021-11-15 17:31:29.890 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:31:29.890 GcodeDriver TRACE: [serial://COM3] confirmed G28X0Y0B0C0 2021-11-15 17:31:29.890 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:31:29.891 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:31:29.891 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:31:29.891 GcodeDriver DEBUG: [serial://COM3] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero., -1 2021-11-15 17:31:29.892 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:31:29.892 GcodeDriver TRACE: [serial://COM3] confirmed G92X0Y0Z0A0B0C0 2021-11-15 17:31:29.892 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:31:29.894 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:31:29.894 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:31:29.894 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM4 IO Error on reading from the controller. at org.openpnp.machine.reference.driver.GcodeDriver.bailOnError(GcodeDriver.java:1095) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.bailOnError(GcodeAsyncDriver.java:293) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.sendCommand(GcodeAsyncDriver.java:310) at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:1033) at org.openpnp.machine.reference.driver.GcodeDriver.home(GcodeDriver.java:495) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.home(GcodeAsyncDriver.java:119) at org.openpnp.machine.reference.driver.AbstractMotionPlanner.home(AbstractMotionPlanner.java:99) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:425) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:327) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:31:29.895 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM4 IO Error on reading from the controller. 2021-11-15 17:42:45.829 ReferenceMachine DEBUG: homing machine 2021-11-15 17:42:45.830 GcodeDriver DEBUG: [serial://COM3] >> M204 S1000 ; set a low acceleration, -1 2021-11-15 17:42:45.831 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:42:45.831 GcodeDriver TRACE: [serial://COM3] confirmed M204S1000 2021-11-15 17:42:45.831 GcodeDriver DEBUG: [serial://COM3] >> G28 A0 Z0, -1 2021-11-15 17:42:46.816 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:42:46.817 GcodeDriver TRACE: [serial://COM3] confirmed G28A0Z0 2021-11-15 17:42:46.817 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:42:46.818 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:42:46.818 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:42:46.818 GcodeDriver DEBUG: [serial://COM3] >> G28 X0 Y0 B0 C0; Home all axes, -1 2021-11-15 17:42:48.009 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:42:48.009 GcodeDriver TRACE: [serial://COM3] confirmed G28X0Y0B0C0 2021-11-15 17:42:48.009 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:42:48.011 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:42:48.011 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:42:48.011 GcodeDriver DEBUG: [serial://COM3] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero., -1 2021-11-15 17:42:48.013 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:42:48.013 GcodeDriver TRACE: [serial://COM3] confirmed G92X0Y0Z0A0B0C0 2021-11-15 17:42:48.013 GcodeDriver DEBUG: [serial://COM3] >> M400, -1 2021-11-15 17:42:48.014 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok 2021-11-15 17:42:48.014 GcodeDriver TRACE: [serial://COM3] confirmed M400 2021-11-15 17:42:48.014 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM4 IO Error on reading from the controller. at org.openpnp.machine.reference.driver.GcodeDriver.bailOnError(GcodeDriver.java:1095) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.bailOnError(GcodeAsyncDriver.java:293) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.sendCommand(GcodeAsyncDriver.java:310) at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:1033) at org.openpnp.machine.reference.driver.GcodeDriver.home(GcodeDriver.java:495) at org.openpnp.machine.reference.driver.GcodeAsyncDriver.home(GcodeAsyncDriver.java:119) at org.openpnp.machine.reference.driver.AbstractMotionPlanner.home(AbstractMotionPlanner.java:99) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:425) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:327) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2021-11-15 17:42:48.015 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM4 IO Error on reading from the controller.