Skip to content

Instantly share code, notes, and snippets.

@foosel

foosel/serial.log

Forked from nophead/serial.log
Last active March 3, 2016 08:59
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save foosel/c631c1415179a18828e5 to your computer and use it in GitHub Desktop.
Save foosel/c631c1415179a18828e5 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
serial.log
2014-10-23 16:58:02,141 - SERIAL - DEBUG - Enabling serial logging
2014-10-23 16:58:09,247 - SERIAL - DEBUG - Changing monitoring state from 'Operational' to 'Closed'
2014-10-23 16:58:13,303 - SERIAL - DEBUG - Connection closed, closing down monitor
2014-10-23 16:58:16,878 - SERIAL - DEBUG - Changing monitoring state from 'Offline' to 'Opening serial port'
2014-10-23 16:58:16,883 - SERIAL - DEBUG - Connecting to: /dev/ttyAMA0
2014-10-23 16:58:16,922 - SERIAL - DEBUG - Connected to: Serial<id=0x1ad8fb0, open=True>(port='/dev/ttyAMA0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=20.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2014-10-23 16:58:16,926 - SERIAL - DEBUG - Changing monitoring state from 'Opening serial port' to 'Connecting'
2014-10-23 16:58:36,974 - SERIAL - DEBUG - Send: M105
2014-10-23 16:58:37,000 - SERIAL - DEBUG - Recv: ok T:21.5 /0.0 B:21.1 /0.0 @:0%
2014-10-23 16:58:37,005 - SERIAL - DEBUG - Changing monitoring state from 'Connecting' to 'Operational'
2014-10-23 16:58:37,019 - SERIAL - DEBUG - Send: M21
2014-10-23 16:58:37,058 - SERIAL - DEBUG - Recv: echo:SD card ok
2014-10-23 16:58:37,063 - SERIAL - DEBUG - Send: M20
2014-10-23 16:58:37,074 - SERIAL - DEBUG - Recv: ok
2014-10-23 16:58:37,086 - SERIAL - DEBUG - Recv: Begin file list
...
2014-10-23 16:58:37,356 - SERIAL - DEBUG - Recv: End file list
2014-10-23 16:58:37,380 - SERIAL - DEBUG - Recv: ok
2014-10-23 16:58:44,661 - SERIAL - DEBUG - Disabling serial logging
2016-02-18 20:56:23,449 - SERIAL - DEBUG - Enabling serial logging
2016-02-18 20:56:26,301 - SERIAL - DEBUG - Send: M105
2016-02-18 20:56:26,343 - SERIAL - DEBUG - Recv: ok T:134.8 /0.0 B:95.2 /0.0 T0:134.8 /0.0 @:0.00 B@:0.00
2016-02-18 20:56:31,323 - SERIAL - DEBUG - Send: M105
2016-02-18 20:56:31,364 - SERIAL - DEBUG - Recv: ok T:133.4 /0.0 B:94.5 /0.0 T0:133.4 /0.0 @:0.00 B@:0.00
2016-02-18 20:56:36,338 - SERIAL - DEBUG - Send: M105
2016-02-18 20:56:36,448 - SERIAL - DEBUG - Recv: ok T:132.1 /0.0 B:93.9 /0.0 T0:132.1 /0.0 @:0.00 B@:0.00
2016-02-18 20:56:36,585 - SERIAL - DEBUG - Changing monitoring state from 'Operational' to 'Printing'
2016-02-18 20:56:36,891 - SERIAL - DEBUG - Send: N0 M110 N0*125
2016-02-18 20:56:36,899 - SERIAL - DEBUG - Recv: ok
2016-02-18 20:56:36,916 - SERIAL - DEBUG - Send: N1 M83*25
2016-02-18 20:56:36,933 - SERIAL - DEBUG - Recv: ok
2016-02-18 20:56:36,959 - SERIAL - DEBUG - Send: N2 G28*17
2016-02-18 20:57:30,021 - SERIAL - DEBUG - Recv: ok
2016-02-18 20:57:30,033 - SERIAL - DEBUG - Send: N3 G1 X5.0 Y98.0 F9000*113
2016-02-18 20:57:30,043 - SERIAL - DEBUG - Recv: ok
2016-02-18 20:57:30,052 - SERIAL - DEBUG - Send: N4 G1 Z0.05*77
2016-02-18 20:57:30,060 - SERIAL - DEBUG - Recv: ok
2016-02-18 20:57:30,079 - SERIAL - DEBUG - Send: N5 M190 S148.0*126
2016-02-18 20:57:31,102 - SERIAL - DEBUG - Recv: T:117.18 E:0 B:87.4
2016-02-18 20:57:32,096 - SERIAL - DEBUG - Recv: T:116.90 E:0 B:87.8
2016-02-18 20:57:33,106 - SERIAL - DEBUG - Recv: T:116.62 E:0 B:88.3
2016-02-18 20:57:34,100 - SERIAL - DEBUG - Recv: T:116.35 E:0 B:88.9
2016-02-18 20:57:35,111 - SERIAL - DEBUG - Recv: T:116.07 E:0 B:89.6
2016-02-18 20:57:36,104 - SERIAL - DEBUG - Recv: T:115.78 E:0 B:90.3
2016-02-18 20:57:37,110 - SERIAL - DEBUG - Recv: T:115.50 E:0 B:90.9
2016-02-18 20:57:38,109 - SERIAL - DEBUG - Recv: T:115.24 E:0 B:91.6
2016-02-18 20:57:39,109 - SERIAL - DEBUG - Recv: T:115.06 E:0 B:92.4
2016-02-18 20:57:40,112 - SERIAL - DEBUG - Recv: T:114.80 E:0 B:93.0
2016-02-18 20:57:41,121 - SERIAL - DEBUG - Recv: T:114.52 E:0 B:93.8
2016-02-18 20:57:42,115 - SERIAL - DEBUG - Recv: T:114.26 E:0 B:94.5
2016-02-18 20:57:43,116 - SERIAL - DEBUG - Recv: T:113.98 E:0 B:95.2
2016-02-18 20:57:44,119 - SERIAL - DEBUG - Recv: T:113.73 E:0 B:96.0
2016-02-18 20:57:45,130 - SERIAL - DEBUG - Recv: T:113.56 E:0 B:96.6
2016-02-18 20:57:46,123 - SERIAL - DEBUG - Recv: T:113.31 E:0 B:97.4
2016-02-18 20:57:47,132 - SERIAL - DEBUG - Recv: T:113.05 E:0 B:98.1
2016-02-18 20:57:48,127 - SERIAL - DEBUG - Recv: T:112.83 E:0 B:98.7
2016-02-18 20:57:49,137 - SERIAL - DEBUG - Recv: T:112.60 E:0 B:99.4
2016-02-18 20:57:50,131 - SERIAL - DEBUG - Recv: T:112.35 E:0 B:100.2
2016-02-18 20:57:51,140 - SERIAL - DEBUG - Recv: T:112.10 E:0 B:100.8
2016-02-18 20:57:52,136 - SERIAL - DEBUG - Recv: T:111.89 E:0 B:101.5
2016-02-18 20:57:53,138 - SERIAL - DEBUG - Recv: T:111.65 E:0 B:102.2
2016-02-18 20:57:54,152 - SERIAL - DEBUG - Recv: T:111.39 E:0 B:102.8
2016-02-18 20:57:55,147 - SERIAL - DEBUG - Recv: T:111.12 E:0 B:103.5
2016-02-18 20:57:56,159 - SERIAL - DEBUG - Recv: T:110.85 E:0 B:104.2
2016-02-18 20:57:57,151 - SERIAL - DEBUG - Recv: T:110.71 E:0 B:104.8
2016-02-18 20:57:58,161 - SERIAL - DEBUG - Recv: T:110.42 E:0 B:105.4
2016-02-18 20:57:59,153 - SERIAL - DEBUG - Recv: T:110.16 E:0 B:106.1
2016-02-18 20:58:00,156 - SERIAL - DEBUG - Recv: T:109.90 E:0 B:106.7
2016-02-18 20:58:01,159 - SERIAL - DEBUG - Recv: T:109.65 E:0 B:107.4
2016-02-18 20:58:02,170 - SERIAL - DEBUG - Recv: T:109.48 E:0 B:107.9
2016-02-18 20:58:03,163 - SERIAL - DEBUG - Recv: T:109.22 E:0 B:108.6
2016-02-18 20:58:04,170 - SERIAL - DEBUG - Recv: T:108.95 E:0 B:109.2
2016-02-18 20:58:05,169 - SERIAL - DEBUG - Recv: T:108.75 E:0 B:109.7
2016-02-18 20:58:06,171 - SERIAL - DEBUG - Recv: T:108.54 E:0 B:110.3
2016-02-18 20:58:07,173 - SERIAL - DEBUG - Recv: T:108.27 E:0 B:110.9
2016-02-18 20:58:08,176 - SERIAL - DEBUG - Recv: T:108.01 E:0 B:111.4
2016-02-18 20:58:09,187 - SERIAL - DEBUG - Recv: T:107.74 E:0 B:112.0
2016-02-18 20:58:10,179 - SERIAL - DEBUG - Recv: T:107.50 E:0 B:112.6
2016-02-18 20:58:11,181 - SERIAL - DEBUG - Recv: T:107.33 E:0 B:113.1
2016-02-18 20:58:12,191 - SERIAL - DEBUG - Recv: T:107.05 E:0 B:113.7
2016-02-18 20:58:13,186 - SERIAL - DEBUG - Recv: T:106.79 E:0 B:114.3
2016-02-18 20:58:14,197 - SERIAL - DEBUG - Recv: T:106.62 E:0 B:114.7
2016-02-18 20:58:15,189 - SERIAL - DEBUG - Recv: T:106.39 E:0 B:115.3
2016-02-18 20:58:16,191 - SERIAL - DEBUG - Recv: T:106.12 E:0 B:115.9
2016-02-18 20:58:17,201 - SERIAL - DEBUG - Recv: T:105.88 E:0 B:116.3
2016-02-18 20:58:18,195 - SERIAL - DEBUG - Recv: T:105.66 E:0 B:116.9
2016-02-18 20:58:19,202 - SERIAL - DEBUG - Recv: T:105.45 E:0 B:117.4
2016-02-18 20:58:20,198 - SERIAL - DEBUG - Recv: T:105.18 E:0 B:117.9
2016-02-18 20:58:21,201 - SERIAL - DEBUG - Recv: T:104.93 E:0 B:118.4
2016-02-18 20:58:22,210 - SERIAL - DEBUG - Recv: T:104.77 E:0 B:118.9
2016-02-18 20:58:23,205 - SERIAL - DEBUG - Recv: T:104.51 E:0 B:119.3
2016-02-18 20:58:24,211 - SERIAL - DEBUG - Recv: T:104.25 E:0 B:119.8
2016-02-18 20:58:25,209 - SERIAL - DEBUG - Recv: T:104.10 E:0 B:120.2
2016-02-18 20:58:26,210 - SERIAL - DEBUG - Recv: T:103.84 E:0 B:120.7
2016-02-18 20:58:27,221 - SERIAL - DEBUG - Recv: T:103.57 E:0 B:121.2
2016-02-18 20:58:28,214 - SERIAL - DEBUG - Recv: T:103.42 E:0 B:121.7
2016-02-18 20:58:29,216 - SERIAL - DEBUG - Recv: T:103.17 E:0 B:122.1
2016-02-18 20:58:30,230 - SERIAL - DEBUG - Recv: T:102.97 E:0 B:122.6
2016-02-18 20:58:31,220 - SERIAL - DEBUG - Recv: T:102.78 E:0 B:123.0
2016-02-18 20:58:32,221 - SERIAL - DEBUG - Recv: T:102.62 E:0 B:123.4
2016-02-18 20:58:33,224 - SERIAL - DEBUG - Recv: T:102.40 E:0 B:123.8
2016-02-18 20:58:34,225 - SERIAL - DEBUG - Recv: T:102.23 E:0 B:124.2
2016-02-18 20:58:35,241 - SERIAL - DEBUG - Recv: T:101.98 E:0 B:124.6
2016-02-18 20:58:36,229 - SERIAL - DEBUG - Recv: T:101.83 E:0 B:125.0
2016-02-18 20:58:37,241 - SERIAL - DEBUG - Recv: T:101.68 E:0 B:125.4
2016-02-18 20:58:38,234 - SERIAL - DEBUG - Recv: T:101.42 E:0 B:125.8
2016-02-18 20:58:39,241 - SERIAL - DEBUG - Recv: T:101.29 E:0 B:126.2
2016-02-18 20:58:40,238 - SERIAL - DEBUG - Recv: T:101.06 E:0 B:126.6
2016-02-18 20:58:41,241 - SERIAL - DEBUG - Recv: T:100.89 E:0 B:127.0
2016-02-18 20:58:42,243 - SERIAL - DEBUG - Recv: T:100.70 E:0 B:127.3
2016-02-18 20:58:43,246 - SERIAL - DEBUG - Recv: T:100.49 E:0 B:127.7
2016-02-18 20:58:44,247 - SERIAL - DEBUG - Recv: T:100.34 E:0 B:128.0
2016-02-18 20:58:45,250 - SERIAL - DEBUG - Recv: T:100.12 E:0 B:128.3
2016-02-18 20:58:46,253 - SERIAL - DEBUG - Recv: T:99.95 E:0 B:128.7
2016-02-18 20:58:47,255 - SERIAL - DEBUG - Recv: T:99.75 E:0 B:129.0
2016-02-18 20:58:48,257 - SERIAL - DEBUG - Recv: T:99.54 E:0 B:129.3
2016-02-18 20:58:49,274 - SERIAL - DEBUG - Recv: T:99.40 E:0 B:129.6
2016-02-18 20:58:50,262 - SERIAL - DEBUG - Recv: T:99.20 E:0 B:129.8
2016-02-18 20:58:51,264 - SERIAL - DEBUG - Recv: T:99.00 E:0 B:130.1
2016-02-18 20:58:52,272 - SERIAL - DEBUG - Recv: T:98.79 E:0 B:130.4
2016-02-18 20:58:53,267 - SERIAL - DEBUG - Recv: T:98.61 E:0 B:130.7
2016-02-18 20:58:54,279 - SERIAL - DEBUG - Recv: T:98.46 E:0 B:131.0
2016-02-18 20:58:55,271 - SERIAL - DEBUG - Recv: T:98.19 E:0 B:131.3
2016-02-18 20:58:56,273 - SERIAL - DEBUG - Recv: T:98.07 E:0 B:131.5
2016-02-18 20:58:57,281 - SERIAL - DEBUG - Recv: T:97.91 E:0 B:131.8
2016-02-18 20:58:58,277 - SERIAL - DEBUG - Recv: T:97.69 E:0 B:132.0
2016-02-18 20:58:59,288 - SERIAL - DEBUG - Recv: T:97.53 E:0 B:132.2
2016-02-18 20:59:00,282 - SERIAL - DEBUG - Recv: T:97.38 E:0 B:132.5
2016-02-18 20:59:01,283 - SERIAL - DEBUG - Recv: T:97.17 E:0 B:132.7
2016-02-18 20:59:02,290 - SERIAL - DEBUG - Recv: T:96.98 E:0 B:132.8
2016-02-18 20:59:03,286 - SERIAL - DEBUG - Recv: T:96.84 E:0 B:133.1
2016-02-18 20:59:04,299 - SERIAL - DEBUG - Recv: T:96.68 E:0 B:133.3
2016-02-18 20:59:05,301 - SERIAL - DEBUG - Recv: T:96.48 E:0 B:133.5
2016-02-18 20:59:06,292 - SERIAL - DEBUG - Recv: T:96.30 E:0 B:133.7
2016-02-18 20:59:07,302 - SERIAL - DEBUG - Recv: T:96.16 E:0 B:133.9
2016-02-18 20:59:08,296 - SERIAL - DEBUG - Recv: T:96.00 E:0 B:134.0
2016-02-18 20:59:09,307 - SERIAL - DEBUG - Recv: T:95.76 E:0 B:134.2
2016-02-18 20:59:10,300 - SERIAL - DEBUG - Recv: T:95.62 E:0 B:134.4
2016-02-18 20:59:11,302 - SERIAL - DEBUG - Recv: T:95.49 E:0 B:134.5
2016-02-18 20:59:12,309 - SERIAL - DEBUG - Recv: T:95.25 E:0 B:134.7
2016-02-18 20:59:13,307 - SERIAL - DEBUG - Recv: T:95.09 E:0 B:134.9
2016-02-18 20:59:14,310 - SERIAL - DEBUG - Recv: T:94.95 E:0 B:135.0
2016-02-18 20:59:15,317 - SERIAL - DEBUG - Recv: T:94.80 E:0 B:135.2
2016-02-18 20:59:16,314 - SERIAL - DEBUG - Recv: T:94.65 E:0 B:135.3
2016-02-18 20:59:17,321 - SERIAL - DEBUG - Recv: T:94.42 E:0 B:135.4
2016-02-18 20:59:18,317 - SERIAL - DEBUG - Recv: T:94.26 E:0 B:135.6
2016-02-18 20:59:19,329 - SERIAL - DEBUG - Recv: T:94.12 E:0 B:135.8
2016-02-18 20:59:20,322 - SERIAL - DEBUG - Recv: T:93.98 E:0 B:135.8
2016-02-18 20:59:21,350 - SERIAL - DEBUG - Recv: T:93.83 E:0 B:136.0
2016-02-18 20:59:22,326 - SERIAL - DEBUG - Recv: T:93.65 E:0 B:136.2
2016-02-18 20:59:23,327 - SERIAL - DEBUG - Recv: T:93.44 E:0 B:136.2
2016-02-18 20:59:24,330 - SERIAL - DEBUG - Recv: T:93.30 E:0 B:136.4
2016-02-18 20:59:25,337 - SERIAL - DEBUG - Recv: T:93.15 E:0 B:136.5
2016-02-18 20:59:26,335 - SERIAL - DEBUG - Recv: T:93.00 E:0 B:136.6
2016-02-18 20:59:27,344 - SERIAL - DEBUG - Recv: T:92.83 E:0 B:136.7
2016-02-18 20:59:28,340 - SERIAL - DEBUG - Recv: T:92.67 E:0 B:136.8
2016-02-18 20:59:29,342 - SERIAL - DEBUG - Recv: T:92.47 E:0 B:136.9
2016-02-18 20:59:30,344 - SERIAL - DEBUG - Recv: T:92.33 E:0 B:137.0
2016-02-18 20:59:31,357 - SERIAL - DEBUG - Recv: T:92.19 E:0 B:137.1
2016-02-18 20:59:32,348 - SERIAL - DEBUG - Recv: T:92.05 E:0 B:137.2
2016-02-18 20:59:33,350 - SERIAL - DEBUG - Recv: T:91.89 E:0 B:137.3
2016-02-18 20:59:34,361 - SERIAL - DEBUG - Recv: T:91.67 E:0 B:137.4
2016-02-18 20:59:35,355 - SERIAL - DEBUG - Recv: T:91.51 E:0 B:137.5
2016-02-18 20:59:36,361 - SERIAL - DEBUG - Recv: T:91.38 E:0 B:137.5
2016-02-18 20:59:37,358 - SERIAL - DEBUG - Recv: T:91.23 E:0 B:137.6
2016-02-18 20:59:38,359 - SERIAL - DEBUG - Recv: T:91.09 E:0 B:137.7
2016-02-18 20:59:39,371 - SERIAL - DEBUG - Recv: T:90.96 E:0 B:137.7
2016-02-18 20:59:40,363 - SERIAL - DEBUG - Recv: T:90.81 E:0 B:137.9
2016-02-18 20:59:41,376 - SERIAL - DEBUG - Recv: T:90.68 E:0 B:137.9
2016-02-18 20:59:42,367 - SERIAL - DEBUG - Recv: T:90.53 E:0 B:137.9
2016-02-18 20:59:43,369 - SERIAL - DEBUG - Recv: T:90.38 E:0 B:138.0
2016-02-18 20:59:44,380 - SERIAL - DEBUG - Recv: T:90.24 E:0 B:138.1
2016-02-18 20:59:45,373 - SERIAL - DEBUG - Recv: T:90.08 E:0 B:138.1
2016-02-18 20:59:46,374 - SERIAL - DEBUG - Recv: T:89.92 E:0 B:138.1
2016-02-18 20:59:47,377 - SERIAL - DEBUG - Recv: T:89.79 E:0 B:138.2
2016-02-18 20:59:48,378 - SERIAL - DEBUG - Recv: T:89.63 E:0 B:138.3
2016-02-18 20:59:49,380 - SERIAL - DEBUG - Recv: T:89.44 E:0 B:138.3
2016-02-18 20:59:50,382 - SERIAL - DEBUG - Recv: T:89.34 E:0 B:138.3
2016-02-18 20:59:51,391 - SERIAL - DEBUG - Recv: T:89.18 E:0 B:138.4
2016-02-18 20:59:52,386 - SERIAL - DEBUG - Recv: T:89.05 E:0 B:138.5
2016-02-18 20:59:53,388 - SERIAL - DEBUG - Recv: T:88.88 E:0 B:138.5
2016-02-18 20:59:54,400 - SERIAL - DEBUG - Recv: T:88.74 E:0 B:138.5
2016-02-18 20:59:55,392 - SERIAL - DEBUG - Recv: T:88.61 E:0 B:138.5
2016-02-18 20:59:56,393 - SERIAL - DEBUG - Recv: T:88.44 E:0 B:138.6
2016-02-18 20:59:57,396 - SERIAL - DEBUG - Recv: T:88.30 E:0 B:138.6
2016-02-18 20:59:58,398 - SERIAL - DEBUG - Recv: T:88.16 E:0 B:138.6
2016-02-18 20:59:59,405 - SERIAL - DEBUG - Recv: T:88.03 E:0 B:138.7
2016-02-18 21:00:00,402 - SERIAL - DEBUG - Recv: T:87.88 E:0 B:138.7
2016-02-18 21:00:01,404 - SERIAL - DEBUG - Recv: T:87.81 E:0 B:138.7
2016-02-18 21:00:02,407 - SERIAL - DEBUG - Recv: T:87.66 E:0 B:138.7
2016-02-18 21:00:03,409 - SERIAL - DEBUG - Recv: T:87.50 E:0 B:138.7
2016-02-18 21:00:04,421 - SERIAL - DEBUG - Recv: T:87.38 E:0 B:138.7
2016-02-18 21:00:05,415 - SERIAL - DEBUG - Recv: T:87.27 E:0 B:138.7
2016-02-18 21:00:06,422 - SERIAL - DEBUG - Recv: T:87.11 E:0 B:138.7
2016-02-18 21:00:07,419 - SERIAL - DEBUG - Recv: T:87.01 E:0 B:138.8
2016-02-18 21:00:08,422 - SERIAL - DEBUG - Recv: T:86.83 E:0 B:138.8
2016-02-18 21:00:09,424 - SERIAL - DEBUG - Recv: T:86.72 E:0 B:138.7
2016-02-18 21:00:10,427 - SERIAL - DEBUG - Recv: T:86.58 E:0 B:138.8
2016-02-18 21:00:11,440 - SERIAL - DEBUG - Recv: T:86.44 E:0 B:138.8
2016-02-18 21:00:12,431 - SERIAL - DEBUG - Recv: T:86.31 E:0 B:138.8
2016-02-18 21:00:13,433 - SERIAL - DEBUG - Recv: T:86.15 E:0 B:138.8
2016-02-18 21:00:14,441 - SERIAL - DEBUG - Recv: T:86.02 E:0 B:138.8
2016-02-18 21:00:15,437 - SERIAL - DEBUG - Recv: T:85.89 E:0 B:138.8
2016-02-18 21:00:16,444 - SERIAL - DEBUG - Recv: T:85.79 E:0 B:138.8
2016-02-18 21:00:17,440 - SERIAL - DEBUG - Recv: T:85.67 E:0 B:138.8
2016-02-18 21:00:18,442 - SERIAL - DEBUG - Recv: T:85.57 E:0 B:138.8
2016-02-18 21:00:19,444 - SERIAL - DEBUG - Recv: T:85.44 E:0 B:138.8
2016-02-18 21:00:20,446 - SERIAL - DEBUG - Recv: T:85.30 E:0 B:138.7
2016-02-18 21:00:21,460 - SERIAL - DEBUG - Recv: T:85.16 E:0 B:138.7
2016-02-18 21:00:22,451 - SERIAL - DEBUG - Recv: T:85.02 E:0 B:138.7
2016-02-18 21:00:23,451 - SERIAL - DEBUG - Recv: T:84.87 E:0 B:138.7
2016-02-18 21:00:24,454 - SERIAL - DEBUG - Recv: T:84.76 E:0 B:138.7
2016-02-18 21:00:25,455 - SERIAL - DEBUG - Recv: T:84.69 E:0 B:138.7
2016-02-18 21:00:26,458 - SERIAL - DEBUG - Recv: T:84.57 E:0 B:138.7
2016-02-18 21:00:27,459 - SERIAL - DEBUG - Recv: T:84.42 E:0 B:138.7
2016-02-18 21:00:28,461 - SERIAL - DEBUG - Recv: T:84.28 E:0 B:138.7
2016-02-18 21:00:29,462 - SERIAL - DEBUG - Recv: T:84.17 E:0 B:138.7
2016-02-18 21:00:30,465 - SERIAL - DEBUG - Recv: T:84.03 E:0 B:138.7
2016-02-18 21:00:31,471 - SERIAL - DEBUG - Recv: T:83.91 E:0 B:138.7
2016-02-18 21:00:32,469 - SERIAL - DEBUG - Recv: T:83.83 E:0 B:138.8
2016-02-18 21:00:33,470 - SERIAL - DEBUG - Recv: T:83.69 E:0 B:138.8
2016-02-18 21:00:34,481 - SERIAL - DEBUG - Recv: T:83.54 E:0 B:138.8
2016-02-18 21:00:35,475 - SERIAL - DEBUG - Recv: T:83.42 E:0 B:138.8
2016-02-18 21:00:36,476 - SERIAL - DEBUG - Recv: T:83.30 E:0 B:138.8
2016-02-18 21:00:37,478 - SERIAL - DEBUG - Recv: T:83.21 E:0 B:138.8
2016-02-18 21:00:38,490 - SERIAL - DEBUG - Recv: T:83.09 E:0 B:138.8
2016-02-18 21:00:39,482 - SERIAL - DEBUG - Recv: T:82.94 E:0 B:138.9
2016-02-18 21:00:40,484 - SERIAL - DEBUG - Recv: T:82.80 E:0 B:139.0
2016-02-18 21:00:41,487 - SERIAL - DEBUG - Recv: T:82.67 E:0 B:139.0
2016-02-18 21:00:42,489 - SERIAL - DEBUG - Recv: T:82.60 E:0 B:139.0
2016-02-18 21:00:43,491 - SERIAL - DEBUG - Recv: T:82.49 E:0 B:139.0
2016-02-18 21:00:44,502 - SERIAL - DEBUG - Recv: T:82.35 E:0 B:139.1
2016-02-18 21:00:45,496 - SERIAL - DEBUG - Recv: T:82.20 E:0 B:139.2
2016-02-18 21:00:46,498 - SERIAL - DEBUG - Recv: T:82.12 E:0 B:139.2
2016-02-18 21:00:47,501 - SERIAL - DEBUG - Recv: T:82.05 E:0 B:139.2
2016-02-18 21:00:48,503 - SERIAL - DEBUG - Recv: T:81.91 E:0 B:139.2
2016-02-18 21:00:49,506 - SERIAL - DEBUG - Recv: T:81.76 E:0 B:139.3
2016-02-18 21:00:50,508 - SERIAL - DEBUG - Recv: T:81.63 E:0 B:139.4
2016-02-18 21:00:51,518 - SERIAL - DEBUG - Recv: T:81.54 E:0 B:139.4
2016-02-18 21:00:52,513 - SERIAL - DEBUG - Recv: T:81.46 E:0 B:139.4
2016-02-18 21:00:53,516 - SERIAL - DEBUG - Recv: T:81.31 E:0 B:139.6
2016-02-18 21:00:54,527 - SERIAL - DEBUG - Recv: T:81.18 E:0 B:139.6
2016-02-18 21:00:55,519 - SERIAL - DEBUG - Recv: T:81.16 E:0 B:139.6
2016-02-18 21:00:56,531 - SERIAL - DEBUG - Recv: T:81.02 E:0 B:139.7
2016-02-18 21:00:57,523 - SERIAL - DEBUG - Recv: T:80.88 E:0 B:139.8
2016-02-18 21:00:58,525 - SERIAL - DEBUG - Recv: T:80.76 E:0 B:139.8
2016-02-18 21:00:59,531 - SERIAL - DEBUG - Recv: T:80.69 E:0 B:139.8
2016-02-18 21:01:00,528 - SERIAL - DEBUG - Recv: T:80.57 E:0 B:139.9
2016-02-18 21:01:01,530 - SERIAL - DEBUG - Recv: T:80.43 E:0 B:140.0
2016-02-18 21:01:02,532 - SERIAL - DEBUG - Recv: T:80.31 E:0 B:140.0
2016-02-18 21:01:03,535 - SERIAL - DEBUG - Recv: T:80.26 E:0 B:140.1
2016-02-18 21:01:04,546 - SERIAL - DEBUG - Recv: T:80.13 E:0 B:140.2
2016-02-18 21:01:05,538 - SERIAL - DEBUG - Recv: T:80.00 E:0 B:140.2
2016-02-18 21:01:06,539 - SERIAL - DEBUG - Recv: T:79.96 E:0 B:140.3
2016-02-18 21:01:07,542 - SERIAL - DEBUG - Recv: T:79.82 E:0 B:140.4
2016-02-18 21:01:08,544 - SERIAL - DEBUG - Recv: T:79.67 E:0 B:140.4
2016-02-18 21:01:09,546 - SERIAL - DEBUG - Recv: T:79.66 E:0 B:140.4
2016-02-18 21:01:10,547 - SERIAL - DEBUG - Recv: T:79.51 E:0 B:140.6
2016-02-18 21:01:11,558 - SERIAL - DEBUG - Recv: T:79.39 E:0 B:140.6
2016-02-18 21:01:12,551 - SERIAL - DEBUG - Recv: T:79.32 E:0 B:140.7
2016-02-18 21:01:13,553 - SERIAL - DEBUG - Recv: T:79.20 E:0 B:140.8
2016-02-18 21:01:14,561 - SERIAL - DEBUG - Recv: T:79.06 E:0 B:140.8
2016-02-18 21:01:15,557 - SERIAL - DEBUG - Recv: T:79.00 E:0 B:140.9
2016-02-18 21:01:16,568 - SERIAL - DEBUG - Recv: T:78.88 E:0 B:141.0
2016-02-18 21:01:17,560 - SERIAL - DEBUG - Recv: T:78.73 E:0 B:141.0
2016-02-18 21:01:18,562 - SERIAL - DEBUG - Recv: T:78.73 E:0 B:141.1
2016-02-18 21:01:19,573 - SERIAL - DEBUG - Recv: T:78.57 E:0 B:141.2
2016-02-18 21:01:20,566 - SERIAL - DEBUG - Recv: T:78.48 E:0 B:141.2
2016-02-18 21:01:21,576 - SERIAL - DEBUG - Recv: T:78.41 E:0 B:141.3
2016-02-18 21:01:22,571 - SERIAL - DEBUG - Recv: T:78.26 E:0 B:141.4
2016-02-18 21:01:23,573 - SERIAL - DEBUG - Recv: T:78.21 E:0 B:141.4
2016-02-18 21:01:24,575 - SERIAL - DEBUG - Recv: T:78.09 E:0 B:141.6
2016-02-18 21:01:25,578 - SERIAL - DEBUG - Recv: T:77.95 E:0 B:141.6
2016-02-18 21:01:26,581 - SERIAL - DEBUG - Recv: T:77.88 E:0 B:141.6
2016-02-18 21:01:27,591 - SERIAL - DEBUG - Recv: T:77.79 E:0 B:141.8
2016-02-18 21:01:28,585 - SERIAL - DEBUG - Recv: T:77.64 E:0 B:141.9
2016-02-18 21:01:29,596 - SERIAL - DEBUG - Recv: T:77.61 E:0 B:141.9
2016-02-18 21:01:30,591 - SERIAL - DEBUG - Recv: T:77.48 E:0 B:142.1
2016-02-18 21:01:31,601 - SERIAL - DEBUG - Recv: T:77.39 E:0 B:142.1
2016-02-18 21:01:32,595 - SERIAL - DEBUG - Recv: T:77.31 E:0 B:142.2
2016-02-18 21:01:33,597 - SERIAL - DEBUG - Recv: T:77.16 E:0 B:142.2
2016-02-18 21:01:34,608 - SERIAL - DEBUG - Recv: T:77.16 E:0 B:142.3
2016-02-18 21:01:35,600 - SERIAL - DEBUG - Recv: T:77.01 E:0 B:142.4
2016-02-18 21:01:36,603 - SERIAL - DEBUG - Recv: T:76.89 E:0 B:142.5
2016-02-18 21:01:37,604 - SERIAL - DEBUG - Recv: T:76.84 E:0 B:142.5
2016-02-18 21:01:38,612 - SERIAL - DEBUG - Recv: T:76.70 E:0 B:142.7
2016-02-18 21:01:39,609 - SERIAL - DEBUG - Recv: T:76.63 E:0 B:142.7
2016-02-18 21:01:40,621 - SERIAL - DEBUG - Recv: T:76.54 E:0 B:142.8
2016-02-18 21:01:41,612 - SERIAL - DEBUG - Recv: T:76.44 E:0 B:142.9
2016-02-18 21:01:42,614 - SERIAL - DEBUG - Recv: T:76.38 E:0 B:142.9
2016-02-18 21:01:43,616 - SERIAL - DEBUG - Recv: T:76.25 E:0 B:143.1
2016-02-18 21:01:44,619 - SERIAL - DEBUG - Recv: T:76.21 E:0 B:143.1
2016-02-18 21:01:45,621 - SERIAL - DEBUG - Recv: T:76.09 E:0 B:143.2
2016-02-18 21:01:46,622 - SERIAL - DEBUG - Recv: T:76.03 E:0 B:143.3
2016-02-18 21:01:47,624 - SERIAL - DEBUG - Recv: T:75.91 E:0 B:143.3
2016-02-18 21:01:48,626 - SERIAL - DEBUG - Recv: T:75.88 E:0 B:143.5
2016-02-18 21:01:49,631 - SERIAL - DEBUG - Recv: T:75.75 E:0 B:143.5
2016-02-18 21:01:50,630 - SERIAL - DEBUG - Recv: T:75.63 E:0 B:143.6
2016-02-18 21:01:51,631 - SERIAL - DEBUG - Recv: T:75.59 E:0 B:143.7
2016-02-18 21:01:52,634 - SERIAL - DEBUG - Recv: T:75.47 E:0 B:143.7
2016-02-18 21:01:53,635 - SERIAL - DEBUG - Recv: T:75.38 E:0 B:143.9
2016-02-18 21:01:54,637 - SERIAL - DEBUG - Recv: T:75.29 E:0 B:143.9
2016-02-18 21:01:55,639 - SERIAL - DEBUG - Recv: T:75.21 E:0 B:144.0
2016-02-18 21:01:56,641 - SERIAL - DEBUG - Recv: T:75.13 E:0 B:144.1
2016-02-18 21:01:57,643 - SERIAL - DEBUG - Recv: T:75.08 E:0 B:144.2
2016-02-18 21:01:58,645 - SERIAL - DEBUG - Recv: T:74.97 E:0 B:144.2
2016-02-18 21:01:59,646 - SERIAL - DEBUG - Recv: T:74.92 E:0 B:144.3
2016-02-18 21:02:00,649 - SERIAL - DEBUG - Recv: T:74.80 E:0 B:144.4
2016-02-18 21:02:01,650 - SERIAL - DEBUG - Recv: T:74.75 E:0 B:144.5
2016-02-18 21:02:02,653 - SERIAL - DEBUG - Recv: T:74.63 E:0 B:144.6
2016-02-18 21:02:03,655 - SERIAL - DEBUG - Recv: T:74.55 E:0 B:144.6
2016-02-18 21:02:04,657 - SERIAL - DEBUG - Recv: T:74.47 E:0 B:144.7
2016-02-18 21:02:05,659 - SERIAL - DEBUG - Recv: T:74.34 E:0 B:144.8
2016-02-18 21:02:06,671 - SERIAL - DEBUG - Recv: T:74.30 E:0 B:144.8
2016-02-18 21:02:07,664 - SERIAL - DEBUG - Recv: T:74.15 E:0 B:144.9
2016-02-18 21:02:08,667 - SERIAL - DEBUG - Recv: T:74.11 E:0 B:145.0
2016-02-18 21:02:09,669 - SERIAL - DEBUG - Recv: T:73.99 E:0 B:145.1
2016-02-18 21:02:10,671 - SERIAL - DEBUG - Recv: T:73.96 E:0 B:145.2
2016-02-18 21:02:11,681 - SERIAL - DEBUG - Recv: T:73.88 E:0 B:145.2
2016-02-18 21:02:12,676 - SERIAL - DEBUG - Recv: T:73.80 E:0 B:145.3
2016-02-18 21:02:13,679 - SERIAL - DEBUG - Recv: T:73.67 E:0 B:145.4
2016-02-18 21:02:14,690 - SERIAL - DEBUG - Recv: T:73.63 E:0 B:145.5
2016-02-18 21:02:15,684 - SERIAL - DEBUG - Recv: T:73.53 E:0 B:145.6
2016-02-18 21:02:16,691 - SERIAL - DEBUG - Recv: T:73.46 E:0 B:145.7
2016-02-18 21:02:17,687 - SERIAL - DEBUG - Recv: T:73.37 E:0 B:145.7
2016-02-18 21:02:18,689 - SERIAL - DEBUG - Recv: T:73.29 E:0 B:145.9
2016-02-18 21:02:19,701 - SERIAL - DEBUG - Recv: T:73.27 E:0 B:145.9
2016-02-18 21:02:20,692 - SERIAL - DEBUG - Recv: T:73.14 E:0 B:146.0
2016-02-18 21:02:21,701 - SERIAL - DEBUG - Recv: T:73.06 E:0 B:146.1
2016-02-18 21:02:22,696 - SERIAL - DEBUG - Recv: T:72.96 E:0 B:146.1
2016-02-18 21:02:23,698 - SERIAL - DEBUG - Recv: T:72.91 E:0 B:146.3
2016-02-18 21:02:24,709 - SERIAL - DEBUG - Recv: T:72.79 E:0 B:146.4
2016-02-18 21:02:25,703 - SERIAL - DEBUG - Recv: T:72.78 E:0 B:146.4
2016-02-18 21:02:26,704 - SERIAL - DEBUG - Recv: T:72.64 E:0 B:146.6
2016-02-18 21:02:27,706 - SERIAL - DEBUG - Recv: T:72.62 E:0 B:146.6
2016-02-18 21:02:28,708 - SERIAL - DEBUG - Recv: T:72.51 E:0 B:146.7
2016-02-18 21:02:29,715 - SERIAL - DEBUG - Recv: T:72.46 E:0 B:146.8
2016-02-18 21:02:30,711 - SERIAL - DEBUG - Recv: T:72.41 E:0 B:146.8
2016-02-18 21:02:31,714 - SERIAL - DEBUG - Recv: T:72.29 E:0 B:146.9
2016-02-18 21:02:32,715 - SERIAL - DEBUG - Recv: T:72.26 E:0 B:147.1
2016-02-18 21:02:33,718 - SERIAL - DEBUG - Recv: T:72.12 E:0 B:147.1
2016-02-18 21:02:34,729 - SERIAL - DEBUG - Recv: T:72.12 E:0 B:147.2
2016-02-18 21:02:35,722 - SERIAL - DEBUG - Recv: T:72.01 E:0 B:147.3
2016-02-18 21:02:36,731 - SERIAL - DEBUG - Recv: T:71.96 E:0 B:147.3
2016-02-18 21:02:37,726 - SERIAL - DEBUG - Recv: T:71.92 E:0 B:147.4
2016-02-18 21:02:38,738 - SERIAL - DEBUG - Recv: T:71.79 E:0 B:147.5
2016-02-18 21:02:39,729 - SERIAL - DEBUG - Recv: T:71.77 E:0 B:147.5
2016-02-18 21:02:40,730 - SERIAL - DEBUG - Recv: T:71.62 E:0 B:147.6
2016-02-18 21:02:41,733 - SERIAL - DEBUG - Recv: T:71.62 E:0 B:147.8
2016-02-18 21:02:42,742 - SERIAL - DEBUG - Recv: T:71.50 E:0 B:147.8
2016-02-18 21:02:43,737 - SERIAL - DEBUG - Recv: T:71.44 E:0 B:147.9
2016-02-18 21:02:44,739 - SERIAL - DEBUG - Recv: T:71.35 E:0 B:148.0
2016-02-18 21:02:45,741 - SERIAL - DEBUG - Recv: T:71.30 E:0 B:148.0
2016-02-18 21:02:46,752 - SERIAL - DEBUG - Recv: T:71.27 E:0 B:148.0
2016-02-18 21:02:46,823 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:02:46,834 - SERIAL - DEBUG - Send: N6 M109 S255.0*114
2016-02-18 21:02:47,857 - SERIAL - DEBUG - Recv: T:71.1 E:0 W:?
2016-02-18 21:02:48,849 - SERIAL - DEBUG - Recv: T:71.3 E:0 W:?
2016-02-18 21:02:49,860 - SERIAL - DEBUG - Recv: T:71.8 E:0 W:?
2016-02-18 21:02:50,857 - SERIAL - DEBUG - Recv: T:72.6 E:0 W:?
2016-02-18 21:02:51,869 - SERIAL - DEBUG - Recv: T:73.9 E:0 W:?
2016-02-18 21:02:52,858 - SERIAL - DEBUG - Recv: T:75.6 E:0 W:?
2016-02-18 21:02:53,859 - SERIAL - DEBUG - Recv: T:77.3 E:0 W:?
2016-02-18 21:02:54,869 - SERIAL - DEBUG - Recv: T:79.4 E:0 W:?
2016-02-18 21:02:55,863 - SERIAL - DEBUG - Recv: T:81.4 E:0 W:?
2016-02-18 21:02:56,872 - SERIAL - DEBUG - Recv: T:83.8 E:0 W:?
2016-02-18 21:02:57,867 - SERIAL - DEBUG - Recv: T:86.3 E:0 W:?
2016-02-18 21:02:58,868 - SERIAL - DEBUG - Recv: T:88.6 E:0 W:?
2016-02-18 21:02:59,880 - SERIAL - DEBUG - Recv: T:91.3 E:0 W:?
2016-02-18 21:03:00,871 - SERIAL - DEBUG - Recv: T:94.0 E:0 W:?
2016-02-18 21:03:01,882 - SERIAL - DEBUG - Recv: T:96.4 E:0 W:?
2016-02-18 21:03:02,876 - SERIAL - DEBUG - Recv: T:99.2 E:0 W:?
2016-02-18 21:03:03,878 - SERIAL - DEBUG - Recv: T:102.1 E:0 W:?
2016-02-18 21:03:04,880 - SERIAL - DEBUG - Recv: T:104.5 E:0 W:?
2016-02-18 21:03:05,883 - SERIAL - DEBUG - Recv: T:107.4 E:0 W:?
2016-02-18 21:03:06,883 - SERIAL - DEBUG - Recv: T:110.2 E:0 W:?
2016-02-18 21:03:07,886 - SERIAL - DEBUG - Recv: T:112.7 E:0 W:?
2016-02-18 21:03:08,887 - SERIAL - DEBUG - Recv: T:115.5 E:0 W:?
2016-02-18 21:03:09,897 - SERIAL - DEBUG - Recv: T:117.9 E:0 W:?
2016-02-18 21:03:10,890 - SERIAL - DEBUG - Recv: T:120.7 E:0 W:?
2016-02-18 21:03:11,901 - SERIAL - DEBUG - Recv: T:123.5 E:0 W:?
2016-02-18 21:03:12,895 - SERIAL - DEBUG - Recv: T:125.8 E:0 W:?
2016-02-18 21:03:13,897 - SERIAL - DEBUG - Recv: T:128.6 E:0 W:?
2016-02-18 21:03:14,899 - SERIAL - DEBUG - Recv: T:131.3 E:0 W:?
2016-02-18 21:03:15,901 - SERIAL - DEBUG - Recv: T:133.6 E:0 W:?
2016-02-18 21:03:16,912 - SERIAL - DEBUG - Recv: T:136.3 E:0 W:?
2016-02-18 21:03:17,907 - SERIAL - DEBUG - Recv: T:138.9 E:0 W:?
2016-02-18 21:03:18,909 - SERIAL - DEBUG - Recv: T:141.2 E:0 W:?
2016-02-18 21:03:19,920 - SERIAL - DEBUG - Recv: T:143.8 E:0 W:?
2016-02-18 21:03:20,914 - SERIAL - DEBUG - Recv: T:146.3 E:0 W:?
2016-02-18 21:03:21,916 - SERIAL - DEBUG - Recv: T:148.5 E:0 W:?
2016-02-18 21:03:22,918 - SERIAL - DEBUG - Recv: T:151.0 E:0 W:?
2016-02-18 21:03:23,921 - SERIAL - DEBUG - Recv: T:153.5 E:0 W:?
2016-02-18 21:03:24,931 - SERIAL - DEBUG - Recv: T:155.6 E:0 W:?
2016-02-18 21:03:25,925 - SERIAL - DEBUG - Recv: T:158.0 E:0 W:?
2016-02-18 21:03:26,937 - SERIAL - DEBUG - Recv: T:160.1 E:0 W:?
2016-02-18 21:03:27,929 - SERIAL - DEBUG - Recv: T:162.6 E:0 W:?
2016-02-18 21:03:28,931 - SERIAL - DEBUG - Recv: T:164.8 E:0 W:?
2016-02-18 21:03:29,932 - SERIAL - DEBUG - Recv: T:166.9 E:0 W:?
2016-02-18 21:03:30,935 - SERIAL - DEBUG - Recv: T:169.3 E:0 W:?
2016-02-18 21:03:31,941 - SERIAL - DEBUG - Recv: T:171.6 E:0 W:?
2016-02-18 21:03:32,939 - SERIAL - DEBUG - Recv: T:173.6 E:0 W:?
2016-02-18 21:03:33,940 - SERIAL - DEBUG - Recv: T:175.9 E:0 W:?
2016-02-18 21:03:34,948 - SERIAL - DEBUG - Recv: T:178.2 E:0 W:?
2016-02-18 21:03:35,943 - SERIAL - DEBUG - Recv: T:180.1 E:0 W:?
2016-02-18 21:03:36,956 - SERIAL - DEBUG - Recv: T:182.3 E:0 W:?
2016-02-18 21:03:37,947 - SERIAL - DEBUG - Recv: T:184.5 E:0 W:?
2016-02-18 21:03:38,959 - SERIAL - DEBUG - Recv: T:186.4 E:0 W:?
2016-02-18 21:03:39,951 - SERIAL - DEBUG - Recv: T:188.5 E:0 W:?
2016-02-18 21:03:40,962 - SERIAL - DEBUG - Recv: T:190.4 E:0 W:?
2016-02-18 21:03:41,955 - SERIAL - DEBUG - Recv: T:192.5 E:0 W:?
2016-02-18 21:03:42,957 - SERIAL - DEBUG - Recv: T:194.5 E:0 W:?
2016-02-18 21:03:43,959 - SERIAL - DEBUG - Recv: T:196.4 E:0 W:?
2016-02-18 21:03:44,970 - SERIAL - DEBUG - Recv: T:198.4 E:0 W:?
2016-02-18 21:03:45,963 - SERIAL - DEBUG - Recv: T:200.3 E:0 W:?
2016-02-18 21:03:46,971 - SERIAL - DEBUG - Recv: T:202.1 E:0 W:?
2016-02-18 21:03:47,966 - SERIAL - DEBUG - Recv: T:204.2 E:0 W:?
2016-02-18 21:03:48,968 - SERIAL - DEBUG - Recv: T:206.1 E:0 W:?
2016-02-18 21:03:49,970 - SERIAL - DEBUG - Recv: T:207.8 E:0 W:?
2016-02-18 21:03:50,972 - SERIAL - DEBUG - Recv: T:209.6 E:0 W:?
2016-02-18 21:03:51,974 - SERIAL - DEBUG - Recv: T:211.6 E:0 W:?
2016-02-18 21:03:52,980 - SERIAL - DEBUG - Recv: T:213.3 E:0 W:?
2016-02-18 21:03:53,978 - SERIAL - DEBUG - Recv: T:215.1 E:0 W:?
2016-02-18 21:03:54,981 - SERIAL - DEBUG - Recv: T:217.2 E:0 W:?
2016-02-18 21:03:55,983 - SERIAL - DEBUG - Recv: T:218.6 E:0 W:?
2016-02-18 21:03:56,991 - SERIAL - DEBUG - Recv: T:220.4 E:0 W:?
2016-02-18 21:03:57,988 - SERIAL - DEBUG - Recv: T:222.3 E:0 W:?
2016-02-18 21:03:58,991 - SERIAL - DEBUG - Recv: T:223.9 E:0 W:?
2016-02-18 21:03:59,993 - SERIAL - DEBUG - Recv: T:225.5 E:0 W:?
2016-02-18 21:04:01,008 - SERIAL - DEBUG - Recv: T:227.2 E:0 W:?
2016-02-18 21:04:01,998 - SERIAL - DEBUG - Recv: T:228.9 E:0 W:?
2016-02-18 21:04:03,000 - SERIAL - DEBUG - Recv: T:230.6 E:0 W:?
2016-02-18 21:04:04,003 - SERIAL - DEBUG - Recv: T:232.3 E:0 W:?
2016-02-18 21:04:05,005 - SERIAL - DEBUG - Recv: T:233.6 E:0 W:?
2016-02-18 21:04:06,015 - SERIAL - DEBUG - Recv: T:235.3 E:0 W:?
2016-02-18 21:04:07,008 - SERIAL - DEBUG - Recv: T:236.7 E:0 W:?
2016-02-18 21:04:08,020 - SERIAL - DEBUG - Recv: T:238.5 E:0 W:?
2016-02-18 21:04:09,012 - SERIAL - DEBUG - Recv: T:240.2 E:0 W:?
2016-02-18 21:04:10,015 - SERIAL - DEBUG - Recv: T:241.6 E:0 W:?
2016-02-18 21:04:11,021 - SERIAL - DEBUG - Recv: T:243.0 E:0 W:?
2016-02-18 21:04:12,018 - SERIAL - DEBUG - Recv: T:244.9 E:0 W:?
2016-02-18 21:04:13,024 - SERIAL - DEBUG - Recv: T:246.3 E:0 W:?
2016-02-18 21:04:14,023 - SERIAL - DEBUG - Recv: T:247.8 E:0 W:?
2016-02-18 21:04:15,024 - SERIAL - DEBUG - Recv: T:249.3 E:0 W:?
2016-02-18 21:04:16,026 - SERIAL - DEBUG - Recv: T:251.3 E:0 W:?
2016-02-18 21:04:17,027 - SERIAL - DEBUG - Recv: T:252.5 E:0 W:?
2016-02-18 21:04:18,036 - SERIAL - DEBUG - Recv: T:254.0 E:0 W:?
2016-02-18 21:04:19,032 - SERIAL - DEBUG - Recv: T:254.3 E:0 W:59
2016-02-18 21:04:20,035 - SERIAL - DEBUG - Recv: T:255.7 E:0 W:58
2016-02-18 21:04:21,036 - SERIAL - DEBUG - Recv: T:255.6 E:0 W:57
2016-02-18 21:04:22,038 - SERIAL - DEBUG - Recv: T:255.4 E:0 W:56
2016-02-18 21:04:23,049 - SERIAL - DEBUG - Recv: T:255.0 E:0 W:55
2016-02-18 21:04:24,042 - SERIAL - DEBUG - Recv: T:254.7 E:0 W:54
2016-02-18 21:04:25,043 - SERIAL - DEBUG - Recv: T:254.2 E:0 W:53
2016-02-18 21:04:26,051 - SERIAL - DEBUG - Recv: T:253.8 E:0 W:52
2016-02-18 21:04:27,046 - SERIAL - DEBUG - Recv: T:253.3 E:0 W:51
2016-02-18 21:04:28,059 - SERIAL - DEBUG - Recv: T:253.4 E:0 W:50
2016-02-18 21:04:29,050 - SERIAL - DEBUG - Recv: T:253.3 E:0 W:49
2016-02-18 21:04:30,053 - SERIAL - DEBUG - Recv: T:253.4 E:0 W:48
2016-02-18 21:04:31,061 - SERIAL - DEBUG - Recv: T:253.6 E:0 W:47
2016-02-18 21:04:32,057 - SERIAL - DEBUG - Recv: T:253.8 E:0 W:46
2016-02-18 21:04:33,058 - SERIAL - DEBUG - Recv: T:254.2 E:0 W:45
2016-02-18 21:04:34,060 - SERIAL - DEBUG - Recv: T:254.7 E:0 W:44
2016-02-18 21:04:35,063 - SERIAL - DEBUG - Recv: T:254.8 E:0 W:43
2016-02-18 21:04:36,071 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:42
2016-02-18 21:04:37,068 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:41
2016-02-18 21:04:38,080 - SERIAL - DEBUG - Recv: T:255.9 E:0 W:40
2016-02-18 21:04:39,073 - SERIAL - DEBUG - Recv: T:255.7 E:0 W:39
2016-02-18 21:04:40,075 - SERIAL - DEBUG - Recv: T:255.5 E:0 W:38
2016-02-18 21:04:41,087 - SERIAL - DEBUG - Recv: T:255.8 E:0 W:37
2016-02-18 21:04:42,080 - SERIAL - DEBUG - Recv: T:255.6 E:0 W:36
2016-02-18 21:04:43,081 - SERIAL - DEBUG - Recv: T:255.5 E:0 W:35
2016-02-18 21:04:44,084 - SERIAL - DEBUG - Recv: T:255.3 E:0 W:34
2016-02-18 21:04:45,086 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:33
2016-02-18 21:04:46,098 - SERIAL - DEBUG - Recv: T:255.2 E:0 W:32
2016-02-18 21:04:47,092 - SERIAL - DEBUG - Recv: T:255.0 E:0 W:31
2016-02-18 21:04:48,098 - SERIAL - DEBUG - Recv: T:254.8 E:0 W:30
2016-02-18 21:04:49,095 - SERIAL - DEBUG - Recv: T:255.0 E:0 W:29
2016-02-18 21:04:50,101 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:28
2016-02-18 21:04:51,099 - SERIAL - DEBUG - Recv: T:254.9 E:0 W:27
2016-02-18 21:04:52,100 - SERIAL - DEBUG - Recv: T:255.0 E:0 W:26
2016-02-18 21:04:53,103 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:25
2016-02-18 21:04:54,104 - SERIAL - DEBUG - Recv: T:255.2 E:0 W:24
2016-02-18 21:04:55,107 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:23
2016-02-18 21:04:56,118 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:22
2016-02-18 21:04:57,110 - SERIAL - DEBUG - Recv: T:255.2 E:0 W:21
2016-02-18 21:04:58,111 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:20
2016-02-18 21:04:59,114 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:19
2016-02-18 21:05:00,115 - SERIAL - DEBUG - Recv: T:255.2 E:0 W:18
2016-02-18 21:05:01,117 - SERIAL - DEBUG - Recv: T:255.3 E:0 W:17
2016-02-18 21:05:02,119 - SERIAL - DEBUG - Recv: T:255.4 E:0 W:16
2016-02-18 21:05:03,122 - SERIAL - DEBUG - Recv: T:255.2 E:0 W:15
2016-02-18 21:05:04,128 - SERIAL - DEBUG - Recv: T:255.2 E:0 W:14
2016-02-18 21:05:05,125 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:13
2016-02-18 21:05:06,126 - SERIAL - DEBUG - Recv: T:255.3 E:0 W:12
2016-02-18 21:05:07,129 - SERIAL - DEBUG - Recv: T:255.0 E:0 W:11
2016-02-18 21:05:08,143 - SERIAL - DEBUG - Recv: T:255.0 E:0 W:10
2016-02-18 21:05:09,133 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:9
2016-02-18 21:05:10,144 - SERIAL - DEBUG - Recv: T:255.2 E:0 W:8
2016-02-18 21:05:11,136 - SERIAL - DEBUG - Recv: T:255.3 E:0 W:7
2016-02-18 21:05:12,149 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:6
2016-02-18 21:05:13,144 - SERIAL - DEBUG - Recv: T:255.2 E:0 W:5
2016-02-18 21:05:14,150 - SERIAL - DEBUG - Recv: T:255.2 E:0 W:4
2016-02-18 21:05:15,148 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:3
2016-02-18 21:05:16,153 - SERIAL - DEBUG - Recv: T:255.0 E:0 W:2
2016-02-18 21:05:17,150 - SERIAL - DEBUG - Recv: T:255.1 E:0 W:1
2016-02-18 21:05:18,159 - SERIAL - DEBUG - Recv: T:255.0 E:0 W:0
2016-02-18 21:05:18,558 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,569 - SERIAL - DEBUG - Send: N7 G92 E0*64
2016-02-18 21:05:18,578 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,587 - SERIAL - DEBUG - Send: N8 G1 E3 F50*21
2016-02-18 21:05:18,597 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,607 - SERIAL - DEBUG - Send: N9 G1 E-1 F1200*61
2016-02-18 21:05:18,615 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,627 - SERIAL - DEBUG - Send: N10 G1 X40.0 F4000*25
2016-02-18 21:05:18,635 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,645 - SERIAL - DEBUG - Send: N11 G1 Z0.3*79
2016-02-18 21:05:18,655 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,672 - SERIAL - DEBUG - Send: N12 M140 S148.0*69
2016-02-18 21:05:18,690 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,704 - SERIAL - DEBUG - Send: N13 M141 S30.0*123
2016-02-18 21:05:18,713 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,723 - SERIAL - DEBUG - Send: N14 M142 S0.0*76
2016-02-18 21:05:18,732 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,742 - SERIAL - DEBUG - Send: N15 M104 S255.0*77
2016-02-18 21:05:18,751 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,762 - SERIAL - DEBUG - Send: N16 G1 X-74.211 Y-62.852 Z0.25 F9000.0*6
2016-02-18 21:05:18,776 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,785 - SERIAL - DEBUG - Send: N17 G1 E1.25 F1800.0*18
2016-02-18 21:05:18,794 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,804 - SERIAL - DEBUG - Send: N18 M101*26
2016-02-18 21:05:18,813 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,824 - SERIAL - DEBUG - Send: N19 G1 X39.754 Y58.22 Z0.25 F535.8 E2.6248*113
2016-02-18 21:05:18,835 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,845 - SERIAL - DEBUG - Send: N20 G1 X55.934 Y74.4 Z0.25 F535.8 E0.3612*79
2016-02-18 21:05:18,857 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,867 - SERIAL - DEBUG - Send: N21 G1 X58.723 Y72.948 Z0.25 F535.8 E0.0496*65
2016-02-18 21:05:18,880 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,890 - SERIAL - DEBUG - Send: N22 G1 X72.949 Y58.722 Z0.25 F535.8 E0.3176*74
2016-02-18 21:05:18,901 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,911 - SERIAL - DEBUG - Send: N23 G1 X74.397 Y55.932 Z0.25 F535.8 E0.0496*78
2016-02-18 21:05:18,921 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,932 - SERIAL - DEBUG - Send: N24 G1 X58.219 Y39.754 Z0.25 F535.8 E0.3612*73
2016-02-18 21:05:18,942 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,953 - SERIAL - DEBUG - Send: N25 G1 X-62.853 Y-74.213 Z0.25 F535.8 E2.6249*71
2016-02-18 21:05:18,963 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,974 - SERIAL - DEBUG - Send: N26 G1 X-65.66 Y-73.04 Z0.25 F535.8 E0.048*121
2016-02-18 21:05:18,984 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:18,994 - SERIAL - DEBUG - Send: N27 G1 X-73.041 Y-65.659 Z0.25 F535.8 E0.1648*68
2016-02-18 21:05:19,005 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:19,014 - SERIAL - DEBUG - Send: N28 G1 X-74.211 Y-62.852 Z0.25 F535.8 E0.048*126
2016-02-18 21:05:19,026 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:19,036 - SERIAL - DEBUG - Send: N29 G1 E-1.25 F1800.0*50
2016-02-18 21:05:22,201 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:22,217 - SERIAL - DEBUG - Send: N30 M105*20
2016-02-18 21:05:22,273 - SERIAL - DEBUG - Recv: ok T:255.3 /255.0 B:148.0 /148.0 T0:255.3 /255.0 @:8.50 B@:162.94
2016-02-18 21:05:22,305 - SERIAL - DEBUG - Send: N31 M103*19
2016-02-18 21:05:22,314 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:22,324 - SERIAL - DEBUG - Send: N32 G1 X-61.753 Y-68.689 Z0.25 F9000.0*5
2016-02-18 21:05:22,334 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:22,344 - SERIAL - DEBUG - Send: N33 G1 E1.25 F1800.0*20
2016-02-18 21:05:24,352 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:24,372 - SERIAL - DEBUG - Send: N34 M101*20
2016-02-18 21:05:24,426 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:24,436 - SERIAL - DEBUG - Send: N35 G1 X56.144 Y43.192 Z0.25 F535.8 E2.5658*71
2016-02-18 21:05:24,447 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:24,457 - SERIAL - DEBUG - Send: N36 G1 X56.603 Y42.734 Z0.25 F535.8 E0.0102*68
2016-02-18 21:05:29,710 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:29,729 - SERIAL - DEBUG - Send: N37 M105*19
2016-02-18 21:05:29,777 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:9.92 B@:149.50
2016-02-18 21:05:29,809 - SERIAL - DEBUG - Send: N38 G1 X70.472 Y56.603 Z0.25 F535.8 E0.3096*69
2016-02-18 21:05:29,820 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:05:29,830 - SERIAL - DEBUG - Send: N39 G1 X56.603 Y70.472 Z0.25 F535.8 E0.3096*68
2016-02-18 21:05:49,852 - SERIAL - DEBUG - Communication timeout during printing, forcing a line
2016-02-18 21:05:49,859 - SERIAL - DEBUG - Send: N40 M105*19
The correct thing to do in this type of protocol is send line 39 again, but a timeout due to the buffer being full is hopeless
because Marlin is not listening.
--> sending N39 again would have bad consequences though if the line indeed had been received and processed, so some "no-op"
command that has the added side-effect of telling us something about the state of the machine (if it gets answered that
is) is less problematic
2016-02-18 21:06:09,885 - SERIAL - DEBUG - Communication timeout during printing, forcing a line
2016-02-18 21:06:09,892 - SERIAL - DEBUG - Send: N41 M105*18
The line number shouldn't increment unless you get an OK
--> That is an interesting point there. Thing is, if I'm just running into a timeout because the response by the firmware
got mangled so that the "ok" got attached to the line (mangled by a comms error, "some responseok" instead of "some
response\nok" for example, seems to happen a lot for some users), I'll basically trigger a resend that way. If I increase
the line number, I still might do that (if the former line indeed was not received at all and hence the firmware counter
not increased), but in cases where it's indeed just a command taking extremely long to be acknowledged or a case of a
mangled ok, it's ok
2016-02-18 21:06:29,939 - SERIAL - DEBUG - Communication timeout during printing, forcing a line
2016-02-18 21:06:29,947 - SERIAL - DEBUG - Send: N42 M105*17
2016-02-18 21:06:43,751 - SERIAL - DEBUG - Recv: ok
This OK is from line 39. The M105s were sent while the buffer was full so will not be seen by Marlin.
2016-02-18 21:06:43,766 - SERIAL - DEBUG - Send: N43 M105*16
2016-02-18 21:06:53,973 - SERIAL - DEBUG - Recv: ok T:255.2 /255.0 B:148.0 /148.0 T0:255.2 /255.0 @:7.29 B@:147.82
2016-02-18 21:06:54,017 - SERIAL - DEBUG - Send: N44 M105*23
2016-02-18 21:06:54,047 - SERIAL - DEBUG - Recv: ok T:255.2 /255.0 B:148.0 /148.0 T0:255.2 /255.0 @:7.29 B@:147.82
2016-02-18 21:06:54,076 - SERIAL - DEBUG - Send: N45 M105*22
2016-02-18 21:06:54,108 - SERIAL - DEBUG - Recv: ok T:255.2 /255.0 B:148.0 /148.0 T0:255.2 /255.0 @:7.29 B@:147.82
2016-02-18 21:06:54,137 - SERIAL - DEBUG - Send: N46 M105*21
2016-02-18 21:06:54,171 - SERIAL - DEBUG - Recv: ok T:255.2 /255.0 B:148.0 /148.0 T0:255.2 /255.0 @:7.29 B@:147.82
2016-02-18 21:06:54,200 - SERIAL - DEBUG - Send: N47 M105*20
2016-02-18 21:06:54,230 - SERIAL - DEBUG - Recv: ok T:255.1 /255.0 B:148.0 /148.0 T0:255.1 /255.0 @:7.90 B@:156.22
2016-02-18 21:06:54,261 - SERIAL - DEBUG - Send: N48 M105*27
2016-02-18 21:06:54,290 - SERIAL - DEBUG - Recv: ok T:255.1 /255.0 B:148.0 /148.0 T0:255.1 /255.0 @:7.90 B@:156.22
2016-02-18 21:06:54,326 - SERIAL - DEBUG - Send: N49 M105*26
2016-02-18 21:06:54,356 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:147.9 /148.0 T0:254.9 /255.0 @:8.91 B@:167.98
2016-02-18 21:06:54,385 - SERIAL - DEBUG - Send: N50 M105*18
2016-02-18 21:06:54,416 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:147.9 /148.0 T0:254.9 /255.0 @:8.91 B@:167.98
2016-02-18 21:06:54,445 - SERIAL - DEBUG - Send: N51 M105*19
2016-02-18 21:06:54,475 - SERIAL - DEBUG - Recv: ok T:255.1 /255.0 B:148.0 /148.0 T0:255.1 /255.0 @:7.90 B@:164.62
2016-02-18 21:06:54,517 - SERIAL - DEBUG - Send: N52 M105*16
2016-02-18 21:06:54,551 - SERIAL - DEBUG - Recv: ok T:255.1 /255.0 B:148.0 /148.0 T0:255.1 /255.0 @:7.90 B@:164.62
2016-02-18 21:06:54,581 - SERIAL - DEBUG - Send: N53 M105*17
2016-02-18 21:06:54,610 - SERIAL - DEBUG - Recv: ok T:255.1 /255.0 B:148.0 /148.0 T0:255.1 /255.0 @:7.90 B@:164.62
2016-02-18 21:06:54,639 - SERIAL - DEBUG - Send: N54 M105*22
2016-02-18 21:06:54,669 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.91 B@:152.86
2016-02-18 21:06:54,698 - SERIAL - DEBUG - Send: N55 M105*23
2016-02-18 21:06:54,732 - SERIAL - DEBUG - Recv: ok T:255.2 /255.0 B:148.0 /148.0 T0:255.2 /255.0 @:7.29 B@:147.82
2016-02-18 21:06:54,763 - SERIAL - DEBUG - Send: N56 M105*20
2016-02-18 21:06:54,794 - SERIAL - DEBUG - Recv: ok T:255.2 /255.0 B:148.0 /148.0 T0:255.2 /255.0 @:7.29 B@:147.82
2016-02-18 21:06:54,826 - SERIAL - DEBUG - Send: N57 M105*21
2016-02-18 21:06:54,855 - SERIAL - DEBUG - Recv: ok T:255.2 /255.0 B:148.0 /148.0 T0:255.2 /255.0 @:7.29 B@:147.82
2016-02-18 21:06:54,885 - SERIAL - DEBUG - Send: N58 M105*26
2016-02-18 21:06:54,916 - SERIAL - DEBUG - Recv: ok T:254.7 /255.0 B:147.9 /148.0 T0:254.7 /255.0 @:9.92 B@:167.98
2016-02-18 21:06:54,945 - SERIAL - DEBUG - Send: N59 M105*27
2016-02-18 21:06:54,976 - SERIAL - DEBUG - Recv: ok T:254.7 /255.0 B:147.9 /148.0 T0:254.7 /255.0 @:9.92 B@:167.98
No idea what this burst of M105s is about. Are they stored up temperature requests?
--> That's a good question, because the timer sending the M105s in 1.2.x should ever only allow one M105 in the queue,
so nothing actually should get stored up.
2016-02-18 21:06:55,006 - SERIAL - DEBUG - Send: N60 G1 X42.733 Y56.603 Z0.25 F535.8 E0.3096*79
2016-02-18 21:06:55,049 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.50 B@:161.26
2016-02-18 21:06:55,083 - SERIAL - DEBUG - Send: N61 G1 X43.191 Y56.144 Z0.25 F535.8 E0.0102*74
2016-02-18 21:06:55,115 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.50 B@:161.26
2016-02-18 21:06:55,149 - SERIAL - DEBUG - Send: N62 G1 X-70.472 Y-63.632 Z0.25 F535.8 E2.6067*71
2016-02-18 21:06:55,180 - SERIAL - DEBUG - Recv: ok T:255.2 /255.0 B:148.0 /148.0 T0:255.2 /255.0 @:7.29 B@:147.82
We are out of sync here as the OKs are from M105s, not the G1s
2016-02-18 21:06:55,212 - SERIAL - DEBUG - Send: N63 G1 X-63.632 Y-70.472 Z0.25 F535.8 E0.1527*66
2016-02-18 21:06:55,221 - SERIAL - DEBUG - Recv: ok
For N60
2016-02-18 21:06:55,232 - SERIAL - DEBUG - Send: N64 G1 X-61.753 Y-68.689 Z0.25 F535.8 E0.0409*66
2016-02-18 21:06:55,351 - SERIAL - DEBUG - Recv: ok
For N61
2016-02-18 21:06:55,362 - SERIAL - DEBUG - Send: N65 G1 E-1.25 F1800.0*58
2016-02-18 21:06:55,370 - SERIAL - DEBUG - Recv: ok
For N62
2016-02-18 21:06:55,380 - SERIAL - DEBUG - Send: N66 M103*17
2016-02-18 21:07:04,328 - SERIAL - DEBUG - Recv: Error:checksum mismatch, Last Line: 62
2016-02-18 21:07:04,348 - SERIAL - DEBUG - Recv: Resend: 63
Looks like buffer overflow corrupted line 63
2016-02-18 21:07:04,359 - SERIAL - DEBUG - Send: N63 G1 X-63.632 Y-70.472 Z0.25 F535.8 E0.1527*66
2016-02-18 21:07:04,366 - SERIAL - DEBUG - Recv: ok
For N63
--> No. This ok actually still belongs to the "Resend: 63" response and gets ignored.
2016-02-18 21:07:05,719 - SERIAL - DEBUG - Recv: ok
Don't know what this OK is for.
--> This one is for N63
2016-02-18 21:07:05,737 - SERIAL - DEBUG - Send: N64 G1 X-61.753 Y-68.689 Z0.25 F535.8 E0.0409*66
2016-02-18 21:07:15,929 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:07:15,937 - SERIAL - DEBUG - Send: N65 G1 E-1.25 F1800.0*58
2016-02-18 21:07:35,960 - SERIAL - DEBUG - Communication timeout during printing, forcing a line
2016-02-18 21:07:35,974 - SERIAL - DEBUG - Send: N67 M105*22
Misses out N66!
--> That's were stuff happens in the wrong error and my "note to self" comes into play. OctoPrint
runs into a timeout, but instead of processing the active resend queue it has sends an M105
with the last "new" line number. I'm not entirely sure what the best course of action here is
(just do nothing on a timeout during a resend? send M105 but with the line number from the
resend queue? continue processing the resend queue?), but this certainly is the wrong one.
2016-02-18 21:07:55,990 - SERIAL - DEBUG - Communication timeout during printing, forcing a line
2016-02-18 21:07:56,006 - SERIAL - DEBUG - Send: N68 M105*25
2016-02-18 21:08:16,031 - SERIAL - DEBUG - Communication timeout during printing, forcing a line
2016-02-18 21:08:16,038 - SERIAL - DEBUG - Send: N69 M105*24
2016-02-18 21:08:29,929 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:08:29,937 - SERIAL - DEBUG - Send: N69 M105*24
2016-02-18 21:08:29,965 - SERIAL - DEBUG - Recv: Error:Line Number is not Last Line Number+1, Last Line: 65
2016-02-18 21:08:29,976 - SERIAL - DEBUG - Recv: Resend: 66
2016-02-18 21:08:29,984 - SERIAL - DEBUG - Send: N66 M103*17
2016-02-18 21:08:29,991 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:08:31,307 - SERIAL - DEBUG - Recv: Error:Line Number is not Last Line Number+1, Last Line: 65
2016-02-18 21:08:31,325 - SERIAL - DEBUG - Recv: Resend: 66
2016-02-18 21:08:31,334 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:08:51,361 - SERIAL - DEBUG - Communication timeout during printing, forcing a line
Why did it timeout here instead of resending 66?
--> Because it knows that it already had sent lines 67, 68 and 69 after line 66, and those might all be in the
firmware side rx buffer. So if it sees another request for line 66 right after re-sending line 66, it ignores that,
otherwise it might get caught in a loop. That it did timeout and sends a new M105 next with increased line number
instead of continuing the active resend queue is an issue though.
2016-02-18 21:08:51,374 - SERIAL - DEBUG - Send: N70 M105*16
2016-02-18 21:08:51,420 - SERIAL - DEBUG - Recv: Error:Line Number is not Last Line Number+1, Last Line: 65
2016-02-18 21:08:51,430 - SERIAL - DEBUG - Recv: Resend: 66
2016-02-18 21:08:51,437 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:11,464 - SERIAL - DEBUG - Communication timeout during printing, forcing a line
2016-02-18 21:09:11,477 - SERIAL - DEBUG - Send: N71 M105*17
2016-02-18 21:09:11,512 - SERIAL - DEBUG - Recv: Error:Line Number is not Last Line Number+1, Last Line: 65
2016-02-18 21:09:11,522 - SERIAL - DEBUG - Recv: Resend: 66
2016-02-18 21:09:11,529 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:31,557 - SERIAL - DEBUG - Communication timeout during printing, forcing a line
2016-02-18 21:09:31,574 - SERIAL - DEBUG - Send: N72 M105*18
2016-02-18 21:09:31,617 - SERIAL - DEBUG - Recv: Error:Line Number is not Last Line Number+1, Last Line: 65
2016-02-18 21:09:31,628 - SERIAL - DEBUG - Recv: Resend: 66
2016-02-18 21:09:31,642 - SERIAL - DEBUG - Send: N66 M103*17
2016-02-18 21:09:31,650 - SERIAL - DEBUG - Recv: ok
--> Again, that's the ok from the resend
2016-02-18 21:09:31,662 - SERIAL - DEBUG - Recv: ok
--> That's the ok for the line that was resent (so for N66)
2016-02-18 21:09:31,671 - SERIAL - DEBUG - Send: N67 M105*22
2016-02-18 21:09:31,710 - SERIAL - DEBUG - Recv: ok T:255.1 /255.0 B:148.0 /148.0 T0:255.1 /255.0 @:7.49 B@:152.86
2016-02-18 21:09:31,747 - SERIAL - DEBUG - Send: N68 M105*25
2016-02-18 21:09:31,786 - SERIAL - DEBUG - Recv: ok T:255.1 /255.0 B:148.0 /148.0 T0:255.1 /255.0 @:7.49 B@:152.86
2016-02-18 21:09:31,821 - SERIAL - DEBUG - Send: N69 M105*24
2016-02-18 21:09:31,854 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.10 B@:144.46
2016-02-18 21:09:31,885 - SERIAL - DEBUG - Send: N70 M105*16
2016-02-18 21:09:31,923 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.10 B@:144.46
2016-02-18 21:09:31,954 - SERIAL - DEBUG - Send: N71 M105*17
2016-02-18 21:09:31,997 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.10 B@:152.86
2016-02-18 21:09:32,027 - SERIAL - DEBUG - Send: N72 M105*18
2016-02-18 21:09:32,057 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.10 B@:152.86
2016-02-18 21:09:32,087 - SERIAL - DEBUG - Send: N73 M105*19
2016-02-18 21:09:32,134 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.10 B@:156.22
2016-02-18 21:09:32,165 - SERIAL - DEBUG - Send: N74 M105*20
2016-02-18 21:09:32,198 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.10 B@:156.22
2016-02-18 21:09:32,226 - SERIAL - DEBUG - Send: N75 M105*21
2016-02-18 21:09:32,256 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.10 B@:144.46
2016-02-18 21:09:32,284 - SERIAL - DEBUG - Send: N76 M105*22
2016-02-18 21:09:32,314 - SERIAL - DEBUG - Recv: ok T:255.0 /255.0 B:148.0 /148.0 T0:255.0 /255.0 @:8.10 B@:144.46
2016-02-18 21:09:32,343 - SERIAL - DEBUG - Send: N77 M105*23
2016-02-18 21:09:32,372 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:144.46
2016-02-18 21:09:32,400 - SERIAL - DEBUG - Send: N78 M105*24
2016-02-18 21:09:32,431 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:144.46
2016-02-18 21:09:32,456 - SERIAL - DEBUG - Send: N79 M105*25
2016-02-18 21:09:32,488 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:144.46
2016-02-18 21:09:32,514 - SERIAL - DEBUG - Send: N80 M105*31
2016-02-18 21:09:32,544 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:144.46
2016-02-18 21:09:32,571 - SERIAL - DEBUG - Send: N81 M105*30
2016-02-18 21:09:32,602 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:144.46
2016-02-18 21:09:32,642 - SERIAL - DEBUG - Send: N82 M105*29
2016-02-18 21:09:32,674 - SERIAL - DEBUG - Recv: ok T:254.7 /255.0 B:148.0 /148.0 T0:254.7 /255.0 @:9.52 B@:156.22
2016-02-18 21:09:32,701 - SERIAL - DEBUG - Send: N83 M105*28
2016-02-18 21:09:32,731 - SERIAL - DEBUG - Recv: ok T:254.7 /255.0 B:148.0 /148.0 T0:254.7 /255.0 @:9.52 B@:156.22
2016-02-18 21:09:32,758 - SERIAL - DEBUG - Send: N84 M105*27
2016-02-18 21:09:32,790 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:152.86
2016-02-18 21:09:32,816 - SERIAL - DEBUG - Send: N85 M105*26
2016-02-18 21:09:32,846 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:152.86
2016-02-18 21:09:32,873 - SERIAL - DEBUG - Send: N86 M105*25
2016-02-18 21:09:32,904 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:141.10
2016-02-18 21:09:32,932 - SERIAL - DEBUG - Send: N87 M105*24
2016-02-18 21:09:32,962 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:141.10
2016-02-18 21:09:32,991 - SERIAL - DEBUG - Send: N88 M105*23
2016-02-18 21:09:33,020 - SERIAL - DEBUG - Recv: ok T:254.8 /255.0 B:148.0 /148.0 T0:254.8 /255.0 @:8.91 B@:144.46
2016-02-18 21:09:33,049 - SERIAL - DEBUG - Send: N89 M105*22
2016-02-18 21:09:33,079 - SERIAL - DEBUG - Recv: ok T:254.8 /255.0 B:148.0 /148.0 T0:254.8 /255.0 @:8.91 B@:144.46
2016-02-18 21:09:33,111 - SERIAL - DEBUG - Send: N90 M105*30
2016-02-18 21:09:33,145 - SERIAL - DEBUG - Recv: ok T:254.7 /255.0 B:147.9 /148.0 T0:254.7 /255.0 @:9.52 B@:164.62
2016-02-18 21:09:33,190 - SERIAL - DEBUG - Send: N91 M105*31
2016-02-18 21:09:33,221 - SERIAL - DEBUG - Recv: ok T:254.7 /255.0 B:147.9 /148.0 T0:254.7 /255.0 @:9.52 B@:164.62
2016-02-18 21:09:33,249 - SERIAL - DEBUG - Send: N92 M105*28
2016-02-18 21:09:33,280 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:152.86
2016-02-18 21:09:33,308 - SERIAL - DEBUG - Send: N93 M105*29
2016-02-18 21:09:33,339 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:152.86
2016-02-18 21:09:33,368 - SERIAL - DEBUG - Send: N94 M105*26
2016-02-18 21:09:33,399 - SERIAL - DEBUG - Recv: ok T:254.8 /255.0 B:148.0 /148.0 T0:254.8 /255.0 @:8.91 B@:156.22
2016-02-18 21:09:33,427 - SERIAL - DEBUG - Send: N95 M105*27
2016-02-18 21:09:33,458 - SERIAL - DEBUG - Recv: ok T:254.8 /255.0 B:148.0 /148.0 T0:254.8 /255.0 @:8.91 B@:156.22
2016-02-18 21:09:33,487 - SERIAL - DEBUG - Send: N96 M105*24
2016-02-18 21:09:33,523 - SERIAL - DEBUG - Recv: ok T:254.8 /255.0 B:148.0 /148.0 T0:254.8 /255.0 @:8.91 B@:156.22
2016-02-18 21:09:33,550 - SERIAL - DEBUG - Send: N97 M105*25
2016-02-18 21:09:33,580 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:144.46
2016-02-18 21:09:33,608 - SERIAL - DEBUG - Send: N98 M105*22
2016-02-18 21:09:33,639 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:144.46
2016-02-18 21:09:33,668 - SERIAL - DEBUG - Send: N99 M105*23
2016-02-18 21:09:33,713 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:152.86
2016-02-18 21:09:33,741 - SERIAL - DEBUG - Send: N100 M105*38
2016-02-18 21:09:33,771 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:152.86
2016-02-18 21:09:33,799 - SERIAL - DEBUG - Send: N101 M105*39
2016-02-18 21:09:33,830 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:152.86
2016-02-18 21:09:33,858 - SERIAL - DEBUG - Send: N102 M105*36
2016-02-18 21:09:33,889 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:152.86
2016-02-18 21:09:33,918 - SERIAL - DEBUG - Send: N103 M105*37
2016-02-18 21:09:33,948 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:156.22
2016-02-18 21:09:33,978 - SERIAL - DEBUG - Send: N104 M105*34
2016-02-18 21:09:34,009 - SERIAL - DEBUG - Recv: ok T:254.9 /255.0 B:148.0 /148.0 T0:254.9 /255.0 @:8.50 B@:156.22
--> I still have no idea.
2016-02-18 21:09:34,038 - SERIAL - DEBUG - Send: N105 G1 X-63.946 Y-62.656 Z0.25 F9000.0*48
2016-02-18 21:09:34,050 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:34,060 - SERIAL - DEBUG - Send: N106 G1 E1.25 F1800.0*35
2016-02-18 21:09:34,068 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:34,079 - SERIAL - DEBUG - Send: N107 M101*37
2016-02-18 21:09:34,088 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:34,099 - SERIAL - DEBUG - Send: N108 G1 X-63.914 Y-62.624 Z0.25 F535.8 E0.0007*113
2016-02-18 21:09:34,113 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:34,123 - SERIAL - DEBUG - Send: N109 G1 X-63.041 Y-62.722 Z0.25 F535.8 E0.0139*114
2016-02-18 21:09:34,134 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:34,149 - SERIAL - DEBUG - Send: N110 G1 X-62.574 Y-63.466 Z0.25 F535.8 E0.0139*122
2016-02-18 21:09:34,161 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:34,171 - SERIAL - DEBUG - Send: N111 G1 X-62.864 Y-64.295 Z0.25 F535.8 E0.0139*122
2016-02-18 21:09:34,182 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:34,195 - SERIAL - DEBUG - Send: N112 G1 X-63.693 Y-64.585 Z0.25 F535.8 E0.0139*120
2016-02-18 21:09:34,214 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:34,229 - SERIAL - DEBUG - Send: N113 G1 X-64.437 Y-64.118 Z0.25 F535.8 E0.0139*114
2016-02-18 21:09:50,371 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:50,381 - SERIAL - DEBUG - Send: N114 M105*35
2016-02-18 21:09:50,666 - SERIAL - DEBUG - Recv: ok T:255.1 /255.0 B:148.0 /148.0 T0:255.1 /255.0 @:7.29 B@:146.14
2016-02-18 21:09:50,694 - SERIAL - DEBUG - Send: N115 M105*34
2016-02-18 21:09:50,724 - SERIAL - DEBUG - Recv: ok T:254.8 /255.0 B:148.0 /148.0 T0:254.8 /255.0 @:8.91 B@:146.14
2016-02-18 21:09:50,753 - SERIAL - DEBUG - Send: N116 M105*33
2016-02-18 21:09:50,782 - SERIAL - DEBUG - Recv: ok T:254.8 /255.0 B:148.0 /148.0 T0:254.8 /255.0 @:8.91 B@:146.14
--> That actually looks like there was more than one timer for M105 active - Hmm...
2016-02-18 21:09:50,811 - SERIAL - DEBUG - Send: N117 G1 X-64.535 Y-63.245 Z0.25 F535.8 E0.0139*121
2016-02-18 21:09:50,822 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:50,832 - SERIAL - DEBUG - Send: N118 G1 X-63.946 Y-62.656 Z0.25 F535.8 E0.0132*117
I cancelled the print here.
2016-02-18 21:09:58,301 - SERIAL - DEBUG - Changing monitoring state from 'Printing' to 'Operational'
2016-02-18 21:09:58,535 - SERIAL - DEBUG - Send: M84
2016-02-18 21:09:58,551 - SERIAL - DEBUG - Send: M104 T0 S0
2016-02-18 21:09:58,558 - SERIAL - DEBUG - Send: M140 S0
2016-02-18 21:09:58,576 - SERIAL - DEBUG - Send: M106 S0
2016-02-18 21:09:59,399 - SERIAL - DEBUG - Recv: ok
2016-02-18 21:09:59,408 - SERIAL - DEBUG - Send: M105
2016-02-18 21:10:01,657 - SERIAL - DEBUG - Send: M105
2016-02-18 21:10:06,667 - SERIAL - DEBUG - Send: M105
2016-02-18 21:10:11,682 - SERIAL - DEBUG - Send: M105
2016-02-18 21:10:16,701 - SERIAL - DEBUG - Send: M105
2016-02-18 21:10:21,725 - SERIAL - DEBUG - Send: M105
2016-02-18 21:10:39,107 - SERIAL - DEBUG - Changing monitoring state from 'Operational' to 'Closed'
2016-02-18 21:10:39,463 - SERIAL - DEBUG - Connection closed, closing down monitor
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment