2021-11-16 10:02:59.287 Main INFO: Bienvenue, Bienvenido, Willkommen, Hello, Namaskar, Welkom, Bonjour to OpenPnP version 2021-11-07_20-20-57.7a8ce09.
2021-11-16 10:02:59.288 Scripting TRACE: Scripting.on Startup
2021-11-16 10:03:00.620 AbstractBroadcastingCamera TRACE: Camera DOWN thread 26 started.
2021-11-16 10:03:01.430 AbstractBroadcastingCamera TRACE: Camera Up_Logitech thread 28 started.
2021-11-16 10:06:01.089 ReferenceMachine DEBUG: setEnabled(true)
2021-11-16 10:06:09.126 GcodeDriver DEBUG: [serial://COM3] >> G21 ; Set millimeters mode, 25000
2021-11-16 10:06:09.126 GcodeDriver$ReaderThread TRACE: [serial://COM3] << Smoothie
2021-11-16 10:06:09.127 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:06:09.128 GcodeDriver TRACE: [serial://COM3] confirmed G21
2021-11-16 10:06:09.128 GcodeDriver DEBUG: [serial://COM3] >> G90 ; Set absolute positioning mode, 25000
2021-11-16 10:06:09.128 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:06:09.128 GcodeDriver TRACE: [serial://COM3] confirmed G90
2021-11-16 10:06:09.128 GcodeDriver DEBUG: [serial://COM3] >> M82 ; Set absolute mode for extruder, 25000
2021-11-16 10:06:09.128 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:06:09.129 GcodeDriver TRACE: [serial://COM3] confirmed M82
2021-11-16 10:06:09.129 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:06:12.134 GcodeDriver$ReaderThread TRACE: [serial://COM4] << Controller starting...
2021-11-16 10:06:12.135 GcodeDriver$ReaderThread TRACE: [serial://COM4] << Controller ready.
2021-11-16 10:06:59.898 ReferenceMachine DEBUG: homing machine
2021-11-16 10:06:59.901 GcodeDriver DEBUG: [serial://COM3] >> M204 S1000 ;  set a low acceleration, -1
2021-11-16 10:06:59.903 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:06:59.903 GcodeDriver TRACE: [serial://COM3] confirmed M204S1000
2021-11-16 10:06:59.903 GcodeDriver DEBUG: [serial://COM3] >> G28 A0 Z0, -1
2021-11-16 10:07:00.648 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:00.648 GcodeDriver TRACE: [serial://COM3] confirmed G28A0Z0
2021-11-16 10:07:00.648 GcodeDriver DEBUG: [serial://COM3] >> M400, -1
2021-11-16 10:07:00.648 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:00.649 GcodeDriver TRACE: [serial://COM3] confirmed M400
2021-11-16 10:07:00.649 GcodeDriver DEBUG: [serial://COM3] >> G28 X0 Y0 B0 C0; Home all axes, -1
2021-11-16 10:07:04.935 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:04.935 GcodeDriver TRACE: [serial://COM3] confirmed G28X0Y0B0C0
2021-11-16 10:07:04.935 GcodeDriver DEBUG: [serial://COM3] >> M400, -1
2021-11-16 10:07:04.936 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:04.936 GcodeDriver TRACE: [serial://COM3] confirmed M400
2021-11-16 10:07:04.936 GcodeDriver DEBUG: [serial://COM3] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero., -1
2021-11-16 10:07:04.938 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:04.938 GcodeDriver TRACE: [serial://COM3] confirmed G92X0Y0Z0A0B0C0
2021-11-16 10:07:04.938 GcodeDriver DEBUG: [serial://COM3] >> M400, -1
2021-11-16 10:07:04.939 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:04.939 GcodeDriver TRACE: [serial://COM3] confirmed M400
2021-11-16 10:07:04.940 GcodeDriver DEBUG: [serial://COM4] >> ; Initialize acceleration, -1
2021-11-16 10:07:04.944 GcodeDriver$ReaderThread TRACE: [serial://COM4] << ok unknown or empty command ignored
2021-11-16 10:07:04.944 GcodeDriver TRACE: [serial://COM4] confirmed 
2021-11-16 10:07:04.944 GcodeDriver DEBUG: [serial://COM4] >> G28 ; Home all axes, -1
2021-11-16 10:07:04.945 GcodeDriver$ReaderThread TRACE: [serial://COM4] << G28
2021-11-16 10:07:04.948 GcodeDriver$ReaderThread TRACE: [serial://COM4] << ok unknown or empty command ignored
2021-11-16 10:07:04.948 GcodeDriver TRACE: [serial://COM4] confirmed G28
2021-11-16 10:07:04.948 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, -1
2021-11-16 10:07:04.949 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:04.949 GcodeDriver TRACE: [serial://COM3] confirmed M400
2021-11-16 10:07:04.950 ReferenceHead DEBUG: H1.home()
2021-11-16 10:07:04.950 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (92.000000, 42.000000, 0.000000, 0.000000 mm)
2021-11-16 10:07:04.951 ReferenceHead DEBUG: H1.moveToSafeZ(0.84)
2021-11-16 10:07:04.951 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.84)
2021-11-16 10:07:04.951 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.84)
2021-11-16 10:07:04.951 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.84)
2021-11-16 10:07:04.952 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.84)
2021-11-16 10:07:04.952 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.84)
2021-11-16 10:07:04.952 AbstractHeadMountable DEBUG: DOWN.moveTo((92.000000, 42.000000, 0.000000, 0.000000 mm), 0.84)
2021-11-16 10:07:04.965 GcodeDriver DEBUG: [serial://COM3] >> M204 S1163.60 G1 X91.8524 Y41.9593     F20567.27 ; move to target, 25000
2021-11-16 10:07:04.966 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:04.966 GcodeDriver TRACE: [serial://COM3] confirmed M204S1163.6G1X91.8524Y41.9593F20567.27
2021-11-16 10:07:04.967 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.0787 Y42.0393     F293.11 ; move to target, 25000
2021-11-16 10:07:04.969 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:04.969 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.0787Y42.0393F293.11
2021-11-16 10:07:04.970 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 29762
2021-11-16 10:07:05.631 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:05.631 GcodeDriver TRACE: [serial://COM3] confirmed M400
2021-11-16 10:07:05.631 AbstractHeadMountable DEBUG: DOWN.moveTo((92.000000, 42.000000, 0.000000, 0.000000 mm), 0.84)
2021-11-16 10:07:05.633 Scripting TRACE: Scripting.on Camera.BeforeSettle
2021-11-16 10:07:05.633 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.640 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.644 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.656 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.660 AbstractCamera TRACE: autoSettleAndCapture t=27 auto settle score: 100.0
2021-11-16 10:07:05.660 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.704 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.707 AbstractCamera TRACE: autoSettleAndCapture t=74 auto settle score: 100.0
2021-11-16 10:07:05.708 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.736 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.739 AbstractCamera TRACE: autoSettleAndCapture t=106 auto settle score: 100.0
2021-11-16 10:07:05.740 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.784 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.788 AbstractCamera TRACE: autoSettleAndCapture t=155 auto settle score: 100.0
2021-11-16 10:07:05.788 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.816 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.819 AbstractCamera TRACE: autoSettleAndCapture t=186 auto settle score: 100.0
2021-11-16 10:07:05.820 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.864 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.867 AbstractCamera TRACE: autoSettleAndCapture t=234 auto settle score: 100.0
2021-11-16 10:07:05.867 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.896 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.899 AbstractCamera TRACE: autoSettleAndCapture t=266 auto settle score: 100.0
2021-11-16 10:07:05.899 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.944 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.949 AbstractCamera TRACE: autoSettleAndCapture t=315 auto settle score: 100.0
2021-11-16 10:07:05.949 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:05.977 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:05.981 AbstractCamera TRACE: autoSettleAndCapture t=348 auto settle score: 100.0
2021-11-16 10:07:05.981 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.024 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.027 AbstractCamera TRACE: autoSettleAndCapture t=394 auto settle score: 100.0
2021-11-16 10:07:06.027 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.057 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.060 AbstractCamera TRACE: autoSettleAndCapture t=427 auto settle score: 100.0
2021-11-16 10:07:06.060 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.104 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.107 AbstractCamera TRACE: autoSettleAndCapture t=474 auto settle score: 100.0
2021-11-16 10:07:06.107 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.136 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.139 AbstractCamera TRACE: autoSettleAndCapture t=506 auto settle score: 100.0
2021-11-16 10:07:06.139 AbstractCamera DEBUG: autoSettleAndCapture in 506 ms
2021-11-16 10:07:06.140 Scripting TRACE: Scripting.on Camera.AfterSettle
2021-11-16 10:07:06.204 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (93.455815, 43.376128, 0.000000, 0.000000 mm)
2021-11-16 10:07:06.204 AbstractHeadMountable DEBUG: DOWN.moveTo((93.455815, 43.376128, 0.000000, 0.000000 mm), 0.84)
2021-11-16 10:07:06.208 GcodeDriver DEBUG: [serial://COM3] >> M204 S1458.86 G1 X93.3082 Y43.3354     F3063.12 ; move to target, 25000
2021-11-16 10:07:06.209 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:06.210 GcodeDriver TRACE: [serial://COM3] confirmed M204S1458.86G1X93.3082Y43.3354F3063.12
2021-11-16 10:07:06.210 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.5345 Y43.4154     F293.11 ; move to target, 25000
2021-11-16 10:07:06.212 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:06.212 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.5345Y43.4154F293.11
2021-11-16 10:07:06.212 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 29762
2021-11-16 10:07:06.354 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:06.354 GcodeDriver TRACE: [serial://COM3] confirmed M400
2021-11-16 10:07:06.355 Scripting TRACE: Scripting.on Camera.BeforeSettle
2021-11-16 10:07:06.356 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.363 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.366 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.377 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.380 AbstractCamera TRACE: autoSettleAndCapture t=24 auto settle score: 100.0
2021-11-16 10:07:06.380 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.424 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.427 AbstractCamera TRACE: autoSettleAndCapture t=71 auto settle score: 100.0
2021-11-16 10:07:06.427 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.456 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.459 AbstractCamera TRACE: autoSettleAndCapture t=103 auto settle score: 100.0
2021-11-16 10:07:06.459 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.504 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.507 AbstractCamera TRACE: autoSettleAndCapture t=151 auto settle score: 100.0
2021-11-16 10:07:06.507 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.536 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.539 AbstractCamera TRACE: autoSettleAndCapture t=183 auto settle score: 100.0
2021-11-16 10:07:06.539 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.584 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.588 AbstractCamera TRACE: autoSettleAndCapture t=231 auto settle score: 100.0
2021-11-16 10:07:06.588 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.616 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.619 AbstractCamera TRACE: autoSettleAndCapture t=263 auto settle score: 100.0
2021-11-16 10:07:06.619 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.648 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.651 AbstractCamera TRACE: autoSettleAndCapture t=295 auto settle score: 100.0
2021-11-16 10:07:06.651 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.696 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.699 AbstractCamera TRACE: autoSettleAndCapture t=343 auto settle score: 100.0
2021-11-16 10:07:06.700 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.729 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.731 AbstractCamera TRACE: autoSettleAndCapture t=375 auto settle score: 100.0
2021-11-16 10:07:06.732 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.777 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.780 AbstractCamera TRACE: autoSettleAndCapture t=423 auto settle score: 100.0
2021-11-16 10:07:06.780 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.808 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.812 AbstractCamera TRACE: autoSettleAndCapture t=456 auto settle score: 100.0
2021-11-16 10:07:06.812 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:06.856 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:06.860 AbstractCamera TRACE: autoSettleAndCapture t=503 auto settle score: 100.0
2021-11-16 10:07:06.860 AbstractCamera DEBUG: autoSettleAndCapture in 504 ms
2021-11-16 10:07:06.860 Scripting TRACE: Scripting.on Camera.AfterSettle
2021-11-16 10:07:06.914 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.354117, 41.252959, 0.000000, 0.000000 mm)
2021-11-16 10:07:06.915 AbstractHeadMountable DEBUG: DOWN.moveTo((92.354117, 41.252959, 0.000000, 0.000000 mm), 0.84)
2021-11-16 10:07:06.917 GcodeDriver DEBUG: [serial://COM3] >> M204 S1164.20 G1 X92.5804 Y41.3330     F3098.45 ; move to target, 25000
2021-11-16 10:07:06.918 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:06.918 GcodeDriver TRACE: [serial://COM3] confirmed M204S1164.2G1X92.5804Y41.333F3098.45
2021-11-16 10:07:06.919 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.3541 Y41.2530     F293.11 ; move to target, 25000
2021-11-16 10:07:06.921 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:06.921 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.3541Y41.253F293.11
2021-11-16 10:07:06.921 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 29762
2021-11-16 10:07:07.080 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:07.080 GcodeDriver TRACE: [serial://COM3] confirmed M400
2021-11-16 10:07:07.083 Scripting TRACE: Scripting.on Camera.BeforeSettle
2021-11-16 10:07:07.084 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.094 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.097 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.104 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.107 AbstractCamera TRACE: autoSettleAndCapture t=23 auto settle score: 100.0
2021-11-16 10:07:07.107 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.127 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.130 AbstractCamera TRACE: autoSettleAndCapture t=46 auto settle score: 100.0
2021-11-16 10:07:07.130 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.177 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.181 AbstractCamera TRACE: autoSettleAndCapture t=97 auto settle score: 100.0
2021-11-16 10:07:07.181 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.213 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.216 AbstractCamera TRACE: autoSettleAndCapture t=132 auto settle score: 100.0
2021-11-16 10:07:07.216 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.255 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.258 AbstractCamera TRACE: autoSettleAndCapture t=174 auto settle score: 100.0
2021-11-16 10:07:07.259 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.288 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.291 AbstractCamera TRACE: autoSettleAndCapture t=207 auto settle score: 100.0
2021-11-16 10:07:07.291 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.336 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.339 AbstractCamera TRACE: autoSettleAndCapture t=255 auto settle score: 100.0
2021-11-16 10:07:07.339 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.368 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.371 AbstractCamera TRACE: autoSettleAndCapture t=287 auto settle score: 100.0
2021-11-16 10:07:07.371 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.416 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.419 AbstractCamera TRACE: autoSettleAndCapture t=335 auto settle score: 100.0
2021-11-16 10:07:07.419 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.447 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.450 AbstractCamera TRACE: autoSettleAndCapture t=366 auto settle score: 100.0
2021-11-16 10:07:07.450 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.495 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.498 AbstractCamera TRACE: autoSettleAndCapture t=414 auto settle score: 100.0
2021-11-16 10:07:07.498 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.527 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.531 AbstractCamera TRACE: autoSettleAndCapture t=446 auto settle score: 100.0
2021-11-16 10:07:07.531 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.576 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.579 AbstractCamera TRACE: autoSettleAndCapture t=495 auto settle score: 100.0
2021-11-16 10:07:07.579 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-11-16 10:07:07.608 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-11-16 10:07:07.611 AbstractCamera TRACE: autoSettleAndCapture t=527 auto settle score: 100.0
2021-11-16 10:07:07.611 AbstractCamera DEBUG: autoSettleAndCapture in 527 ms
2021-11-16 10:07:07.611 Scripting TRACE: Scripting.on Camera.AfterSettle
2021-11-16 10:07:07.668 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (93.495161, 43.336810, 0.000000, 0.000000 mm)
2021-11-16 10:07:07.668 AbstractHeadMountable DEBUG: DOWN.moveTo((93.495161, 43.336810, 0.000000, 0.000000 mm), 0.84)
2021-11-16 10:07:07.670 GcodeDriver DEBUG: [serial://COM3] >> M204 S1176.88 G1 X93.3476 Y43.2961     F3102.50 ; move to target, 25000
2021-11-16 10:07:07.671 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:07.671 GcodeDriver TRACE: [serial://COM3] confirmed M204S1176.88G1X93.3476Y43.2961F3102.5
2021-11-16 10:07:07.672 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X93.5739 Y43.3761     F293.11 ; move to target, 25000
2021-11-16 10:07:07.673 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:07.673 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X93.5739Y43.3761F293.11
2021-11-16 10:07:07.674 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 29762
2021-11-16 10:07:07.833 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:07.833 GcodeDriver TRACE: [serial://COM3] confirmed M400
2021-11-16 10:07:07.834 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (92.924639, 42.294885, 0.000000, 0.000000 mm)
2021-11-16 10:07:07.834 AbstractHeadMountable DEBUG: DOWN.moveTo((92.924639, 42.294885, 0.000000, 0.000000 mm), 0.84)
2021-11-16 10:07:07.836 GcodeDriver DEBUG: [serial://COM3] >> M204 S1148.95 G1 X93.1509 Y42.3749     F2120.31 ; move to target, 25000
2021-11-16 10:07:07.837 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:07.838 GcodeDriver TRACE: [serial://COM3] confirmed M204S1148.95G1X93.1509Y42.3749F2120.31
2021-11-16 10:07:07.838 GcodeDriver DEBUG: [serial://COM3] >> M204 S99.44 G1 X92.9246 Y42.2949     F293.11 ; move to target, 25000
2021-11-16 10:07:07.839 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:07.840 GcodeDriver TRACE: [serial://COM3] confirmed M204S99.44G1X92.9246Y42.2949F293.11
2021-11-16 10:07:07.840 GcodeDriver DEBUG: [serial://COM3] >> M400 ; Wait for moves to complete before returning, 29762
2021-11-16 10:07:07.972 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:07.972 GcodeDriver TRACE: [serial://COM3] confirmed M400
2021-11-16 10:07:07.973 GcodeDriver DEBUG: [serial://COM3] >> G92 X0.0000 Y0.0000     ; reset coordinates, -1
2021-11-16 10:07:07.974 GcodeDriver$ReaderThread TRACE: [serial://COM3] << ok
2021-11-16 10:07:07.974 GcodeDriver TRACE: [serial://COM3] confirmed G92X0Y0
2021-11-16 10:07:07.974 ReferenceNozzle DEBUG: N1.home()
2021-11-16 10:07:07.974 ReferenceNozzle DEBUG: N2.home()
2021-11-16 10:07:07.974 ReferenceNozzle DEBUG: N3.home()
2021-11-16 10:07:07.974 ReferenceNozzle DEBUG: N4.home()
2021-11-16 10:07:07.974 Scripting TRACE: Scripting.on Machine.AfterHoming
2021-11-16 10:07:07.975 ReferenceMachine INFO: setHomed(true)