Trace with replaced Smoothieboard: 
2024-04-04 09:01:55.294 Main INFO: Bienvenue, Bienvenido, Willkommen, Hello, Namaskar, Welkom, Bonjour to OpenPnP version 2024-03-27_09-59-31.0b0aca0.
2024-04-04 09:01:55.296 Scripting TRACE: Scripting.on Startup
2024-04-04 09:01:56.812 AbstractBroadcastingCamera TRACE: Camera DOWN thread 49 started.
2024-04-04 09:01:57.584 AbstractBroadcastingCamera TRACE: Camera Up_Logitech thread 51 started.
2024-04-04 09:02:00.514 ReferenceMachine DEBUG: setEnabled(true)
2024-04-04 09:02:00.519 GcodeDriver DEBUG: [Smoothie:COM5] Connect
2024-04-04 09:02:00.553 GcodeDriver TRACE: Smoothie waiting for connection 15000ms
2024-04-04 09:02:15.555 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> G21 ;
2024-04-04 09:02:15.556 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> G90 ; Set absolute positioning mode
2024-04-04 09:02:15.557 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> G21 ;
2024-04-04 09:02:15.557 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:15.568 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M82 ; Set absolute mode for extruder
2024-04-04 09:02:15.568 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> G90 ; Set absolute positioning mode
2024-04-04 09:02:15.568 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset
2024-04-04 09:02:15.568 GcodeDriver DEBUG: [Schultz:COM3] Connect
2024-04-04 09:02:15.568 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M82 ; Set absolute mode for extruder
2024-04-04 09:02:15.569 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:15.569 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:15.578 GcodeDriver TRACE: Schultz waiting for connection 3000ms
2024-04-04 09:02:15.641 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller starting...
2024-04-04 09:02:15.643 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller ready.
2024-04-04 09:02:18.579 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G21 ; Set millimeters mode
2024-04-04 09:02:18.579 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G90 ; Set absolute positioning mode
2024-04-04 09:02:18.579 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset
2024-04-04 09:02:18.579 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G21 ; Set millimeters mode
2024-04-04 09:02:18.582 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G21 ; Set millimeters mode
2024-04-04 09:02:18.585 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored
2024-04-04 09:02:18.585 GcodeDriver TRACE: [Schultz:COM3] confirmed G21 ; Set millimeters mode
2024-04-04 09:02:18.586 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G90 ; Set absolute positioning mode
2024-04-04 09:02:18.589 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G90 ; Set absolute positioning mode
2024-04-04 09:02:18.592 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored
2024-04-04 09:02:18.598 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:23.813 ReferenceMachine DEBUG: homing machine
2024-04-04 09:02:23.816 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1000 ;  set a low acceleration
2024-04-04 09:02:23.817 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> G28 A0 Z0
2024-04-04 09:02:23.817 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1000 ;  set a low acceleration
2024-04-04 09:02:23.818 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> G28 X88 Y42 B0 C0; Home all axes
2024-04-04 09:02:23.818 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> G28 A0 Z0
2024-04-04 09:02:23.818 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero.
2024-04-04 09:02:23.818 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:23.818 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> G28 X88 Y42 B0 C0; Home all axes
2024-04-04 09:02:23.818 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero.
2024-04-04 09:02:23.818 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset
2024-04-04 09:02:23.819 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G28 ; Home all axes
2024-04-04 09:02:23.819 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset
2024-04-04 09:02:23.819 GcodeDriver TRACE: [Schultz:COM3] confirmed G90 ; Set absolute positioning mode
2024-04-04 09:02:23.819 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:23.819 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M114 ; get position
2024-04-04 09:02:23.819 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G28 ; Home all axes
2024-04-04 09:02:23.819 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:23.819 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M114 ; get position
2024-04-04 09:02:23.821 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G28 ; Home all axes
2024-04-04 09:02:23.824 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored
2024-04-04 09:02:24.742 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:34.006 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:34.006 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:34.007 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:34.007 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000
2024-04-04 09:02:34.008 GcodeDriver TRACE: Position report: ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000
2024-04-04 09:02:34.008 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000)
2024-04-04 09:02:34.008 GcodeAsyncDriver TRACE: Smoothie confirmation complete.
2024-04-04 09:02:34.009 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:34.009 GcodeDriver TRACE: [Schultz:COM3] confirmed G28 ; Home all axes
2024-04-04 09:02:34.009 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:34.014 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << M400 ; Wait for moves to complete before returning
2024-04-04 09:02:34.017 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored
2024-04-04 09:02:34.017 GcodeDriver TRACE: [Schultz:COM3] confirmed M400 ; Wait for moves to complete before returning
2024-04-04 09:02:34.018 GcodeAsyncDriver TRACE: Schultz waited 9ms to drain command queue.
2024-04-04 09:02:34.018 GcodeAsyncDriver TRACE: Schultz confirmation complete.
2024-04-04 09:02:34.019 ReferenceHead DEBUG: H1.home()
2024-04-04 09:02:34.019 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (110.000000, 43.500000, -25.000000, 0.000000 mm)
2024-04-04 09:02:34.020 ReferenceHead DEBUG: H1.moveToSafeZ(0.86)
2024-04-04 09:02:34.020 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86)
2024-04-04 09:02:34.020 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86)
2024-04-04 09:02:34.020 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86)
2024-04-04 09:02:34.021 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86)
2024-04-04 09:02:34.021 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86)
2024-04-04 09:02:34.021 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:34.048 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, -25.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:34.082 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1400 G1 X109.3513 Y42.9913     F17207 ; move to target
2024-04-04 09:02:34.083 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1400 G1 X109.3513 Y42.9913     F17207 ; move to target
2024-04-04 09:02:34.084 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:34.084 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S18 G1 X109.3213 Y43.4438     F120 ; move to target
2024-04-04 09:02:34.085 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S18 G1 X109.3213 Y43.4438     F120 ; move to target
2024-04-04 09:02:34.085 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:34.086 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M114 ; get position
2024-04-04 09:02:34.086 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:34.086 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:34.086 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M114 ; get position
2024-04-04 09:02:34.983 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:34.984 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok C: X:109.3213 Y:43.4438 Z:0.0000 A:0.0000 B:0.0000 C:0.0000
2024-04-04 09:02:34.985 GcodeDriver TRACE: Position report: ok C: X:109.3213 Y:43.4438 Z:0.0000 A:0.0000 B:0.0000 C:0.0000
2024-04-04 09:02:34.985 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:109.321300, y:43.443800, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000)
2024-04-04 09:02:34.986 GcodeAsyncDriver TRACE: Smoothie confirmation complete.
2024-04-04 09:02:34.986 ReferenceActuator DEBUG: UpCamLight.actuate(false)
2024-04-04 09:02:34.987 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M811 ; turn off Light
2024-04-04 09:02:34.987 Scripting TRACE: Scripting.on Camera.BeforeSettle
2024-04-04 09:02:34.987 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M811 ; turn off Light
2024-04-04 09:02:34.988 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:34.988 Scripting TRACE: Scripting.on Camera.BeforeCapture
2024-04-04 09:02:34.998 Scripting TRACE: Scripting.on Camera.AfterCapture
2024-04-04 09:02:35.002 Scripting TRACE: Scripting.on Camera.BeforeCapture
2024-04-04 09:02:35.032 Scripting TRACE: Scripting.on Camera.AfterCapture
2024-04-04 09:02:35.042 AbstractSettlingCamera TRACE: autoSettleAndCapture t=52 auto settle score: 57.647 compute time: 7
2024-04-04 09:02:35.042 Scripting TRACE: Scripting.on Camera.BeforeCapture
2024-04-04 09:02:35.052 Scripting TRACE: Scripting.on Camera.AfterCapture
2024-04-04 09:02:35.057 AbstractSettlingCamera TRACE: autoSettleAndCapture t=70 auto settle score: 28.235 compute time: 5
2024-04-04 09:02:35.058 Scripting TRACE: Scripting.on Camera.BeforeCapture
2024-04-04 09:02:35.068 Scripting TRACE: Scripting.on Camera.AfterCapture
2024-04-04 09:02:35.074 AbstractSettlingCamera TRACE: autoSettleAndCapture t=86 auto settle score: 23.137 compute time: 5
2024-04-04 09:02:35.074 Scripting TRACE: Scripting.on Camera.BeforeCapture
2024-04-04 09:02:35.097 Scripting TRACE: Scripting.on Camera.AfterCapture
2024-04-04 09:02:35.103 AbstractSettlingCamera TRACE: autoSettleAndCapture t=115 auto settle score: 18.039 compute time: 4
2024-04-04 09:02:35.103 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 116 ms
2024-04-04 09:02:35.103 Scripting TRACE: Scripting.on Camera.AfterSettle
2024-04-04 09:02:35.126 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.5) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1555: error: (-2:Unspecified error) in function 'double __cdecl cv::threshold(const class cv::_InputArray &,const class cv::_OutputArray &,double,double,int)'
> THRESH_OTSU mode:
>     'src_type == CV_8UC1 || src_type == CV_16UC1'
> where
>     'src_type' is 16 (CV_8UC3)
]
2024-04-04 09:02:35.170 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.692552, 44.936028, -25.000000, 0.000000 mm)
2024-04-04 09:02:35.170 AbstractHeadMountable DEBUG: DOWN.moveTo((112.692552, 44.936028, -25.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:35.178 Scripting TRACE: Scripting.on Camera.BeforeSettle
2024-04-04 09:02:35.196 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S405 G1 X112.0439 Y44.4273     F1452 ; move to target
2024-04-04 09:02:35.196 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S405 G1 X112.0439 Y44.4273     F1452 ; move to target
2024-04-04 09:02:35.197 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:35.198 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S18 G1 X112.0138 Y44.8798     F120 ; move to target
2024-04-04 09:02:35.199 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:35.199 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S18 G1 X112.0138 Y44.8798     F120 ; move to target
2024-04-04 09:02:35.199 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M114 ; get position
2024-04-04 09:02:35.199 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:35.199 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M114 ; get position
2024-04-04 09:02:35.200 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:35.658 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:35.659 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok C: X:112.0138 Y:44.8798 Z:0.0000 A:0.0000 B:0.0000 C:0.0000
2024-04-04 09:02:35.659 GcodeDriver TRACE: Position report: ok C: X:112.0138 Y:44.8798 Z:0.0000 A:0.0000 B:0.0000 C:0.0000
2024-04-04 09:02:35.659 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:112.013800, y:44.879800, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000)
2024-04-04 09:02:35.659 GcodeAsyncDriver TRACE: Smoothie confirmation complete.
2024-04-04 09:02:35.660 Scripting TRACE: Scripting.on Camera.BeforeCapture
2024-04-04 09:02:35.668 Scripting TRACE: Scripting.on Camera.AfterCapture
2024-04-04 09:02:35.671 Scripting TRACE: Scripting.on Camera.BeforeCapture
2024-04-04 09:02:35.707 Scripting TRACE: Scripting.on Camera.AfterCapture
2024-04-04 09:02:35.713 AbstractSettlingCamera TRACE: autoSettleAndCapture t=53 auto settle score: 25.490 compute time: 5
2024-04-04 09:02:35.713 Scripting TRACE: Scripting.on Camera.BeforeCapture
2024-04-04 09:02:35.738 Scripting TRACE: Scripting.on Camera.AfterCapture
2024-04-04 09:02:35.742 AbstractSettlingCamera TRACE: autoSettleAndCapture t=82 auto settle score: 23.529 compute time: 4
2024-04-04 09:02:35.742 Scripting TRACE: Scripting.on Camera.BeforeCapture
2024-04-04 09:02:35.768 Scripting TRACE: Scripting.on Camera.AfterCapture
2024-04-04 09:02:35.774 AbstractSettlingCamera TRACE: autoSettleAndCapture t=114 auto settle score: 18.039 compute time: 5
2024-04-04 09:02:35.774 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 115 ms
2024-04-04 09:02:35.774 Scripting TRACE: Scripting.on Camera.AfterSettle
2024-04-04 09:02:35.792 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.5) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1555: error: (-2:Unspecified error) in function 'double __cdecl cv::threshold(const class cv::_InputArray &,const class cv::_OutputArray &,double,double,int)'
> THRESH_OTSU mode:
>     'src_type == CV_8UC1 || src_type == CV_16UC1'
> where
>     'src_type' is 16 (CV_8UC3)
]
2024-04-04 09:02:35.825 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.672607, 44.936028, -25.000000, 0.000000 mm)
2024-04-04 09:02:35.826 ReferenceFiducialLocator TRACE: FIDUCIAL-HOME less than max. linear offset 0.020mm < 0.200mm, locator satisfied.
2024-04-04 09:02:35.830 AbstractHeadMountable DEBUG: DOWN.moveTo((112.672607, 44.936028, -25.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:35.865 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S59 G1 X112.0239      F60 ; move to target
2024-04-04 09:02:35.865 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S59 G1 X112.0239      F60 ; move to target
2024-04-04 09:02:35.866 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X111.9939      F68 ; move to target
2024-04-04 09:02:35.866 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X111.9939      F68 ; move to target
2024-04-04 09:02:35.866 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:35.867 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> G92 X-0.6787 Y-0.0562     ; reset coordinates
2024-04-04 09:02:35.867 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:35.867 ReferenceNozzle DEBUG: N1.home()
2024-04-04 09:02:35.867 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> G92 X-0.6787 Y-0.0562     ; reset coordinates
2024-04-04 09:02:35.867 ReferenceNozzle DEBUG: N2.home()
2024-04-04 09:02:35.867 ReferenceNozzle DEBUG: N3.home()
2024-04-04 09:02:35.869 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:35.869 ReferenceNozzle DEBUG: N3.home() nozzle tip 504 ID1.5 calibration reset
2024-04-04 09:02:35.869 ReferenceNozzle DEBUG: N3.home() nozzle tip 505 ID3.5 calibration reset
2024-04-04 09:02:35.869 ReferenceNozzle DEBUG: N3.home() nozzle tip 506 ID5.0 calibration reset
2024-04-04 09:02:35.869 ReferenceNozzle DEBUG: N4.home()
2024-04-04 09:02:35.869 ReferenceNozzle DEBUG: N4.home() nozzle tip 504 ID1.5 calibration reset
2024-04-04 09:02:35.869 ReferenceNozzle DEBUG: N4.home() nozzle tip 505 ID3.5 calibration reset
2024-04-04 09:02:35.869 ReferenceNozzle DEBUG: N4.home() nozzle tip 506 ID5.0 calibration reset
2024-04-04 09:02:36.405 Scripting TRACE: Scripting.on Machine.AfterHoming
2024-04-04 09:02:36.406 ReferenceMachine INFO: setHomed(true)
2024-04-04 09:02:36.406 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:40.171 AbstractHeadMountable DEBUG: N1.moveTo((45.600259, 22.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:40.183 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S588 G1 X9.3513      F3257 ; move to target
2024-04-04 09:02:40.183 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S588 G1 X9.3513      F3257 ; move to target
2024-04-04 09:02:40.184 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X9.3213      F68 ; move to target
2024-04-04 09:02:40.184 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:40.184 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X9.3213      F68 ; move to target
2024-04-04 09:02:40.184 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:40.185 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:40.823 AbstractHeadMountable DEBUG: N1.moveTo((55.600259, 22.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:40.836 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S588 G1 X19.3513      F3257 ; move to target
2024-04-04 09:02:40.836 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S588 G1 X19.3513      F3257 ; move to target
2024-04-04 09:02:40.837 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X19.3213      F68 ; move to target
2024-04-04 09:02:40.837 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:40.837 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X19.3213      F68 ; move to target
2024-04-04 09:02:40.837 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:40.838 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:41.065 AbstractHeadMountable DEBUG: N1.moveTo((65.600259, 22.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:41.076 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S588 G1 X29.3513      F3257 ; move to target
2024-04-04 09:02:41.077 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S588 G1 X29.3513      F3257 ; move to target
2024-04-04 09:02:41.077 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X29.3213      F68 ; move to target
2024-04-04 09:02:41.077 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:41.077 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X29.3213      F68 ; move to target
2024-04-04 09:02:41.078 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:41.079 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:41.328 AbstractHeadMountable DEBUG: N1.moveTo((75.600259, 22.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:41.339 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S588 G1 X39.3513      F3257 ; move to target
2024-04-04 09:02:41.340 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S588 G1 X39.3513      F3257 ; move to target
2024-04-04 09:02:41.340 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X39.3213      F68 ; move to target
2024-04-04 09:02:41.340 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:41.340 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X39.3213      F68 ; move to target
2024-04-04 09:02:41.341 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:41.342 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:41.548 AbstractHeadMountable DEBUG: N1.moveTo((85.600259, 22.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:41.559 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S588 G1 X49.3513      F3257 ; move to target
2024-04-04 09:02:41.560 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X49.3213      F68 ; move to target
2024-04-04 09:02:41.560 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:41.560 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S588 G1 X49.3513      F3257 ; move to target
2024-04-04 09:02:41.560 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X49.3213      F68 ; move to target
2024-04-04 09:02:41.561 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:41.562 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:41.771 AbstractHeadMountable DEBUG: N1.moveTo((95.600259, 22.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:41.782 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S588 G1 X59.3513      F3257 ; move to target
2024-04-04 09:02:41.782 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S588 G1 X59.3513      F3257 ; move to target
2024-04-04 09:02:41.782 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X59.3213      F68 ; move to target
2024-04-04 09:02:41.783 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:41.783 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X59.3213      F68 ; move to target
2024-04-04 09:02:41.783 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:41.783 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M114 ; get position
2024-04-04 09:02:41.783 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M400 ; Wait for moves to complete before returning
2024-04-04 09:02:41.784 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M114 ; get position
2024-04-04 09:02:41.784 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:41.785 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:42.517 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:42.518 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok C: X:59.3213 Y:-0.0562 Z:0.0000 A:0.0000 B:0.0000 C:0.0000
2024-04-04 09:02:42.518 GcodeDriver TRACE: Position report: ok C: X:59.3213 Y:-0.0562 Z:0.0000 A:0.0000 B:0.0000 C:0.0000
2024-04-04 09:02:42.519 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:59.321300, y:-0.056200, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000)
2024-04-04 09:02:42.519 GcodeAsyncDriver TRACE: Smoothie confirmation complete.
2024-04-04 09:02:42.519 ReferenceActuator DEBUG: UpCamLight.actuate(true)
2024-04-04 09:02:42.519 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M810 ; turn on Light
2024-04-04 09:02:42.519 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M810 ; turn on Light
2024-04-04 09:02:42.520 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:42.521 AbstractHeadMountable DEBUG: N1.moveTo((105.600259, 22.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:42.534 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S588 G1 X69.3513      F3257 ; move to target
2024-04-04 09:02:42.534 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S588 G1 X69.3513      F3257 ; move to target
2024-04-04 09:02:42.534 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X69.3213      F68 ; move to target
2024-04-04 09:02:42.535 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:42.535 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X69.3213      F68 ; move to target
2024-04-04 09:02:42.535 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:42.536 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:44.825 AbstractHeadMountable DEBUG: N1.moveTo((205.600259, 22.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:44.835 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1265 G1 X169.3513      F15091 ; move to target
2024-04-04 09:02:44.835 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1265 G1 X169.3513      F15091 ; move to target
2024-04-04 09:02:44.835 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X169.3213      F68 ; move to target
2024-04-04 09:02:44.835 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X169.3213      F68 ; move to target
2024-04-04 09:02:44.836 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:44.835 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:44.837 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:45.607 AbstractHeadMountable DEBUG: N1.moveTo((305.600259, 22.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:45.616 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1265 G1 X269.3513      F15091 ; move to target
2024-04-04 09:02:45.617 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1265 G1 X269.3513      F15091 ; move to target
2024-04-04 09:02:45.617 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X269.3213      F68 ; move to target
2024-04-04 09:02:45.617 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:45.617 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X269.3213      F68 ; move to target
2024-04-04 09:02:45.618 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:45.619 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:46.956 AbstractHeadMountable DEBUG: N1.moveTo((305.600259, 122.600471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:46.962 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1  Y99.4913     F14962 ; move to target
2024-04-04 09:02:46.962 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1  Y99.4913     F14962 ; move to target
2024-04-04 09:02:46.962 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S18 G1  Y99.9438     F120 ; move to target
2024-04-04 09:02:46.963 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:46.963 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S18 G1  Y99.9438     F120 ; move to target
2024-04-04 09:02:46.963 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:46.964 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:49.048 ReferenceHead DEBUG: H1.moveToSafeZ(0.86)
2024-04-04 09:02:49.049 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86)
2024-04-04 09:02:49.049 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86)
2024-04-04 09:02:49.049 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86)
2024-04-04 09:02:49.049 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86)
2024-04-04 09:02:49.049 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86)
2024-04-04 09:02:49.050 AbstractHeadMountable DEBUG: DOWN.moveTo((270.000000, 100.000000, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:49.050 AbstractHeadMountable DEBUG: DOWN.moveTo((310.000000, 290.000000, NaN, NaN mm), 0.86)
2024-04-04 09:02:49.057 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1134 G1 X309.3513 Y289.4913     F22807 ; move to target
2024-04-04 09:02:49.057 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1134 G1 X309.3513 Y289.4913     F22807 ; move to target
2024-04-04 09:02:49.057 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S18 G1 X309.3213 Y289.9438     F120 ; move to target
2024-04-04 09:02:49.057 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:49.057 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S18 G1 X309.3213 Y289.9438     F120 ; move to target
2024-04-04 09:02:49.058 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:49.059 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:02:55.748 AbstractHeadMountable DEBUG: DOWN.moveTo((310.000000, 290.000000, 10.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:55.749 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:59.763 AbstractHeadMountable DEBUG: N2.moveTo((377.100259, 313.100471, 10.000000, 0.000000 mm), 0.86)
2024-04-04 09:02:59.765 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1   Z10.0000    F6320 ; move to target
2024-04-04 09:02:59.765 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:02:59.765 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1   Z10.0000    F6320 ; move to target
2024-04-04 09:02:59.766 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:00.262 AbstractHeadMountable DEBUG: N2.moveTo((377.100259, 313.100471, 20.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:00.264 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1   Z20.0000    F6320 ; move to target
2024-04-04 09:03:00.264 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:00.264 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1   Z20.0000    F6320 ; move to target
2024-04-04 09:03:00.265 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:02.313 AbstractHeadMountable DEBUG: N2.moveTo((377.100259, 313.100471, 10.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:02.315 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1   Z10.0000    F6320 ; move to target
2024-04-04 09:03:02.315 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:02.316 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1   Z10.0000    F6320 ; move to target
2024-04-04 09:03:02.316 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:03.973 AbstractHeadMountable DEBUG: N2.moveTo((377.100259, 313.100471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:03.974 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1   Z0.0000    F6320 ; move to target
2024-04-04 09:03:03.974 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:03.974 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1   Z0.0000    F6320 ; move to target
2024-04-04 09:03:03.975 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:07.341 AbstractHeadMountable DEBUG: N2.moveTo((377.100259, 313.100471, 10.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:07.342 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1   Z10.0000    F6320 ; move to target
2024-04-04 09:03:07.343 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:07.343 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1   Z10.0000    F6320 ; move to target
2024-04-04 09:03:07.344 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:10.094 AbstractHeadMountable DEBUG: N2.moveTo((377.100259, 313.100471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:10.096 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1   Z0.0000    F6320 ; move to target
2024-04-04 09:03:10.096 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:10.096 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1   Z0.0000    F6320 ; move to target
2024-04-04 09:03:10.097 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:17.013 ReferenceHead DEBUG: H1.moveToSafeZ(0.86)
2024-04-04 09:03:17.013 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86)
2024-04-04 09:03:17.013 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86)
2024-04-04 09:03:17.013 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86)
2024-04-04 09:03:17.013 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86)
2024-04-04 09:03:17.013 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86)
2024-04-04 09:03:17.014 AbstractHeadMountable DEBUG: N2.moveTo((377.100259, 313.100471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:17.015 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:18.698 ReferenceHead DEBUG: H1.moveToSafeZ(0.86)
2024-04-04 09:03:18.698 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86)
2024-04-04 09:03:18.698 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86)
2024-04-04 09:03:18.698 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86)
2024-04-04 09:03:18.698 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86)
2024-04-04 09:03:18.698 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86)
2024-04-04 09:03:18.699 AbstractHeadMountable DEBUG: N2.moveTo((377.100259, 313.100471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:18.699 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:22.352 AbstractHeadMountable DEBUG: N2.moveTo((377.100259, 313.100471, 10.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:22.355 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1   Z10.0000    F6320 ; move to target
2024-04-04 09:03:22.355 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:22.355 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1   Z10.0000    F6320 ; move to target
2024-04-04 09:03:22.355 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:44.994 AbstractHeadMountable DEBUG: N3.moveTo((347.900259, 288.200471, 10.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:44.995 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1    A10.0000   F6320 ; move to target
2024-04-04 09:03:44.996 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:44.996 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1    A10.0000   F6320 ; move to target
2024-04-04 09:03:44.997 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:48.881 AbstractHeadMountable DEBUG: N3.moveTo((347.900259, 288.200471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:48.882 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1    A0.0000   F6320 ; move to target
2024-04-04 09:03:48.882 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:48.883 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1    A0.0000   F6320 ; move to target
2024-04-04 09:03:48.883 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:50.322 AbstractHeadMountable DEBUG: N3.moveTo((347.900259, 288.200471, -10.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:50.324 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1    A-10.0000   F6320 ; move to target
2024-04-04 09:03:50.324 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:50.324 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1    A-10.0000   F6320 ; move to target
2024-04-04 09:03:50.325 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:53.613 AbstractHeadMountable DEBUG: N3.moveTo((347.900259, 288.200471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:53.614 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1    A0.0000   F6320 ; move to target
2024-04-04 09:03:53.615 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:53.615 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1    A0.0000   F6320 ; move to target
2024-04-04 09:03:53.616 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:03:58.973 ReferenceHead DEBUG: H1.moveToSafeZ(0.86)
2024-04-04 09:03:58.973 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86)
2024-04-04 09:03:58.973 AbstractHeadMountable DEBUG: N1.moveTo((345.600259, 312.600471, -1.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:58.974 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86)
2024-04-04 09:03:58.974 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86)
2024-04-04 09:03:58.974 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86)
2024-04-04 09:03:58.974 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86)
2024-04-04 09:03:58.974 AbstractHeadMountable DEBUG: N3.moveTo((347.900259, 288.200471, 0.000000, 0.000000 mm), 0.86)
2024-04-04 09:03:58.976 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1109 G1   Z1.0000    F5995 ; move to target
2024-04-04 09:03:58.976 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:03:58.976 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1109 G1   Z1.0000    F5995 ; move to target
2024-04-04 09:03:58.977 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:04:20.063 AbstractHeadMountable DEBUG: N3.moveTo((347.900259, 288.200471, 0.000000, -10.000000 mm), 0.86)
2024-04-04 09:04:20.065 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S1479 G1     B-10.0000  F5520 ; move to target
2024-04-04 09:04:20.066 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:04:20.066 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S1479 G1     B-10.0000  F5520 ; move to target
2024-04-04 09:04:20.067 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:04:25.719 AbstractHeadMountable DEBUG: N3.moveTo((357.900259, 288.200471, 0.000000, -10.000000 mm), 0.86)
2024-04-04 09:04:25.732 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S588 G1 X319.3513      F3257 ; move to target
2024-04-04 09:04:25.732 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S588 G1 X319.3513      F3257 ; move to target
2024-04-04 09:04:25.732 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S85 G1 X319.3213      F68 ; move to target
2024-04-04 09:04:25.732 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S85 G1 X319.3213      F68 ; move to target
2024-04-04 09:04:25.732 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:04:25.734 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:04:25.735 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:04:27.357 AbstractHeadMountable DEBUG: N3.moveTo((347.900259, 288.200471, 0.000000, -10.000000 mm), 0.86)
2024-04-04 09:04:27.365 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S587 G1 X309.3513      F3251 ; move to target
2024-04-04 09:04:27.365 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S587 G1 X309.3513      F3251 ; move to target
2024-04-04 09:04:27.365 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S194 G1 X309.3213      F177 ; move to target
2024-04-04 09:04:27.366 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:04:27.366 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S194 G1 X309.3213      F177 ; move to target
2024-04-04 09:04:27.367 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:04:27.369 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:04:28.277 AbstractHeadMountable DEBUG: N3.moveTo((347.900259, 278.200471, 0.000000, -10.000000 mm), 0.86)
2024-04-04 09:04:28.285 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S579 G1  Y280.3430     F3163 ; move to target
2024-04-04 09:04:28.286 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S18 G1  Y279.8905     F120 ; move to target
2024-04-04 09:04:28.286 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S579 G1  Y280.3430     F3163 ; move to target
2024-04-04 09:04:28.286 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:04:28.286 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S18 G1  Y279.8905     F120 ; move to target
2024-04-04 09:04:28.288 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:04:28.289 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:04:29.045 AbstractHeadMountable DEBUG: N3.moveTo((347.900259, 288.200471, 0.000000, -10.000000 mm), 0.86)
2024-04-04 09:04:29.053 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S579 G1  Y289.4913     F3163 ; move to target
2024-04-04 09:04:29.054 GcodeAsyncDriver DEBUG: [Smoothie:COM5] commandQueue offer >> M204 S18 G1  Y289.9438     F120 ; move to target
2024-04-04 09:04:29.054 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S579 G1  Y289.4913     F3163 ; move to target
2024-04-04 09:04:29.054 AbstractMachine TRACE: Machine entering idle state.
2024-04-04 09:04:29.054 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM5] >> M204 S18 G1  Y289.9438     F120 ; move to target
2024-04-04 09:04:29.055 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok
2024-04-04 09:04:29.057 GcodeDriver$ReaderThread TRACE: [Smoothie:COM5] << ok