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