2024-04-03 08:20:12.077 Main INFO: Bienvenue, Bienvenido, Willkommen, Hello, Namaskar, Welkom, Bonjour to OpenPnP version 2024-03-27_09-59-31.0b0aca0. 2024-04-03 08:20:12.079 Scripting TRACE: Scripting.on Startup 2024-04-03 08:20:13.636 AbstractBroadcastingCamera TRACE: Camera DOWN thread 49 started. 2024-04-03 08:20:15.701 AbstractBroadcastingCamera TRACE: Camera Up_Logitech thread 51 started. 2024-04-03 08:20:17.724 ReferenceMachine DEBUG: setEnabled(true) 2024-04-03 08:20:17.726 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-03 08:20:17.761 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-03 08:20:32.763 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G21 ; 2024-04-03 08:20:32.763 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-03 08:20:32.764 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:32.764 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G21 ; 2024-04-03 08:20:32.776 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M82 ; Set absolute mode for extruder 2024-04-03 08:20:32.776 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-03 08:20:32.776 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G90 ; Set absolute positioning mode 2024-04-03 08:20:32.776 GcodeDriver DEBUG: [Schultz:COM3] Connect 2024-04-03 08:20:32.777 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M82 ; Set absolute mode for extruder 2024-04-03 08:20:32.777 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:32.777 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:32.789 GcodeDriver TRACE: Schultz waiting for connection 3000ms 2024-04-03 08:20:32.849 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller starting... 2024-04-03 08:20:32.851 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller ready. 2024-04-03 08:20:35.789 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G21 ; Set millimeters mode 2024-04-03 08:20:35.789 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-03 08:20:35.789 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-03 08:20:35.789 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G21 ; Set millimeters mode 2024-04-03 08:20:35.792 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G21 ; Set millimeters mode 2024-04-03 08:20:35.795 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-03 08:20:35.796 GcodeDriver TRACE: [Schultz:COM3] confirmed G21 ; Set millimeters mode 2024-04-03 08:20:35.796 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G90 ; Set absolute positioning mode 2024-04-03 08:20:35.800 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G90 ; Set absolute positioning mode 2024-04-03 08:20:35.803 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-03 08:20:35.807 AbstractMachine TRACE: Machine entering idle state. 2024-04-03 08:20:38.799 ReferenceMachine DEBUG: homing machine 2024-04-03 08:20:38.800 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1000 ; set a low acceleration 2024-04-03 08:20:38.801 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 A0 Z0 2024-04-03 08:20:38.801 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-03 08:20:38.801 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1000 ; set a low acceleration 2024-04-03 08:20:38.801 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 X88 Y42 B0 C0; Home all axes 2024-04-03 08:20:38.801 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-03 08:20:38.801 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 A0 Z0 2024-04-03 08:20:38.801 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-03 08:20:38.801 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-03 08:20:38.801 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:38.801 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-03 08:20:38.802 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 X88 Y42 B0 C0; Home all axes 2024-04-03 08:20:38.802 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-03 08:20:38.802 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-03 08:20:38.802 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-03 08:20:38.802 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-03 08:20:38.802 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G28 ; Home all axes 2024-04-03 08:20:38.803 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-03 08:20:38.803 GcodeDriver TRACE: [Schultz:COM3] confirmed G90 ; Set absolute positioning mode 2024-04-03 08:20:38.803 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:38.803 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G28 ; Home all axes 2024-04-03 08:20:38.803 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-03 08:20:38.803 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:38.803 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-03 08:20:38.805 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G28 ; Home all axes 2024-04-03 08:20:38.808 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-03 08:20:39.881 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:39.881 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:46.519 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:46.519 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:46.520 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:46.520 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:46.520 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:46.521 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-03 08:20:46.522 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-03 08:20:46.522 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-03 08:20:46.522 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-03 08:20:46.522 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:46.523 GcodeDriver TRACE: [Schultz:COM3] confirmed G28 ; Home all axes 2024-04-03 08:20:46.523 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:46.528 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << M400 ; Wait for moves to complete before returning 2024-04-03 08:20:46.531 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-03 08:20:46.531 GcodeDriver TRACE: [Schultz:COM3] confirmed M400 ; Wait for moves to complete before returning 2024-04-03 08:20:46.531 GcodeAsyncDriver TRACE: Schultz waited 8ms to drain command queue. 2024-04-03 08:20:46.531 GcodeAsyncDriver TRACE: Schultz confirmation complete. 2024-04-03 08:20:46.532 ReferenceHead DEBUG: H1.home() 2024-04-03 08:20:46.533 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (110.000000, 43.500000, -25.000000, 0.000000 mm) 2024-04-03 08:20:46.533 ReferenceHead DEBUG: H1.moveToSafeZ(0.86) 2024-04-03 08:20:46.534 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86) 2024-04-03 08:20:46.534 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86) 2024-04-03 08:20:46.534 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86) 2024-04-03 08:20:46.535 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86) 2024-04-03 08:20:46.535 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86) 2024-04-03 08:20:46.535 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, 0.000000, 0.000000 mm), 0.86) 2024-04-03 08:20:46.564 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, -25.000000, 0.000000 mm), 0.86) 2024-04-03 08:20:46.571 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2387 G1 X110.0000 Y43.6265 F22548 ; move to target 2024-04-03 08:20:46.572 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2387 G1 X110.0000 Y43.6265 F22548 ; move to target 2024-04-03 08:20:46.573 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:46.573 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y43.5000 F6000 ; move to target 2024-04-03 08:20:46.573 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y43.5000 F6000 ; move to target 2024-04-03 08:20:46.574 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:46.574 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-03 08:20:46.574 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:46.574 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:46.575 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-03 08:20:47.090 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.090 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:110.0000 Y:43.5000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-03 08:20:47.090 GcodeDriver TRACE: Position report: ok C: X:110.0000 Y:43.5000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-03 08:20:47.091 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:110.000000, y:43.500000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-03 08:20:47.091 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-03 08:20:47.092 ReferenceActuator DEBUG: UpCamLight.actuate(false) 2024-04-03 08:20:47.092 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M811 ; turn off Light 2024-04-03 08:20:47.092 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-03 08:20:47.093 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M811 ; turn off Light 2024-04-03 08:20:47.093 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.093 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.103 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.109 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.127 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.136 AbstractSettlingCamera TRACE: autoSettleAndCapture t=40 auto settle score: 84.314 compute time: 6 2024-04-03 08:20:47.137 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.161 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.169 AbstractSettlingCamera TRACE: autoSettleAndCapture t=75 auto settle score: 80.000 compute time: 7 2024-04-03 08:20:47.169 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.197 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.203 AbstractSettlingCamera TRACE: autoSettleAndCapture t=109 auto settle score: 54.510 compute time: 5 2024-04-03 08:20:47.203 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 110 ms 2024-04-03 08:20:47.204 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-03 08:20:47.240 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-03 08:20:47.296 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.145718, 45.095754, -25.000000, 0.000000 mm) 2024-04-03 08:20:47.296 AbstractHeadMountable DEBUG: DOWN.moveTo((112.145718, 45.095754, -25.000000, 0.000000 mm), 0.86) 2024-04-03 08:20:47.312 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-03 08:20:47.314 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1950 G1 X112.1457 Y45.2222 F6000 ; move to target 2024-04-03 08:20:47.315 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1950 G1 X112.1457 Y45.2222 F6000 ; move to target 2024-04-03 08:20:47.316 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y45.0958 F6000 ; move to target 2024-04-03 08:20:47.316 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.316 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y45.0958 F6000 ; move to target 2024-04-03 08:20:47.316 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:47.317 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:47.317 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-03 08:20:47.317 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-03 08:20:47.318 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.437 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.438 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:112.1457 Y:45.0958 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-03 08:20:47.438 GcodeDriver TRACE: Position report: ok C: X:112.1457 Y:45.0958 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-03 08:20:47.438 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:112.145700, y:45.095800, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-03 08:20:47.438 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-03 08:20:47.438 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.451 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.456 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.495 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.506 AbstractSettlingCamera TRACE: autoSettleAndCapture t=67 auto settle score: 85.490 compute time: 9 2024-04-03 08:20:47.507 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.518 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.524 AbstractSettlingCamera TRACE: autoSettleAndCapture t=86 auto settle score: 85.098 compute time: 5 2024-04-03 08:20:47.524 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.536 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.541 AbstractSettlingCamera TRACE: autoSettleAndCapture t=103 auto settle score: 67.843 compute time: 4 2024-04-03 08:20:47.542 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 103 ms 2024-04-03 08:20:47.542 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-03 08:20:47.569 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-03 08:20:47.610 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.028679, 45.290358, -25.000000, 0.000000 mm) 2024-04-03 08:20:47.610 AbstractHeadMountable DEBUG: DOWN.moveTo((112.028679, 45.290358, -25.000000, 0.000000 mm), 0.86) 2024-04-03 08:20:47.622 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-03 08:20:47.624 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1684 G1 X112.0287 Y45.4169 F6000 ; move to target 2024-04-03 08:20:47.624 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1684 G1 X112.0287 Y45.4169 F6000 ; move to target 2024-04-03 08:20:47.625 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y45.2904 F6000 ; move to target 2024-04-03 08:20:47.625 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.625 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y45.2904 F6000 ; move to target 2024-04-03 08:20:47.625 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:47.626 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:47.626 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-03 08:20:47.626 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-03 08:20:47.626 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.704 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.705 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:112.0287 Y:45.2904 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-03 08:20:47.705 GcodeDriver TRACE: Position report: ok C: X:112.0287 Y:45.2904 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-03 08:20:47.705 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:112.028700, y:45.290400, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-03 08:20:47.705 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-03 08:20:47.705 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.717 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.720 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.736 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.743 AbstractSettlingCamera TRACE: autoSettleAndCapture t=36 auto settle score: 74.118 compute time: 5 2024-04-03 08:20:47.743 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.772 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.778 AbstractSettlingCamera TRACE: autoSettleAndCapture t=71 auto settle score: 45.098 compute time: 5 2024-04-03 08:20:47.779 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-03 08:20:47.804 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-03 08:20:47.810 AbstractSettlingCamera TRACE: autoSettleAndCapture t=103 auto settle score: 49.412 compute time: 5 2024-04-03 08:20:47.810 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 104 ms 2024-04-03 08:20:47.811 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-03 08:20:47.845 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-03 08:20:47.887 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (111.950653, 44.978991, -25.000000, 0.000000 mm) 2024-04-03 08:20:47.887 AbstractHeadMountable DEBUG: DOWN.moveTo((111.950653, 44.978991, -25.000000, 0.000000 mm), 0.86) 2024-04-03 08:20:47.896 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (111.989666, 45.134675, -25.000000, 0.000000 mm) 2024-04-03 08:20:47.897 AbstractHeadMountable DEBUG: DOWN.moveTo((111.989666, 45.134675, -25.000000, 0.000000 mm), 0.86) 2024-04-03 08:20:47.905 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1296 G1 X111.9507 Y45.1055 F6000 ; move to target 2024-04-03 08:20:47.905 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1296 G1 X111.9507 Y45.1055 F6000 ; move to target 2024-04-03 08:20:47.906 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y44.9790 F6000 ; move to target 2024-04-03 08:20:47.906 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y44.9790 F6000 ; move to target 2024-04-03 08:20:47.906 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.906 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1867 G1 X111.9897 Y45.2612 F6000 ; move to target 2024-04-03 08:20:47.906 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1867 G1 X111.9897 Y45.2612 F6000 ; move to target 2024-04-03 08:20:47.907 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y45.1347 F6000 ; move to target 2024-04-03 08:20:47.907 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y45.1347 F6000 ; move to target 2024-04-03 08:20:47.907 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.907 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0.0000 Y0.0000 ; reset coordinates 2024-04-03 08:20:47.908 ReferenceNozzle DEBUG: N1.home() 2024-04-03 08:20:47.908 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0.0000 Y0.0000 ; reset coordinates 2024-04-03 08:20:47.908 ReferenceNozzle DEBUG: N2.home() 2024-04-03 08:20:47.908 ReferenceNozzle DEBUG: N3.home() 2024-04-03 08:20:47.908 ReferenceNozzle DEBUG: N4.home() 2024-04-03 08:20:47.908 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.909 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:47.910 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:48.412 Scripting TRACE: Scripting.on Machine.AfterHoming 2024-04-03 08:20:48.412 ReferenceMachine INFO: setHomed(true) 2024-04-03 08:20:48.413 AbstractMachine TRACE: Machine entering idle state. 2024-04-03 08:20:59.976 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:59.976 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-03 08:20:59.976 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-03 08:20:59.977 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-03 08:20:59.977 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:20:59.978 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-03 08:20:59.978 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-03 08:20:59.978 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-03 08:20:59.979 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-03 08:20:59.979 ReferenceActuator DEBUG: Vac_PUMP.actuate(false) 2024-04-03 08:20:59.979 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M809 ; turn off pump 2024-04-03 08:20:59.980 AbstractMachine TRACE: Machine entering idle state. 2024-04-03 08:20:59.980 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M809 ; turn off pump 2024-04-03 08:20:59.980 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:21:07.592 ReferenceActuator DEBUG: Vac_PUMP.actuate(true) 2024-04-03 08:21:07.592 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M808 ; turn on pump 2024-04-03 08:21:07.593 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M808 ; turn on pump 2024-04-03 08:21:07.593 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:21:07.594 AbstractMachine TRACE: Machine entering idle state. 2024-04-03 08:21:36.159 AbstractHeadMountable DEBUG: DOWN.moveTo((100.000000, 0.000000, -25.000000, 0.000000 mm), 0.86) 2024-04-03 08:21:36.162 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X100.0000 F19984 ; move to target 2024-04-03 08:21:36.162 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X100.0000 F19984 ; move to target 2024-04-03 08:21:36.162 AbstractMachine TRACE: Machine entering idle state. 2024-04-03 08:21:36.163 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:21:36.989 AbstractHeadMountable DEBUG: DOWN.moveTo((200.000000, 0.000000, -25.000000, 0.000000 mm), 0.86) 2024-04-03 08:21:36.991 MachineControlsPanel DEBUG: DOWN exceeded roaming distance at non-safe Z, going to safe Z. Last user action at (100.000000, 0.000000, -25.000000, 0.000000 mm) roamed to (200.000000, 0.000000, -25.000000, 0.000000 mm) distance 100.000mmmm. 2024-04-03 08:21:36.993 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X200.0000 F19984 ; move to target 2024-04-03 08:21:36.993 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X200.0000 F19984 ; move to target 2024-04-03 08:21:36.993 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86) 2024-04-03 08:21:36.993 AbstractHeadMountable DEBUG: DOWN.moveTo((200.000000, 0.000000, 0.000000, 0.000000 mm), 0.86) 2024-04-03 08:21:36.994 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:21:36.994 AbstractMachine TRACE: Machine entering idle state. 2024-04-03 08:21:37.759 AbstractHeadMountable DEBUG: DOWN.moveTo((300.000000, 0.000000, 0.000000, 0.000000 mm), 0.86) 2024-04-03 08:21:37.762 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X300.0000 F19984 ; move to target 2024-04-03 08:21:37.762 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X300.0000 F19984 ; move to target 2024-04-03 08:21:37.763 AbstractMachine TRACE: Machine entering idle state. 2024-04-03 08:21:37.763 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:22:19.989 AbstractNozzle TRACE: Set rotation mode offset: none. 2024-04-03 08:22:19.990 Scripting TRACE: Scripting.on NozzleCalibration.Starting 2024-04-03 08:22:19.990 ReferenceHead DEBUG: H1.moveToSafeZ(0.86) 2024-04-03 08:22:19.990 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86) 2024-04-03 08:22:19.991 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86) 2024-04-03 08:22:19.991 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86) 2024-04-03 08:22:19.991 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86) 2024-04-03 08:22:19.991 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86) 2024-04-03 08:22:19.991 AbstractHeadMountable DEBUG: N1.moveTo((140.713000, 18.484000, -1.000000, -180.000000 mm), 0.86) 2024-04-03 08:22:19.995 AbstractHeadMountable DEBUG: N1.moveTo((140.713000, 18.484000, -24.850000, -180.000000 mm), 0.86) 2024-04-03 08:22:19.997 ReferenceNozzleTipCalibration DEBUG: [nozzleTipCalibration]starting measurement; angleStart: -180.0, angleStop: 180.0, angleIncrement: 60.0, angleSubdivisions: 5 2024-04-03 08:22:19.998 ReferenceNozzleTipCalibration DEBUG: [nozzleTipCalibration]i: 0, measureAngle: -180.0 2024-04-03 08:22:19.998 AbstractHeadMountable DEBUG: N1.moveTo((140.713000, 18.484000, -24.850000, -180.000000 mm), 0.86) 2024-04-03 08:22:19.999 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S3016 G1 X104.4340 Y-4.0995 Z1.0000 B-180.00 F37988 ; move to target 2024-04-03 08:22:19.999 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S3016 G1 X104.4340 Y-4.0995 Z1.0000 B-180.00 F37988 ; move to target 2024-04-03 08:22:19.999 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y-4.2260 F6000 ; move to target 2024-04-03 08:22:20.000 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y-4.2260 F6000 ; move to target 2024-04-03 08:22:20.000 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Z24.8500 F8909 ; move to target 2024-04-03 08:22:20.000 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-03 08:22:20.000 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Z24.8500 F8909 ; move to target 2024-04-03 08:22:20.000 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-03 08:22:20.001 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-03 08:22:20.001 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:22:20.001 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-03 08:22:20.002 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-03 08:22:20.003 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok