2022-03-16 20:27:43,867 | INFO    | inkcut | ========================================
2022-03-16 20:27:43,867 | INFO    | inkcut | Inkcut launched
2022-03-16 20:27:43,868 | INFO    | inkcut | Version: 2.1.3
2022-03-16 20:27:43,868 | INFO    | inkcut | Python: 3.10.2 (main, Jan 15 2022, 19:56:27) [GCC 11.1.0]
2022-03-16 20:27:43,868 | INFO    | inkcut | System: uname_result(system='Linux', node='nobody-IC3LAF', release='5.16.11-2-MANJARO', version='#1 SMP PREEMPT Fri Feb 25 11:27:31 UTC 2022', machine='x86_64')
2022-03-16 20:27:43,868 | INFO    | inkcut | Executable: /usr/bin/python
2022-03-16 20:27:43,868 | INFO    | inkcut | Args: ['/usr/bin/inkcut', 'open', '-', '--nodes', '/*/*[3]/*[1]', '/*/*[3]/*[2]', '/*/*[3]/*[3]', '/*/*[3]/*[4]', '/*/*[3]/*[5]', '/*/*[3]/*[6]']
2022-03-16 20:27:43,900 | INFO    | inkcut | Qt: 5.15.2 Api:
2022-03-16 20:27:43,900 | INFO    | inkcut | ========================================
2022-03-16 20:27:44,731 | DEBUG   | inkcut | Starting plugin 'inkcut.core'
2022-03-16 20:27:44,732 | DEBUG   | inkcut | Inkcut loaded.
2022-03-16 20:27:44,745 | DEBUG   | inkcut | CLI | Commands loaded
2022-03-16 20:27:44,749 | WARNING | inkcut | Translations not found at /usr/lib/python3.10/site-packages/inkcut/res/translations/en_US
2022-03-16 20:27:45,390 | DEBUG   | inkcut | CLI | Commands loaded
2022-03-16 20:27:45,411 | INFO    | inkcut | Loading plugin inkcut.device
2022-03-16 20:27:45,528 | DEBUG   | inkcut | Starting plugin 'inkcut.device'
2022-03-16 20:27:45,529 | DEBUG   | inkcut | Restoring state '<inkcut.core.models.AreaBase object at 0x7fc180db9b00>.size = [2161.417322826, 900000.0]'
2022-03-16 20:27:45,530 | DEBUG   | inkcut | Restoring state '<inkcut.core.models.AreaBase object at 0x7fc180db9b00>.padding = [10.0, 10.0, 10.0, 10.0]'
2022-03-16 20:27:45,538 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796c91c0>.port = /dev/ttyUSB0'
2022-03-16 20:27:45,538 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796c91c0>.baudrate = 9600'
2022-03-16 20:27:45,538 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796c91c0>.bytesize = 8'
2022-03-16 20:27:45,538 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796c91c0>.parity = None'
2022-03-16 20:27:45,538 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796c91c0>.stopbits = 1'
2022-03-16 20:27:45,539 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796c91c0>.xonxoff = False'
2022-03-16 20:27:45,539 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796c91c0>.rtscts = False'
2022-03-16 20:27:45,539 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796c91c0>.dsrdtr = False'
2022-03-16 20:27:45,540 | DEBUG   | inkcut | Restoring state '<inkcut.device.protocols.hpgl.HPGLProtocol object at 0x7fc1796c9240>.declaration = <inkcut.device.extensions.DeviceProtocol object at 0x7fc1796f84c0>'
2022-03-16 20:27:45,540 | DEBUG   | inkcut | Restoring state '<inkcut.device.protocols.hpgl.HPGLProtocol object at 0x7fc1796c9240>.config = <inkcut.core.models.Model object at 0x7fc1796f9340>'
2022-03-16 20:27:45,540 | DEBUG   | inkcut | Restoring state '.declaration = <inkcut.device.extensions.DeviceTransport object at 0x7fc1796c9140>'
2022-03-16 20:27:45,540 | DEBUG   | inkcut | Restoring state '.config = <inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796c91c0>'
2022-03-16 20:27:45,540 | DEBUG   | inkcut | Restoring state '.protocol = <inkcut.device.protocols.hpgl.HPGLProtocol object at 0x7fc1796c9240>'
2022-03-16 20:27:45,541 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.step_time = 35.0'
2022-03-16 20:27:45,541 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.custom_rate = -1.0'
2022-03-16 20:27:45,541 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.step_size = 3.5433070866'
2022-03-16 20:27:45,541 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.interpolate = False'
2022-03-16 20:27:45,542 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.sample_rate = 100'
2022-03-16 20:27:45,542 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.rotation = 0'
2022-03-16 20:27:45,542 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.swap_xy = False'
2022-03-16 20:27:45,542 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.mirror_y = False'
2022-03-16 20:27:45,542 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.mirror_x = False'
2022-03-16 20:27:45,542 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.scale = [1.0, 1.0]'
2022-03-16 20:27:45,542 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.quality_factor = 1.0'
2022-03-16 20:27:45,543 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.speed = 4.0'
2022-03-16 20:27:45,543 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.speed_units = in/s'
2022-03-16 20:27:45,543 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.speed_enabled = False'
2022-03-16 20:27:45,543 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.force = 40.0'
2022-03-16 20:27:45,543 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.force_units = g'
2022-03-16 20:27:45,543 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.force_enabled = False'
2022-03-16 20:27:45,543 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.absolute = False'
2022-03-16 20:27:45,543 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.spooled = False'
2022-03-16 20:27:45,544 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.test_mode = False'
2022-03-16 20:27:45,544 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.commands_before = '
2022-03-16 20:27:45,544 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.commands_after = '
2022-03-16 20:27:45,544 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.commands_connect = '
2022-03-16 20:27:45,544 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>.commands_disconnect = '
2022-03-16 20:27:45,544 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.name = PCUTTER'
2022-03-16 20:27:45,544 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.manufacturer = Creation'
2022-03-16 20:27:45,545 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.model = PCut CT-630'
2022-03-16 20:27:45,545 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.custom = False'
2022-03-16 20:27:45,545 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.area = <inkcut.core.models.AreaBase object at 0x7fc180db9b00>'
2022-03-16 20:27:45,545 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.declaration = <inkcut.device.extensions.DeviceDriver object at 0x7fc18096a140>'
2022-03-16 20:27:45,545 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.filters = []'
2022-03-16 20:27:45,545 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.connection = '
2022-03-16 20:27:45,545 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.jobs = []'
2022-03-16 20:27:45,546 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.queue = []'
2022-03-16 20:27:45,546 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8480>.config = <inkcut.device.plugin.DeviceConfig object at 0x7fc1796c9100>'
2022-03-16 20:27:45,546 | DEBUG   | inkcut | Restoring state '<inkcut.core.models.AreaBase object at 0x7fc1796f9e00>.size = [2692.9133858160003, 900000.0]'
2022-03-16 20:27:45,546 | DEBUG   | inkcut | Restoring state '<inkcut.core.models.AreaBase object at 0x7fc1796f9e00>.padding = [10.0, 10.0, 10.0, 10.0]'
2022-03-16 20:27:45,547 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796fa500>.port = /dev/ttyUSB0'
2022-03-16 20:27:45,547 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796fa500>.baudrate = 9600'
2022-03-16 20:27:45,547 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796fa500>.bytesize = 8'
2022-03-16 20:27:45,547 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796fa500>.parity = None'
2022-03-16 20:27:45,547 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796fa500>.stopbits = 1'
2022-03-16 20:27:45,547 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796fa500>.xonxoff = False'
2022-03-16 20:27:45,548 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796fa500>.rtscts = False'
2022-03-16 20:27:45,548 | DEBUG   | inkcut | Restoring state '<inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796fa500>.dsrdtr = False'
2022-03-16 20:27:45,548 | DEBUG   | inkcut | Restoring state '<inkcut.device.protocols.hpgl.HPGLProtocol object at 0x7fc1796fa580>.declaration = <inkcut.device.extensions.DeviceProtocol object at 0x7fc1796f84c0>'
2022-03-16 20:27:45,548 | DEBUG   | inkcut | Restoring state '<inkcut.device.protocols.hpgl.HPGLProtocol object at 0x7fc1796fa580>.config = <inkcut.core.models.Model object at 0x7fc1796fa740>'
2022-03-16 20:27:45,548 | DEBUG   | inkcut | Restoring state '.declaration = <inkcut.device.extensions.DeviceTransport object at 0x7fc1796c9140>'
2022-03-16 20:27:45,548 | DEBUG   | inkcut | Restoring state '.config = <inkcut.device.transports.serialport.plugin.SerialConfig object at 0x7fc1796fa500>'
2022-03-16 20:27:45,549 | DEBUG   | inkcut | Restoring state '.protocol = <inkcut.device.protocols.hpgl.HPGLProtocol object at 0x7fc1796fa580>'
2022-03-16 20:27:45,549 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.step_time = 35.0'
2022-03-16 20:27:45,549 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.custom_rate = -1.0'
2022-03-16 20:27:45,549 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.step_size = 3.5433070866'
2022-03-16 20:27:45,549 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.interpolate = False'
2022-03-16 20:27:45,549 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.sample_rate = 100'
2022-03-16 20:27:45,550 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.rotation = 0'
2022-03-16 20:27:45,550 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.swap_xy = False'
2022-03-16 20:27:45,550 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.mirror_y = False'
2022-03-16 20:27:45,550 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.mirror_x = False'
2022-03-16 20:27:45,550 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.scale = [1.0, 1.0]'
2022-03-16 20:27:45,550 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.quality_factor = 1.0'
2022-03-16 20:27:45,550 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.speed = 4.0'
2022-03-16 20:27:45,551 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.speed_units = in/s'
2022-03-16 20:27:45,551 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.speed_enabled = False'
2022-03-16 20:27:45,551 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.force = 40.0'
2022-03-16 20:27:45,551 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.force_units = g'
2022-03-16 20:27:45,551 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.force_enabled = False'
2022-03-16 20:27:45,551 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.absolute = False'
2022-03-16 20:27:45,551 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.spooled = False'
2022-03-16 20:27:45,552 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.test_mode = False'
2022-03-16 20:27:45,552 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.commands_before = '
2022-03-16 20:27:45,552 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.commands_after = '
2022-03-16 20:27:45,552 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.commands_connect = '
2022-03-16 20:27:45,552 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>.commands_disconnect = '
2022-03-16 20:27:45,552 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.name = New device'
2022-03-16 20:27:45,552 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.manufacturer = Creation'
2022-03-16 20:27:45,552 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.model = PCut CT-630'
2022-03-16 20:27:45,553 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.custom = False'
2022-03-16 20:27:45,553 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.area = <inkcut.core.models.AreaBase object at 0x7fc1796f9e00>'
2022-03-16 20:27:45,553 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.declaration = <inkcut.device.extensions.DeviceDriver object at 0x7fc18096a140>'
2022-03-16 20:27:45,553 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.filters = []'
2022-03-16 20:27:45,553 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.connection = '
2022-03-16 20:27:45,553 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.jobs = []'
2022-03-16 20:27:45,553 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.queue = []'
2022-03-16 20:27:45,554 | DEBUG   | inkcut | Restoring state '<inkcut.device.plugin.Device object at 0x7fc1796c8540>.config = <inkcut.device.plugin.DeviceConfig object at 0x7fc1796fa880>'
2022-03-16 20:27:45,559 | DEBUG   | inkcut | Starting plugin 'inkcut.job'
2022-03-16 20:27:45,560 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.size = [1800.0, 2700.0]'
2022-03-16 20:27:45,560 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.padding = [10.0, 10.0, 10.0, 10.0]'
2022-03-16 20:27:45,560 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.name = '
2022-03-16 20:27:45,560 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.color = #000000'
2022-03-16 20:27:45,561 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.is_roll = False'
2022-03-16 20:27:45,561 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.used = [0.0, 0.0]'
2022-03-16 20:27:45,561 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.cost = 1.0'
2022-03-16 20:27:45,561 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.use_force = False'
2022-03-16 20:27:45,561 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.use_speed = False'
2022-03-16 20:27:45,561 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.force = 10'
2022-03-16 20:27:45,561 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Material object at 0x7fc17987f080>.speed = 10'
2022-03-16 20:27:45,562 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.status = staged'
2022-03-16 20:27:45,562 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.created = 2022-03-16 20:19:21.696978'
2022-03-16 20:27:45,562 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.started = None'
2022-03-16 20:27:45,562 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.ended = None'
2022-03-16 20:27:45,562 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.progress = 0'
2022-03-16 20:27:45,563 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.data = '
2022-03-16 20:27:45,563 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.count = 0'
2022-03-16 20:27:45,563 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.speed = 0.0'
2022-03-16 20:27:45,563 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.length = 0.0'
2022-03-16 20:27:45,563 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.duration = 0:00:00'
2022-03-16 20:27:45,563 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.units = in'
2022-03-16 20:27:45,563 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.JobInfo object at 0x7fc17987e8c0>.auto_approve = False'
2022-03-16 20:27:45,564 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.material = <inkcut.job.models.Material object at 0x7fc17987f080>'
2022-03-16 20:27:45,564 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.document = -'
2022-03-16 20:27:45,564 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.document_kwargs = {'ids': ['/*/*[3]/*[1]', '/*/*[3]/*[2]', '/*/*[3]/*[3]', '/*/*[3]/*[4]', '/*/*[3]/*[5]', '/*/*[3]/*[6]']}'
2022-03-16 20:27:45,564 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.info = <inkcut.job.models.JobInfo object at 0x7fc17987e8c0>'
2022-03-16 20:27:45,565 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.scale = [1.0, 1.0]'
2022-03-16 20:27:45,565 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.auto_scale = False'
2022-03-16 20:27:45,565 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.lock_scale = True'
2022-03-16 20:27:45,565 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.mirror = [False, False]'
2022-03-16 20:27:45,565 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.align_center = [False, False]'
2022-03-16 20:27:45,565 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.rotation = 0.0'
2022-03-16 20:27:45,566 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.auto_rotate = False'
2022-03-16 20:27:45,566 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.copies = 1'
2022-03-16 20:27:45,566 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.auto_copies = False'
2022-03-16 20:27:45,566 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.copy_spacing = [10.0, 10.0]'
2022-03-16 20:27:45,566 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.copy_weedline = False'
2022-03-16 20:27:45,566 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.copy_weedline_padding = [10.0, 10.0, 10.0, 10.0]'
2022-03-16 20:27:45,566 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.plot_weedline = False'
2022-03-16 20:27:45,567 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.plot_weedline_padding = [10.0, 10.0, 10.0, 10.0]'
2022-03-16 20:27:45,567 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.order = Normal'
2022-03-16 20:27:45,567 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.feed_to_end = False'
2022-03-16 20:27:45,567 | DEBUG   | inkcut | Restoring state '<inkcut.job.models.Job object at 0x7fc1796c8600>.feed_after = 0.0'
2022-03-16 20:27:45,567 | INFO    | inkcut | {'type': 'create', 'object': <inkcut.job.plugin.JobPlugin object at 0x7fc179877440>, 'name': 'job', 'value': <inkcut.job.models.Job object at 0x7fc179850a40>}
2022-03-16 20:27:45,810 | DEBUG   | inkcut | Starting plugin 'inkcut.preview'
2022-03-16 20:27:45,812 | INFO    | inkcut | Saving state due to change: {'type': 'request'}
2022-03-16 20:27:45,814 | INFO    | inkcut | {'type': 'update', 'object': <inkcut.job.plugin.JobPlugin object at 0x7fc179877440>, 'name': 'job', 'oldvalue': <inkcut.job.models.Job object at 0x7fc179850a40>, 'value': <inkcut.job.models.Job object at 0x7fc1796c8600>}
2022-03-16 20:27:45,815 | INFO    | inkcut | Saving state due to change: {'type': 'request'}
2022-03-16 20:27:45,817 | WARNING | inkcut | Plugin inkcut.job state restored from: /home/laurie/.config/inkcut/inkcut.job.json
2022-03-16 20:27:45,817 | INFO    | inkcut | {}
2022-03-16 20:27:45,817 | INFO    | inkcut | Saving state due to change: {'type': 'request'}
2022-03-16 20:27:45,820 | INFO    | inkcut | {}
2022-03-16 20:27:45,820 | INFO    | inkcut | Saving state due to change: {'type': 'request'}
2022-03-16 20:27:45,823 | WARNING | inkcut | Plugin inkcut.device state restored from: /home/laurie/.config/inkcut/inkcut.device.json
2022-03-16 20:27:45,958 | INFO    | inkcut | Loading plugin inkcut.console
2022-03-16 20:27:46,381 | INFO    | inkcut | Loading plugin inkcut.preview
2022-03-16 20:27:46,395 | INFO    | inkcut | Loading plugin inkcut.monitor
2022-03-16 20:27:46,395 | DEBUG   | inkcut | Starting plugin 'inkcut.monitor'
2022-03-16 20:27:46,433 | INFO    | inkcut | Loading plugin inkcut.joystick
2022-03-16 20:27:46,433 | DEBUG   | inkcut | Starting plugin 'inkcut.joystick'
2022-03-16 20:27:46,493 | INFO    | inkcut | Loading plugin inkcut.job
2022-03-16 20:27:46,729 | INFO    | inkcut | Loading plugin inkcut.job
2022-03-16 20:27:46,730 | INFO    | inkcut | Loading plugin inkcut.job
2022-03-16 20:27:46,731 | INFO    | inkcut | Loading plugin inkcut.job
2022-03-16 20:27:46,731 | INFO    | inkcut | Loading plugin inkcut.job
2022-03-16 20:27:46,732 | DEBUG   | inkcut | Updating dock items: [<inkcut.device.view.DeviceProgressDockItem object at 0x7fc17987e140>, <inkcut.console.view.ConsoleDockItem object at 0x7fc17983f400>, <inkcut.preview.view.PreviewDockItem object at 0x7fc1813b9cc0>, <inkcut.monitor.view.MonitorDockItem object at 0x7fc1727a3f40>, <inkcut.joystick.view.JoystickDockItem object at 0x7fc173dfc0c0>, <inkcut.job.view.GraphicDockItem object at 0x7fc1727d5640>, <inkcut.job.view.MaterialDockItem object at 0x7fc1727c0240>, <inkcut.job.view.WeedlinesDockItem object at 0x7fc172800ac0>, <inkcut.job.view.LayersDockItem object at 0x7fc1727e2780>, <inkcut.job.view.JobHistoryDockItem object at 0x7fc1727b6d00>]
2022-03-16 20:27:46,781 | DEBUG   | inkcut | Updating settings pages: [<inkcut.ui.extensions.SettingsPage object at 0x7fc180766100>, <inkcut.ui.extensions.SettingsPage object at 0x7fc17982bf00>, <inkcut.ui.extensions.SettingsPage object at 0x7fc1799392c0>]
2022-03-16 20:27:46,782 | DEBUG   | inkcut | Starting plugin 'inkcut.ui'
2022-03-16 20:27:47,275 | DEBUG   | asyncio | Using selector: EpollSelector
2022-03-16 20:27:47,367 | DEBUG   | asyncio | Using selector: EpollSelector
2022-03-16 20:27:47,828 | DEBUG   | inkcut | CLI | Runinng command 'open' with args: Namespace(path='-', nodes=['/*/*[3]/*[1]', '/*/*[3]/*[2]', '/*/*[3]/*[3]', '/*/*[3]/*[4]', '/*/*[3]/*[5]', '/*/*[3]/*[6]'], cmd=<inkcut.cli.plugin.Command object at 0x7fc17982ba00>)
2022-03-16 20:27:47,832 | DEBUG   | inkcut | Opening document from stdin...
2022-03-16 20:27:47,832 | INFO    | inkcut | Closing -
2022-03-16 20:27:47,833 | INFO    | inkcut | {'type': 'update', 'object': <inkcut.job.plugin.JobPlugin object at 0x7fc179877440>, 'name': 'job', 'oldvalue': <inkcut.job.models.Job object at 0x7fc1796c8600>, 'value': <inkcut.job.models.Job object at 0x7fc171441140>}
2022-03-16 20:27:47,845 | INFO    | inkcut | Saving state due to change: {'type': 'request'}
2022-03-16 20:27:47,848 | INFO    | inkcut | Saving state due to change: {'type': 'update', 'object': <inkcut.job.plugin.JobPlugin object at 0x7fc179877440>, 'name': 'job', 'oldvalue': <inkcut.job.models.Job object at 0x7fc1796c8600>, 'value': <inkcut.job.models.Job object at 0x7fc171441140>}
2022-03-16 20:27:47,851 | INFO    | inkcut | Opening -
2022-03-16 20:27:47,855 | INFO    | inkcut | {'type': 'update', 'object': <inkcut.job.models.Job object at 0x7fc171441140>, 'name': 'model', 'oldvalue': None, 'value': <PyQt5.QtGui.QPainterPath object at 0x7fc17142eb20>}
2022-03-16 20:27:47,867 | INFO    | inkcut | Saving state due to change: {'type': 'request'}
2022-03-16 20:27:56,832 | INFO    | inkcut | Saving state due to change: {'type': 'request'}
2022-03-16 20:27:56,834 | INFO    | inkcut | device | 
2022-03-16 20:27:56,839 | INFO    | inkcut | device | 
2022-03-16 20:27:56,873 | INFO    | inkcut | {}
2022-03-16 20:27:56,903 | INFO    | inkcut | Saving state due to change: {'type': 'request'}
2022-03-16 20:28:01,569 | DEBUG   | inkcut | device | submit <inkcut.job.models.Job object at 0x7fc171441140>
2022-03-16 20:28:01,596 | INFO    | inkcut | device | Initializing job
2022-03-16 20:28:01,597 | DEBUG   | inkcut | device | init <inkcut.job.models.Job object at 0x7fc171441140>
2022-03-16 20:28:01,598 | DEBUG   | inkcut | device | Path length: 2524.3208507713634
2022-03-16 20:28:08,176 | INFO    | inkcut | device | Connecting to device
2022-03-16 20:28:08,177 | INFO    | inkcut | device | Processing job
2022-03-16 20:28:08,177 | DEBUG   | inkcut | device | connect
2022-03-16 20:28:08,310 | DEBUG   | inkcut | -> /dev/ttyUSB0 | IN;
2022-03-16 20:28:08,311 | DEBUG   | inkcut | /dev/ttyUSB0 | opened
2022-03-16 20:28:08,311 | INFO    | inkcut | device | Working...
2022-03-16 20:28:08,319 | DEBUG   | inkcut | -> /dev/ttyUSB0 | PU113,126;
2022-03-16 20:28:08,322 | ERROR   | inkcut | Traceback (most recent call last):
  File "/usr/lib/python3.10/site-packages/inkcut/device/plugin.py", line 810, in submit
    yield async_sleep(d/rate)
  File "/usr/lib/python3.10/site-packages/inkcut/core/utils.py", line 116, in async_sleep
    timed_call(ms, d.callback, True)
  File "/home/laurie/.local/lib/python3.10/site-packages/enaml/application.py", line 501, in timed_call
    app.timed_call(ms, callback, *args, **kwargs)
  File "/home/laurie/.local/lib/python3.10/site-packages/enaml/qt/qt_application.py", line 103, in timed_call
    timedCall(ms, callback, *args, **kwargs)
  File "/home/laurie/.local/lib/python3.10/site-packages/enaml/qt/q_deferred_caller.py", line 73, in timedCall
    QTimer.singleShot(ms, lambda: callback(*args, **kwargs))
TypeError: arguments did not match any overloaded call:
  singleShot(int, PYQT_SLOT): argument 1 has unexpected type 'float'
  singleShot(int, Qt.TimerType, PYQT_SLOT): argument 1 has unexpected type 'float'

2022-03-16 20:28:08,323 | DEBUG   | inkcut | device | disconnect
2022-03-16 20:28:08,323 | DEBUG   | inkcut | -- /dev/ttyUSB0 | closed by request
2022-03-16 20:28:08,329 | ERROR   | inkcut |  device | Execution error Traceback (most recent call last):
  File "/usr/lib/python3.10/site-packages/inkcut/device/plugin.py", line 810, in submit
    yield async_sleep(d/rate)
  File "/usr/lib/python3.10/site-packages/inkcut/core/utils.py", line 116, in async_sleep
    timed_call(ms, d.callback, True)
  File "/home/laurie/.local/lib/python3.10/site-packages/enaml/application.py", line 501, in timed_call
    app.timed_call(ms, callback, *args, **kwargs)
  File "/home/laurie/.local/lib/python3.10/site-packages/enaml/qt/qt_application.py", line 103, in timed_call
    timedCall(ms, callback, *args, **kwargs)
  File "/home/laurie/.local/lib/python3.10/site-packages/enaml/qt/q_deferred_caller.py", line 73, in timedCall
    QTimer.singleShot(ms, lambda: callback(*args, **kwargs))
