2021-10-13 20:47:02.869 Main INFO: Bienvenue, Bienvenido, Willkommen, Hello, Namaskar, Welkom, Bonjour to OpenPnP version 2021-10-12_20-18-26.dcf5ca3.
2021-10-13 20:47:02.869 Scripting TRACE: Scripting.on Startup
2021-10-13 20:47:04.401 AbstractBroadcastingCamera TRACE: Camera DOWN thread 26 started.
2021-10-13 20:47:05.363 AbstractBroadcastingCamera TRACE: Camera Up_Logitech thread 28 started.
2021-10-13 20:47:05.405 MessageBoxes DEBUG: Error: java.util.regex.PatternSyntaxException: Named capturing group <A> is already defined near index 87
^.*X:(?<X>-?\d+\.\d+) Y:(?<Y>-?\d+\.\d+) Z:(?<Z>-?\d+\.\d+) .*A:(?<A>-?\d+\.\d+) A:(?<A>-?\d+\.\d+) B:(?<B>-?\d+\.\d+) B:(?<B>-?\d+\.\d+) C:(?<C>-?\d+\.\d+).*
                                                                                       ^
2021-10-13 20:47:28.630 ReferenceMachine DEBUG: setEnabled(true)
2021-10-13 20:47:36.704 GcodeDriver DEBUG: [serial://COM5] >> G21 ; Set millimeters mode, 25000
2021-10-13 20:47:36.704 GcodeDriver$ReaderThread TRACE: [serial://COM5] << Smoothie
2021-10-13 20:47:36.705 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:36.706 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:36.706 GcodeDriver TRACE: [serial://COM5] confirmed G21 ; Set millimeters mode
2021-10-13 20:47:36.706 GcodeDriver DEBUG: [serial://COM5] >> G90 ; Set absolute positioning mode, 25000
2021-10-13 20:47:36.707 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:36.709 GcodeDriver TRACE: [serial://COM5] confirmed G90 ; Set absolute positioning mode
2021-10-13 20:47:36.709 GcodeDriver DEBUG: [serial://COM5] >> M82 ; Set absolute mode for extruder, 25000
2021-10-13 20:47:36.710 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:36.711 GcodeDriver TRACE: [serial://COM5] confirmed M82 ; Set absolute mode for extruder
2021-10-13 20:47:39.721 GcodeDriver DEBUG: [serial://COM6] >> G21 ; Set millimeters mode, 5000
2021-10-13 20:47:39.736 GcodeDriver$ReaderThread TRACE: [serial://COM6] << Controller starting...
2021-10-13 20:47:39.737 GcodeDriver$ReaderThread TRACE: [serial://COM6] << Controller ready.
2021-10-13 20:47:39.740 GcodeDriver$ReaderThread TRACE: [serial://COM6] << G21 ; Set millimeters mode
2021-10-13 20:47:39.741 GcodeDriver$ReaderThread TRACE: [serial://COM6] << ok unknown or empty command ignored
2021-10-13 20:47:39.742 GcodeDriver TRACE: [serial://COM6] confirmed G21 ; Set millimeters mode
2021-10-13 20:47:39.743 GcodeDriver DEBUG: [serial://COM6] >> G90 ; Set absolute positioning mode, 5000
2021-10-13 20:47:39.747 GcodeDriver$ReaderThread TRACE: [serial://COM6] << G90 ; Set absolute positioning mode
2021-10-13 20:47:39.754 GcodeDriver$ReaderThread TRACE: [serial://COM6] << ok unknown or empty command ignored
2021-10-13 20:47:39.754 GcodeDriver TRACE: [serial://COM6] confirmed G90 ; Set absolute positioning mode
2021-10-13 20:47:42.371 ReferenceMachine DEBUG: homing machine
2021-10-13 20:47:42.373 GcodeDriver DEBUG: [serial://COM5] >> M204 S1000 ;  set a low acceleration, -1
2021-10-13 20:47:42.375 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:42.375 GcodeDriver TRACE: [serial://COM5] confirmed M204 S1000 ;  set a low acceleration
2021-10-13 20:47:42.375 GcodeDriver DEBUG: [serial://COM5] >> G28 A0 Z0, -1
2021-10-13 20:47:43.798 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:43.798 GcodeDriver TRACE: [serial://COM5] confirmed G28 A0 Z0
2021-10-13 20:47:43.798 GcodeDriver DEBUG: [serial://COM5] >> M400, -1
2021-10-13 20:47:43.799 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:43.800 GcodeDriver TRACE: [serial://COM5] confirmed M400
2021-10-13 20:47:43.800 GcodeDriver DEBUG: [serial://COM5] >> G28 X0 Y0 B0 C0; Home all axes, -1
2021-10-13 20:47:52.515 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:52.515 GcodeDriver TRACE: [serial://COM5] confirmed G28 X0 Y0 B0 C0; Home all axes
2021-10-13 20:47:52.516 GcodeDriver DEBUG: [serial://COM5] >> M400, -1
2021-10-13 20:47:52.517 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:52.517 GcodeDriver TRACE: [serial://COM5] confirmed M400
2021-10-13 20:47:52.519 GcodeDriver DEBUG: [serial://COM5] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero., -1
2021-10-13 20:47:52.520 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:52.520 GcodeDriver TRACE: [serial://COM5] confirmed G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero.
2021-10-13 20:47:52.520 GcodeDriver DEBUG: [serial://COM5] >> M400, -1
2021-10-13 20:47:52.521 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:52.522 GcodeDriver TRACE: [serial://COM5] confirmed M400
2021-10-13 20:47:52.531 ReferenceHead DEBUG: H1.home()
2021-10-13 20:47:52.531 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (93.241000, 44.100000, 0.000000, 0.000000 mm)
2021-10-13 20:47:52.532 ReferenceHead DEBUG: H1.moveToSafeZ(0.5)
2021-10-13 20:47:52.533 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.5)
2021-10-13 20:47:52.533 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.5)
2021-10-13 20:47:52.534 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.5)
2021-10-13 20:47:52.534 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.5)
2021-10-13 20:47:52.535 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.5)
2021-10-13 20:47:52.535 AbstractHeadMountable DEBUG: DOWN.moveTo((93.241000, 44.100000, 0.000000, 0.000000 mm), 0.5)
2021-10-13 20:47:52.580 GcodeDriver DEBUG: [serial://COM5] >> G1 X93.2410 Y44.2000 F12416, 25000
2021-10-13 20:47:52.582 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:52.582 GcodeDriver TRACE: [serial://COM5] confirmed G1 X93.2410 Y44.2000 F12416
2021-10-13 20:47:52.582 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:47:53.290 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:53.290 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:47:53.294 GcodeDriver DEBUG: [serial://COM5] >> G1  Y44.1000 F367, 25000
2021-10-13 20:47:53.297 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:53.298 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y44.1000 F367
2021-10-13 20:47:53.298 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:47:53.321 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:53.321 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:47:53.324 AbstractHeadMountable DEBUG: DOWN.moveTo((93.241000, 44.100000, 0.000000, 0.000000 mm), 0.5)
2021-10-13 20:47:53.326 Scripting TRACE: Scripting.on Camera.BeforeSettle
2021-10-13 20:47:53.583 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-10-13 20:47:53.601 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-10-13 20:47:53.602 Scripting TRACE: Scripting.on Camera.AfterSettle
2021-10-13 20:47:53.652 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.1) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1557: 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)
]
2021-10-13 20:47:53.785 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.849876, 43.139890, 0.000000, 0.000000 mm)
2021-10-13 20:47:53.787 AbstractHeadMountable DEBUG: DOWN.moveTo((92.849876, 43.139890, 0.000000, 0.000000 mm), 0.5)
2021-10-13 20:47:53.822 GcodeDriver DEBUG: [serial://COM5] >> G1 X92.8499 Y43.2399 F1184, 25000
2021-10-13 20:47:53.824 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:53.825 GcodeDriver TRACE: [serial://COM5] confirmed G1 X92.8499 Y43.2399 F1184
2021-10-13 20:47:53.825 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:47:53.894 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:53.895 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:47:53.897 GcodeDriver DEBUG: [serial://COM5] >> G1  Y43.1399 F367, 25000
2021-10-13 20:47:53.910 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:53.911 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y43.1399 F367
2021-10-13 20:47:53.911 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:47:53.935 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:53.936 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:47:53.938 Scripting TRACE: Scripting.on Camera.BeforeSettle
2021-10-13 20:47:54.190 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-10-13 20:47:54.207 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-10-13 20:47:54.208 Scripting TRACE: Scripting.on Camera.AfterSettle
2021-10-13 20:47:54.313 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.1) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1557: 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)
]
2021-10-13 20:47:54.464 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (93.110625, 43.283907, 0.000000, 0.000000 mm)
2021-10-13 20:47:54.464 AbstractHeadMountable DEBUG: DOWN.moveTo((93.110625, 43.283907, 0.000000, 0.000000 mm), 0.5)
2021-10-13 20:47:54.486 GcodeDriver DEBUG: [serial://COM5] >> G1 X93.1106 Y43.3839 F813, 25000
2021-10-13 20:47:54.489 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:54.489 GcodeDriver TRACE: [serial://COM5] confirmed G1 X93.1106 Y43.3839 F813
2021-10-13 20:47:54.489 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:47:54.530 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:54.531 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:47:54.532 GcodeDriver DEBUG: [serial://COM5] >> G1  Y43.2839 F367, 25000
2021-10-13 20:47:54.533 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:54.533 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y43.2839 F367
2021-10-13 20:47:54.534 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:47:54.557 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:54.558 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:47:54.560 Scripting TRACE: Scripting.on Camera.BeforeSettle
2021-10-13 20:47:54.814 Scripting TRACE: Scripting.on Camera.BeforeCapture
2021-10-13 20:47:54.830 Scripting TRACE: Scripting.on Camera.AfterCapture
2021-10-13 20:47:54.833 Scripting TRACE: Scripting.on Camera.AfterSettle
2021-10-13 20:47:54.884 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.1) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1557: 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)
]
2021-10-13 20:47:55.347 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (92.980251, 43.283907, 0.000000, 0.000000 mm)
2021-10-13 20:47:55.349 AbstractHeadMountable DEBUG: DOWN.moveTo((92.980251, 43.283907, 0.000000, 0.000000 mm), 0.5)
2021-10-13 20:47:55.355 GcodeDriver DEBUG: [serial://COM5] >> G1 X92.9803  F420, 25000
2021-10-13 20:47:55.357 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:55.357 GcodeDriver TRACE: [serial://COM5] confirmed G1 X92.9803  F420
2021-10-13 20:47:55.357 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:47:55.384 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:55.385 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:47:55.387 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (93.045438, 43.283907, 0.000000, 0.000000 mm)
2021-10-13 20:47:55.388 AbstractHeadMountable DEBUG: DOWN.moveTo((93.045438, 43.283907, 0.000000, 0.000000 mm), 0.5)
2021-10-13 20:47:55.391 GcodeDriver DEBUG: [serial://COM5] >> G1 X93.0454  F297, 25000
2021-10-13 20:47:55.395 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:55.395 GcodeDriver TRACE: [serial://COM5] confirmed G1 X93.0454  F297
2021-10-13 20:47:55.396 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:47:55.415 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:55.417 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:47:55.420 GcodeDriver DEBUG: [serial://COM5] >> G92X0Y0, -1
2021-10-13 20:47:55.422 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:47:55.422 GcodeDriver TRACE: [serial://COM5] confirmed G92X0Y0
2021-10-13 20:47:55.422 ReferenceNozzle DEBUG: N1.home()
2021-10-13 20:47:55.423 ReferenceNozzle DEBUG: N1.home() nozzle tip N1-502 calibration neeeded
2021-10-13 20:47:55.423 ReferenceNozzle DEBUG: N1.home() nozzle tip N2-502 calibration reset
2021-10-13 20:47:55.423 ReferenceNozzle DEBUG: N1.home() nozzle tip N3-502 calibration reset
2021-10-13 20:47:55.423 ReferenceNozzle DEBUG: N2.home()
2021-10-13 20:47:55.423 ReferenceNozzle DEBUG: N2.home() nozzle tip N2-502 calibration neeeded
2021-10-13 20:47:55.423 ReferenceNozzle DEBUG: N2.home() nozzle tip N3-502 calibration reset
2021-10-13 20:47:55.424 ReferenceNozzle DEBUG: N2.home() nozzle tip N4-502 calibration reset
2021-10-13 20:47:55.424 ReferenceNozzle DEBUG: N3.home()
2021-10-13 20:47:55.424 ReferenceNozzle DEBUG: N3.home() nozzle tip N1-502 calibration reset
2021-10-13 20:47:55.424 ReferenceNozzle DEBUG: N3.home() nozzle tip N2-502 calibration reset
2021-10-13 20:47:55.424 ReferenceNozzle DEBUG: N3.home() nozzle tip N3-502 calibration neeeded
2021-10-13 20:47:55.424 ReferenceNozzle DEBUG: N4.home()
2021-10-13 20:47:55.424 ReferenceNozzle DEBUG: N4.home() nozzle tip N1-502 calibration reset
2021-10-13 20:47:55.436 ReferenceNozzle DEBUG: N4.home() nozzle tip N2-502 calibration reset
2021-10-13 20:47:55.437 ReferenceNozzle DEBUG: N4.home() nozzle tip N3-502 calibration reset
2021-10-13 20:47:55.437 ReferenceNozzle DEBUG: N4.home() nozzle tip N4-502 calibration neeeded
2021-10-13 20:47:55.437 Scripting TRACE: Scripting.on Machine.AfterHoming
2021-10-13 20:47:55.438 ReferenceMachine INFO: setHomed(true)
2021-10-13 20:48:04.764 GcodeDriver DEBUG: [serial://COM5] >> M115, 25000
2021-10-13 20:48:04.770 GcodeDriver$ReaderThread TRACE: [serial://COM5] << FIRMWARE_NAME:Smoothieware, FIRMWARE_URL:http%3A//smoothieware.org, X-SOURCE_CODE_URL:https://github.com/Smoothieware/Smoothieware, FIRMWARE_VERSION:feature/best-for-pnp-fc4476a0, X-FIRMWARE_BUILD_DATE:Aug 14 2020 20:14:27, X-SYSTEM_CLOCK:120MHz, X-AXES:6, X-GRBL_MODE:0, X-CNC:0, X-MSD:1
2021-10-13 20:48:04.771 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:48:04.771 GcodeDriver TRACE: [serial://COM5] confirmed M115
2021-10-13 20:48:04.773 GcodeDriver DEBUG: [serial://COM5] >> M114, 25000
2021-10-13 20:48:04.775 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok C: X:0.0000 Y:0.0000 Z:0.0000
2021-10-13 20:48:04.775 GcodeDriver TRACE: [serial://COM5] confirmed M114
2021-10-13 20:48:04.777 MessageBoxes DEBUG: Error: java.util.regex.PatternSyntaxException: Named capturing group <A> is already defined near index 87
^.*X:(?<X>-?\d+\.\d+) Y:(?<Y>-?\d+\.\d+) Z:(?<Z>-?\d+\.\d+) .*A:(?<A>-?\d+\.\d+) A:(?<A>-?\d+\.\d+) B:(?<B>-?\d+\.\d+) B:(?<B>-?\d+\.\d+) C:(?<C>-?\d+\.\d+).*
                                                                                       ^
2021-10-13 20:48:16.361 GcodeDriver DEBUG: [serial://COM5] >> M115, 25000
2021-10-13 20:48:16.367 GcodeDriver$ReaderThread TRACE: [serial://COM5] << FIRMWARE_NAME:Smoothieware, FIRMWARE_URL:http%3A//smoothieware.org, X-SOURCE_CODE_URL:https://github.com/Smoothieware/Smoothieware, FIRMWARE_VERSION:feature/best-for-pnp-fc4476a0, X-FIRMWARE_BUILD_DATE:Aug 14 2020 20:14:27, X-SYSTEM_CLOCK:120MHz, X-AXES:6, X-GRBL_MODE:0, X-CNC:0, X-MSD:1
2021-10-13 20:48:16.367 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:48:16.368 GcodeDriver TRACE: [serial://COM5] confirmed M115
2021-10-13 20:48:16.368 GcodeDriver DEBUG: [serial://COM5] >> M114, 25000
2021-10-13 20:48:16.369 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok C: X:0.0000 Y:0.0000 Z:0.0000
2021-10-13 20:48:16.370 GcodeDriver TRACE: [serial://COM5] confirmed M114
2021-10-13 20:48:16.372 MessageBoxes DEBUG: Error: java.util.regex.PatternSyntaxException: Named capturing group <A> is already defined near index 87
^.*X:(?<X>-?\d+\.\d+) Y:(?<Y>-?\d+\.\d+) Z:(?<Z>-?\d+\.\d+) .*A:(?<A>-?\d+\.\d+) A:(?<A>-?\d+\.\d+) B:(?<B>-?\d+\.\d+) B:(?<B>-?\d+\.\d+) C:(?<C>-?\d+\.\d+).*
                                                                                       ^
2021-10-13 20:48:16.430 GcodeDriver DEBUG: [serial://COM5] >> M115, 25000
2021-10-13 20:48:16.437 GcodeDriver$ReaderThread TRACE: [serial://COM5] << FIRMWARE_NAME:Smoothieware, FIRMWARE_URL:http%3A//smoothieware.org, X-SOURCE_CODE_URL:https://github.com/Smoothieware/Smoothieware, FIRMWARE_VERSION:feature/best-for-pnp-fc4476a0, X-FIRMWARE_BUILD_DATE:Aug 14 2020 20:14:27, X-SYSTEM_CLOCK:120MHz, X-AXES:6, X-GRBL_MODE:0, X-CNC:0, X-MSD:1
2021-10-13 20:48:16.437 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:48:16.438 GcodeDriver TRACE: [serial://COM5] confirmed M115
2021-10-13 20:48:16.439 GcodeDriver DEBUG: [serial://COM5] >> M114, 25000
2021-10-13 20:48:16.444 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok C: X:0.0000 Y:0.0000 Z:0.0000
2021-10-13 20:48:16.444 GcodeDriver TRACE: [serial://COM5] confirmed M114
2021-10-13 20:48:16.446 MessageBoxes DEBUG: Error: java.util.regex.PatternSyntaxException: Named capturing group <A> is already defined near index 87
^.*X:(?<X>-?\d+\.\d+) Y:(?<Y>-?\d+\.\d+) Z:(?<Z>-?\d+\.\d+) .*A:(?<A>-?\d+\.\d+) A:(?<A>-?\d+\.\d+) B:(?<B>-?\d+\.\d+) B:(?<B>-?\d+\.\d+) C:(?<C>-?\d+\.\d+).*
                                                                                       ^
2021-10-13 20:48:46.978 AbstractMachine TRACE: Exception caught, executing pending motion: org.openpnp.spi.JobProcessor$JobProcessorException: No compatible, enabled feeder found for part R0805-1K
	at org.openpnp.spi.base.AbstractPnpJobProcessor.findFeeder(AbstractPnpJobProcessor.java:50)
	at org.openpnp.machine.reference.ReferencePnpJobProcessor$PreFlight.checkJobPlacement(ReferencePnpJobProcessor.java:241)
	at org.openpnp.machine.reference.ReferencePnpJobProcessor$PreFlight.checkSetupErrors(ReferencePnpJobProcessor.java:212)
	at org.openpnp.machine.reference.ReferencePnpJobProcessor$PreFlight.step(ReferencePnpJobProcessor.java:168)
	at org.openpnp.machine.reference.ReferencePnpJobProcessor.next(ReferencePnpJobProcessor.java:113)
	at org.openpnp.gui.JobPanel.lambda$jobRun$2(JobPanel.java:903)
	at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571)
	at java.util.concurrent.FutureTask.run(Unknown Source)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
	at java.lang.Thread.run(Unknown Source)
2021-10-13 20:48:47.071 MessageBoxes DEBUG: Job Error: No compatible, enabled feeder found for part R0805-1K
2021-10-13 20:49:02.165 MessageBoxes DEBUG: Error: java.lang.Exception: Actuator GetPitch must not coordinate with machine when actuated outside machine task.
2021-10-13 20:49:02.194 GcodeDriver DEBUG: [serial://COM6] >> M610N8, 5000
2021-10-13 20:49:02.204 GcodeDriver$ReaderThread TRACE: [serial://COM6] << M610N8
2021-10-13 20:49:02.263 GcodeDriver$ReaderThread TRACE: [serial://COM6] << error  no response from feeder
2021-10-13 20:49:07.205 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM6 timeout waiting for response to M610N8
	at org.openpnp.machine.reference.driver.GcodeDriver.waitForConfirmation(GcodeDriver.java:1028)
	at org.openpnp.machine.reference.driver.GcodeDriver.sendCommand(GcodeDriver.java:1009)
	at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:976)
	at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:958)
	at org.openpnp.machine.reference.driver.GcodeDriver.actuatorRead(GcodeDriver.java:886)
	at org.openpnp.machine.reference.ReferenceActuator.read(ReferenceActuator.java:342)
	at org.openpnp.machine.reference.feeder.wizards.SlotSchultzFeederConfigurationWizard$7.lambda$actionPerformed$0(SlotSchultzFeederConfigurationWizard.java:717)
	at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38)
	at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571)
	at java.util.concurrent.FutureTask.run(Unknown Source)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
	at java.lang.Thread.run(Unknown Source)
2021-10-13 20:49:07.206 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM6 timeout waiting for response to M610N8
2021-10-13 20:49:09.731 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM6 error response from controller: error  no response from feeder
	at org.openpnp.machine.reference.driver.GcodeDriver.bailOnError(GcodeDriver.java:1035)
	at org.openpnp.machine.reference.driver.GcodeDriver.sendCommand(GcodeDriver.java:986)
	at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:976)
	at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:958)
	at org.openpnp.machine.reference.driver.GcodeDriver.actuatorRead(GcodeDriver.java:886)
	at org.openpnp.machine.reference.ReferenceActuator.read(ReferenceActuator.java:342)
	at org.openpnp.machine.reference.feeder.wizards.SlotSchultzFeederConfigurationWizard$14.lambda$actionPerformed$0(SlotSchultzFeederConfigurationWizard.java:885)
	at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38)
	at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571)
	at java.util.concurrent.FutureTask.run(Unknown Source)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
	at java.lang.Thread.run(Unknown Source)
2021-10-13 20:49:09.843 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM6 error response from controller: error  no response from feeder
2021-10-13 20:50:06.874 ReferenceHead DEBUG: H1.moveToSafeZ(0.5)
2021-10-13 20:50:06.874 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.5)
2021-10-13 20:50:06.877 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.5)
2021-10-13 20:50:06.878 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.5)
2021-10-13 20:50:06.879 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.5)
2021-10-13 20:50:06.880 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.5)
2021-10-13 20:50:06.881 AbstractHeadMountable DEBUG: DOWN.moveTo((569.684000, 73.902000, 0.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:06.884 GcodeDriver DEBUG: [serial://COM5] >> G1 X569.6840 Y74.0020 F22500, 25000
2021-10-13 20:50:06.886 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:06.886 GcodeDriver TRACE: [serial://COM5] confirmed G1 X569.6840 Y74.0020 F22500
2021-10-13 20:50:06.887 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:08.798 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:08.798 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:08.799 GcodeDriver DEBUG: [serial://COM5] >> G1  Y73.9020 F367, 25000
2021-10-13 20:50:08.801 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:08.801 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y73.9020 F367
2021-10-13 20:50:08.801 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:08.825 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:08.825 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:08.825 AbstractHeadMountable DEBUG: DOWN.moveTo((569.684000, 73.902000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:08.829 Scripting TRACE: Scripting.on Camera.AfterPosition
2021-10-13 20:50:19.328 ReferenceHead DEBUG: H1.moveToSafeZ(0.5)
2021-10-13 20:50:19.328 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.5)
2021-10-13 20:50:19.329 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.5)
2021-10-13 20:50:19.329 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.5)
2021-10-13 20:50:19.330 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.5)
2021-10-13 20:50:19.330 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.5)
2021-10-13 20:50:19.331 AbstractHeadMountable DEBUG: DOWN.moveTo((569.684000, 73.902000, 0.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:19.333 AbstractHeadMountable DEBUG: DOWN.moveTo((539.684000, 78.902000, 0.000000, 0.000000 mm), 0.5)
2021-10-13 20:50:19.339 GcodeDriver DEBUG: [serial://COM5] >> G1 X539.6840 Y79.0020 F6455, 25000
2021-10-13 20:50:19.341 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:19.342 GcodeDriver TRACE: [serial://COM5] confirmed G1 X539.6840 Y79.0020 F6455
2021-10-13 20:50:19.342 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:19.734 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:19.734 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:19.736 GcodeDriver DEBUG: [serial://COM5] >> G1  Y78.9020 F367, 25000
2021-10-13 20:50:19.738 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:19.738 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y78.9020 F367
2021-10-13 20:50:19.738 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:19.762 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:19.762 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:19.763 AbstractHeadMountable DEBUG: DOWN.moveTo((539.684000, 78.902000, -27.000000, 0.000000 mm), 0.5)
2021-10-13 20:50:19.765 Scripting TRACE: Scripting.on Camera.AfterPosition
2021-10-13 20:50:27.012 ReferenceHead DEBUG: H1.moveToSafeZ(0.5)
2021-10-13 20:50:27.012 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.5)
2021-10-13 20:50:27.013 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.5)
2021-10-13 20:50:27.013 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.5)
2021-10-13 20:50:27.014 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.5)
2021-10-13 20:50:27.014 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.5)
2021-10-13 20:50:27.015 AbstractHeadMountable DEBUG: DOWN.moveTo((539.684000, 78.902000, 0.000000, 0.000000 mm), 0.5)
2021-10-13 20:50:27.017 AbstractHeadMountable DEBUG: DOWN.moveTo((569.684000, 73.902000, 0.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:27.023 GcodeDriver DEBUG: [serial://COM5] >> G1 X569.6840 Y74.0020 F6448, 25000
2021-10-13 20:50:27.024 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:27.025 GcodeDriver TRACE: [serial://COM5] confirmed G1 X569.6840 Y74.0020 F6448
2021-10-13 20:50:27.025 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:27.417 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:27.419 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:27.420 GcodeDriver DEBUG: [serial://COM5] >> G1  Y73.9020 F367, 25000
2021-10-13 20:50:27.423 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:27.423 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y73.9020 F367
2021-10-13 20:50:27.423 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:27.447 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:27.447 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:27.447 AbstractHeadMountable DEBUG: DOWN.moveTo((569.684000, 73.902000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:27.450 Scripting TRACE: Scripting.on Camera.AfterPosition
2021-10-13 20:50:34.624 AbstractHeadMountable DEBUG: DOWN.moveTo((569.784000, 73.902000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:34.627 GcodeDriver DEBUG: [serial://COM5] >> G1 X569.7840  F367, 25000
2021-10-13 20:50:34.629 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:34.629 GcodeDriver TRACE: [serial://COM5] confirmed G1 X569.7840  F367
2021-10-13 20:50:34.629 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:34.653 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:34.654 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:35.382 AbstractHeadMountable DEBUG: DOWN.moveTo((569.884000, 73.902000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:35.385 GcodeDriver DEBUG: [serial://COM5] >> G1 X569.8840  F367, 25000
2021-10-13 20:50:35.388 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:35.389 GcodeDriver TRACE: [serial://COM5] confirmed G1 X569.8840  F367
2021-10-13 20:50:35.389 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:35.412 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:35.413 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:35.840 AbstractHeadMountable DEBUG: DOWN.moveTo((569.984000, 73.902000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:35.843 GcodeDriver DEBUG: [serial://COM5] >> G1 X569.9840  F367, 25000
2021-10-13 20:50:35.844 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:35.845 GcodeDriver TRACE: [serial://COM5] confirmed G1 X569.9840  F367
2021-10-13 20:50:35.845 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:35.869 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:35.869 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:36.340 AbstractHeadMountable DEBUG: DOWN.moveTo((570.084000, 73.902000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:36.342 GcodeDriver DEBUG: [serial://COM5] >> G1 X570.0840  F367, 25000
2021-10-13 20:50:36.344 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:36.347 GcodeDriver TRACE: [serial://COM5] confirmed G1 X570.0840  F367
2021-10-13 20:50:36.348 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:36.372 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:36.372 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:36.644 AbstractHeadMountable DEBUG: DOWN.moveTo((570.184000, 73.902000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:36.646 GcodeDriver DEBUG: [serial://COM5] >> G1 X570.1840  F367, 25000
2021-10-13 20:50:36.651 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:36.653 GcodeDriver TRACE: [serial://COM5] confirmed G1 X570.1840  F367
2021-10-13 20:50:36.653 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:36.676 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:36.676 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:37.558 AbstractHeadMountable DEBUG: DOWN.moveTo((570.284000, 73.902000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:37.561 GcodeDriver DEBUG: [serial://COM5] >> G1 X570.2840  F367, 25000
2021-10-13 20:50:37.562 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:37.563 GcodeDriver TRACE: [serial://COM5] confirmed G1 X570.2840  F367
2021-10-13 20:50:37.563 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:37.586 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:37.587 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:39.480 AbstractHeadMountable DEBUG: DOWN.moveTo((570.284000, 74.002000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:39.483 GcodeDriver DEBUG: [serial://COM5] >> G1  Y74.1020 F520, 25000
2021-10-13 20:50:39.485 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:39.485 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y74.1020 F520
2021-10-13 20:50:39.485 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:39.518 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:39.518 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:39.519 GcodeDriver DEBUG: [serial://COM5] >> G1  Y74.0020 F367, 25000
2021-10-13 20:50:39.521 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:39.521 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y74.0020 F367
2021-10-13 20:50:39.521 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:39.546 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:39.546 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:40.478 AbstractHeadMountable DEBUG: DOWN.moveTo((570.284000, 74.102000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:40.481 GcodeDriver DEBUG: [serial://COM5] >> G1  Y74.2020 F520, 25000
2021-10-13 20:50:40.483 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:40.483 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y74.2020 F520
2021-10-13 20:50:40.484 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:40.516 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:40.517 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:40.518 GcodeDriver DEBUG: [serial://COM5] >> G1  Y74.1020 F367, 25000
2021-10-13 20:50:40.519 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:40.519 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y74.1020 F367
2021-10-13 20:50:40.519 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:40.543 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:40.543 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:41.830 AbstractHeadMountable DEBUG: DOWN.moveTo((570.284000, 74.202000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:41.833 GcodeDriver DEBUG: [serial://COM5] >> G1  Y74.3020 F520, 25000
2021-10-13 20:50:41.835 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:41.835 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y74.3020 F520
2021-10-13 20:50:41.835 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:41.868 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:41.868 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:41.869 GcodeDriver DEBUG: [serial://COM5] >> G1  Y74.2020 F367, 25000
2021-10-13 20:50:41.870 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:41.871 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y74.2020 F367
2021-10-13 20:50:41.871 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:41.894 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:41.894 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:43.097 AbstractHeadMountable DEBUG: DOWN.moveTo((570.284000, 74.302000, -27.000000, 90.000000 mm), 0.5)
2021-10-13 20:50:43.101 GcodeDriver DEBUG: [serial://COM5] >> G1  Y74.4020 F520, 25000
2021-10-13 20:50:43.103 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:43.103 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y74.4020 F520
2021-10-13 20:50:43.103 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:43.136 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:43.136 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:50:43.137 GcodeDriver DEBUG: [serial://COM5] >> G1  Y74.3020 F367, 25000
2021-10-13 20:50:43.138 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:43.138 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y74.3020 F367
2021-10-13 20:50:43.139 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:50:43.164 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:50:43.164 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:51:19.547 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.5)
2021-10-13 20:51:19.548 ReferenceHead DEBUG: H1.moveToSafeZ(0.5)
2021-10-13 20:51:19.548 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.5)
2021-10-13 20:51:19.548 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.5)
2021-10-13 20:51:19.549 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.5)
2021-10-13 20:51:19.549 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.5)
2021-10-13 20:51:19.549 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.5)
2021-10-13 20:51:19.550 AbstractHeadMountable DEBUG: DOWN.moveTo((570.284000, 74.302000, 0.000000, 90.000000 mm), 0.5)
2021-10-13 20:51:19.552 AbstractHeadMountable DEBUG: N1.moveTo((570.284000, 74.302000, -15.000000, 90.000000 mm), 0.5)
2021-10-13 20:51:19.558 GcodeDriver DEBUG: [serial://COM5] >> G1 X532.6840 Y53.0020 Z15.0000 {Rotation:B%.4f} F8668, 25000
2021-10-13 20:51:19.560 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:51:19.561 GcodeDriver TRACE: [serial://COM5] confirmed G1 X532.6840 Y53.0020 Z15.0000 {Rotation:B%.4f} F8668
2021-10-13 20:51:19.561 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:51:20.024 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:51:20.025 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:51:20.027 GcodeDriver DEBUG: [serial://COM5] >> G1  Y52.9020  {Rotation:B%.4f} F367, 25000
2021-10-13 20:51:20.028 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:51:20.029 GcodeDriver TRACE: [serial://COM5] confirmed G1  Y52.9020  {Rotation:B%.4f} F367
2021-10-13 20:51:20.029 GcodeDriver DEBUG: [serial://COM5] >> M400 ; Wait for moves to complete before returning, 25000
2021-10-13 20:51:20.052 GcodeDriver$ReaderThread TRACE: [serial://COM5] << ok
2021-10-13 20:51:20.053 GcodeDriver TRACE: [serial://COM5] confirmed M400 ; Wait for moves to complete before returning
2021-10-13 20:51:20.053 AbstractHeadMountable DEBUG: N1.moveTo((570.284000, 74.302000, NaN, 90.000000 mm), 0.5)
2021-10-13 20:51:20.056 ReferenceActuator DEBUG: PrePick.actuate(8.0)
2021-10-13 20:51:20.056 GcodeDriver DEBUG: [serial://COM6] >> M600N8, 5000
2021-10-13 20:51:20.059 GcodeDriver$ReaderThread TRACE: [serial://COM6] << M600N8
2021-10-13 20:51:20.117 GcodeDriver$ReaderThread TRACE: [serial://COM6] << error No acknowledge from feeder
2021-10-13 20:51:25.061 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: serial://COM6 timeout waiting for response to M600N8
	at org.openpnp.machine.reference.driver.GcodeDriver.waitForConfirmation(GcodeDriver.java:1028)
	at org.openpnp.machine.reference.driver.GcodeDriver.sendCommand(GcodeDriver.java:1009)
	at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:976)
	at org.openpnp.machine.reference.driver.GcodeDriver.sendGcode(GcodeDriver.java:958)
	at org.openpnp.machine.reference.driver.GcodeDriver.actuate(GcodeDriver.java:847)
	at org.openpnp.machine.reference.ReferenceActuator.driveActuation(ReferenceActuator.java:288)
	at org.openpnp.machine.reference.ReferenceActuator.actuate(ReferenceActuator.java:279)
	at org.openpnp.machine.reference.feeder.SchultzFeeder.feed(SchultzFeeder.java:156)
	at org.openpnp.machine.reference.feeder.SlotSchultzFeeder.feed(SlotSchultzFeeder.java:79)
	at org.openpnp.gui.FeedersPanel.feedFeeder(FeedersPanel.java:635)
	at org.openpnp.gui.FeedersPanel.pickFeeder(FeedersPanel.java:648)
	at org.openpnp.gui.FeedersPanel$8.lambda$actionPerformed$0(FeedersPanel.java:604)
	at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38)
	at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571)
	at java.util.concurrent.FutureTask.run(Unknown Source)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
	at java.lang.Thread.run(Unknown Source)
2021-10-13 20:51:25.062 MessageBoxes DEBUG: Error: java.lang.Exception: serial://COM6 timeout waiting for response to M600N8