TypeError: arguments did not match any overloaded call:
  singleShot(int, PYQT_SLOT): argument 1 has unexpected type 'float'
  singleShot(int, Qt.TimerType, PYQT_SLOT): argument 1 has unexpected type 'float'

2022-03-16 20:28:08,330 | ERROR   | inkcut | Traceback (most recent call last):
  File "/usr/lib/python3.10/site-packages/inkcut/device/manifest.enaml", line 54, in job_start
    yield defer.maybeDeferred(plugin.submit, job)
TypeError: arguments did not match any overloaded call:
  singleShot(int, PYQT_SLOT): argument 1 has unexpected type 'float'
  singleShot(int, Qt.TimerType, PYQT_SLOT): argument 1 has unexpected type 'float'

2022-03-16 20:28:08,331 | CRITICAL | inkcut | Traceback (most recent call last):
  File "/usr/lib/python3.10/site-packages/inkcut/device/manifest.enaml", line 54, in job_start
    yield defer.maybeDeferred(plugin.submit, job)
TypeError: arguments did not match any overloaded call:
  singleShot(int, PYQT_SLOT): argument 1 has unexpected type 'float'
  singleShot(int, Qt.TimerType, PYQT_SLOT): argument 1 has unexpected type 'float'

2022-03-16 20:28:08,406 | DEBUG   | inkcut | -- /dev/ttyUSB0 | dropped: [Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ConnectionDone'>: Connection was closed cleanly.
]
2022-03-16 20:28:20,274 | INFO    | inkcut | ========================================
2022-03-16 20:28:20,274 | INFO    | inkcut | Inkcut exited cleanly
2022-03-16 20:28:20,274 | INFO    | inkcut | ========================================