Arduino Pinball Controller

29. Juli 2019

vor 2 Minuten schrieb jabdoa:

0.53/dev ist abwärtskompatibel. Wenn du Version 0.0.8 zurück gibst verwendet er die alten Commands.

sehr schön, und bei LISY35 hatten wir glaub ich nur die Rückgabe des Hardwaretyps implementiert, richtig?

wenn ich da bei 'connected hardware' "LISY35" zurück gebe geht das auch schon mit 0.52 oder meckert der dann?

29. Juli 2019

vor 5 Minuten schrieb bontango:

sehr schön, und bei LISY35 hatten wir glaub ich nur die Rückgabe des Hardwaretyps implementiert, richtig?

Genau.

vor 5 Minuten schrieb bontango:

wenn ich da bei 'connected hardware' "LISY35" zurück gebe geht das auch schon mit 0.52 oder meckert der dann?

Sollte gehen.

30. Juli 2019

Am 28.7.2019 um 19:01 schrieb Black Knight:

Versuch mal Spule 25 als Flipper-Relais zu aktivieren

Yup, klappt. Interessant. Mit 23 und 24 kann ich ein Flipper (de)aktivieren, koennte vielleicht spass machen in irgendeine Mode

Und jetzt eine Frage auf Englisch....

Special solenoids switches.... they do not register on the F-14, using Base Mode switch test. If I ground the pins on the APC directly, they register, but not in the machine. I have been trying to follow the wiring.... where do the special solenoid switches get their ground connection from? Obviously the 6 switch inputs come in, and they should ground the pin when the switch is closed, but I can't see where the ground comes from. In "normal" operation, these only function when the flipper relay is active (if I remember well). Did I miss a connection, or do I have a bad connection somewhere?

30. Juli 2019

1 hour ago, Snux said:

Mit 23 und 24 kann ich ein Flipper (de)aktivieren

Ja, das liegt daran, dass der APC kein Flipper-Relais verwendet, sondern jeden Flipper jeweils wie eine normale Spüle behandelt. Das mit der 25 habe ich nur für das USB-Protokoll eingebaut, damit man mit einer Spule beide Flipperfinger freischalten kann.

2 hours ago, Snux said:

Did I miss a connection, or do I have a bad connection somewhere?

Die Special-Solenoid Schalter sollten direkt an Ground hängen. Auf dem Original-CPU-board kann man sie dann noch durch ein Steuersignal deaktivieren, das ist beim APC aber nicht nötig - da werden die Special-Solenoid sowieso per Software betätigt.

Daher würde ich sagen, dass deine Ground-Leitung irgendwo unterbrochen ist.

3. August 2019

Am 30.7.2019 um 21:42 schrieb Black Knight:

Die Special-Solenoid Schalter sollten direkt an Ground hängen.

So, Problem gefunden glaube ich. Die hängen direkt an Ground, das stimmt. Ich habe in F-14 Ground gefolgt und gefunden. Es gibt eine kleine Fehler in Sys11 Schaltplan. Ground kommt von 1J18-7. Schaltplan zeigt N.C (aber mit Ground). Das ist leider falsch. Ist Special-Solenoid Schalter Ground. Ich glaube bei APC die sind wirklich N.C?

apc.jpg.7daf371d3490b2eb5bd35066e4058290.jpg

Specials.jpg.94f0774290a878aff0b36e0e48d9969a.jpg

Bearbeitet 3. August 2019 von Snux

3. August 2019

Found this comment in Clay's pinball repair bible

907070535_Image3.jpg.0426d708d890a68c95ef7efd96659a9f.jpg

3. August 2019

45 minutes ago, Snux said:

Ich glaube bei APC die sind wirklich N.C?

Ja, die sind nicht verbunden, ich habe mich da an den Belegungsplan gehalten.

Bei meinem Pinbot funktioniert es, da gehen aber auch Drähte an Pin 6 und Pin 7 von 1J18 und über einen kommt wohl der Ground. Ab Sys11a gibt's dann Ärger - sowohl bei meiner F-14, als auch der Space Station ist nur noch ein Pin belegt und kein Ground mehr da.

Vielen Dank für die Info, da werde ich wohl eine neue APC Version generieren müssen.

3. August 2019

vor 3 Stunden schrieb Black Knight:

Ja, die sind nicht verbunden

Jetzt sind die

Naechstes Thema...

Im Moment brauche ich APC "sound system" nicht, weil MPF kummert sich darauf. Aber diese TDA amplifier ist verdammt heiss. Muss ich was machen? Ich habe keine APC Verbindung mit "pot" oder lautsprecher....

Ich habe auch ein neues Display, jetzt lauft alles wieder gut..

4. August 2019

Am 29.7.2019 um 07:08 schrieb jabdoa:

Btw ich habe das Segment Display Format wie besprochen angepasst

Leider sieht es nicht viel besser aus... Mit mpf-0.53.0.dev50 und mpf-mc-0.53.0.dev8

Display 1 = 1234567

Display 2 = MPF-APC

IMG_20190804_092525.jpg.f1fc4f96e3b1b1cc81dc8f048c052b56.jpg

4. August 2019

15 hours ago, Snux said:

Aber diese TDA amplifier ist verdammt heiss. Muss ich was machen? Ich habe keine APC Verbindung mit "pot" oder lautsprecher....

Du hast weder den Stecker für das Lautstärkepoti (1J16) noch den Stecker für den Lautsprecher drauf und trotzdem wird der TDA heiss? Das ist nicht gut.

Kommt das nur vor, wenn du die Platine auf deinem Tisch liegen hast (also ohne die 12V) oder auch im Flipper? Wie steht denn dein Setting für die Lautstärke?

8 minutes ago, Snux said:

Leider sieht es nicht viel besser aus... Mit mpf-0.53.0.dev50 und mpf-mc-0.53.0.dev8

Hattest du auch die APC SW geupdated? Zeigt er am Anfang 'APC REV 00,10'?

4. August 2019

vor 8 Minuten schrieb Black Knight:

Du hast weder den Stecker für das Lautstärkepoti (1J16) noch den Stecker für den Lautsprecher drauf und trotzdem wird der TDA heiss?

Jup. Aber scheint nur im Flipper (also mit 12v).

vor 8 Minuten schrieb Black Knight:

Zeigt er am Anfang 'APC REV 00,10'?

Jup.

IMG_20190804_094933.jpg.2e8024268e6e6f4270cbe33c749e857b.jpg

4. August 2019

Ich kann aber 12v Sicherung rausnehmen. 12v ist nur fuer TDA oder?

4. August 2019

16 minutes ago, Snux said:

Ich kann aber 12v Sicherung rausnehmen. 12v ist nur fuer TDA oder?

Ja, das kannst du machen, aber mich würde natürlich interessieren, was da los ist. Kannst du den Sound nochmal testen, wenn du die Platine das nächste Mal im Flipper hast? Wenn du einen Kurzschluss an einem der Pins für den Lautsprecher hast dürfte ein Kanal nicht mehr funktionieren. Und denk daran, die digitale Lautstärke auf Null zu stellen, bevor du den 1J16 wieder anschließt.

50 minutes ago, Snux said:

Leider sieht es nicht viel besser aus...

Kannst du mal im Logfile schauen, ob der MPF sagt was genau er sendet?

4. August 2019

Protokoll kenn ich nicht so gut, vielleicht hilft es...

2019-08-04 14:03:06,520 : INFO : Machine : Mission Pinball Framework Core Engine v0.53.0-dev.50
2019-08-04 14:03:06,521 : INFO : Machine : Command line arguments: {'no_load_cache': False, 'create_config_cache': True, 'bcp': True, 'configfile': ['config.yaml'], 'mpfconfigfile': 'c:\\users\\mark\\appdata\\local\\programs\\python\\python36\\lib\\site-packages\\mpf\\mpfconfig.yaml', 'force_assets_load': False, 'jsonlogging': False, 'logfile': 'logs\\mpf.log', 'pause': False, 'production': False, 'text_ui': True, 'loglevel': 10, 'consoleloglevel': 10, 'force_platform': None, 'syslog_address': None, 'mc_file_name': 'logs\\mc.log', 'no_sound': False}
2019-08-04 14:03:06,521 : INFO : Machine : MPF path: c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpf
2019-08-04 14:03:06,521 : INFO : Machine : Machine path: c:\MPF\F14-MPF
2019-08-04 14:03:06,521 : INFO : Machine : Platform: win32
2019-08-04 14:03:06,521 : INFO : Machine : Python executable location: c:\users\mark\appdata\local\programs\python\python36\python.exe
2019-08-04 14:03:06,521 : INFO : Machine : Python version: 3.6.4 (64-bit)
2019-08-04 14:03:06,521 : INFO : Machine : Machine config file #1: config.yaml
2019-08-04 14:03:06,522 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\595d82c30cab02f8508231fdf9adff7e.mpf_cache
2019-08-04 14:03:06,524 : DEBUG : asyncio : Using selector: SelectSelector
2019-08-04 14:03:06,525 : INFO : Machine : Initialise MPF.
2019-08-04 14:03:06,558 : DEBUG : OpenPixel : Configuring Open Pixel hardware interface.
2019-08-04 14:03:06,558 : DEBUG : FadeCandy : Configuring FadeCandy hardware interface.
2019-08-04 14:03:06,876 : INFO : lisy : Connecting to com6 at 115200bps
2019-08-04 14:03:06,877 : WARNING : lisy : Could not import terminos (this is ok on windows).
2019-08-04 14:03:06,959 : DEBUG : lisy : Sending 100 b''
2019-08-04 14:03:06,959 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:06,963 : DEBUG : lisy : Received 0
2019-08-04 14:03:06,963 : DEBUG : lisy : Sending 0 b''
2019-08-04 14:03:06,963 : DEBUG : lisy : Reading zero terminated string
2019-08-04 14:03:06,967 : DEBUG : lisy : Received b'APC'
2019-08-04 14:03:06,967 : DEBUG : lisy : Sending 1 b''
2019-08-04 14:03:06,967 : DEBUG : lisy : Reading zero terminated string
2019-08-04 14:03:06,971 : DEBUG : lisy : Received b'00.10'
2019-08-04 14:03:06,971 : DEBUG : lisy : Sending 2 b''
2019-08-04 14:03:06,971 : DEBUG : lisy : Reading zero terminated string
2019-08-04 14:03:06,975 : DEBUG : lisy : Received b'0.09'
2019-08-04 14:03:06,975 : INFO : EventManager : Event: ======'machine_var_lisy_hardware'====== Args={'value': b'APC', 'prev_value': None, 'change': True}
2019-08-04 14:03:06,975 : INFO : EventManager : Event: ======'machine_var_lisy_version'====== Args={'value': b'00.10', 'prev_value': None, 'change': True}
2019-08-04 14:03:06,975 : INFO : EventManager : Event: ======'machine_var_lisy_api_version'====== Args={'value': b'0.09', 'prev_value': None, 'change': True}
2019-08-04 14:03:06,975 : DEBUG : lisy : Sending 3 b''
2019-08-04 14:03:06,975 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:06,979 : DEBUG : lisy : Received 64
2019-08-04 14:03:06,979 : DEBUG : lisy : Sending 4 b''
2019-08-04 14:03:06,979 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:06,984 : DEBUG : lisy : Received 25
2019-08-04 14:03:06,984 : DEBUG : lisy : Sending 6 b''
2019-08-04 14:03:06,984 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:06,988 : DEBUG : lisy : Received 4
2019-08-04 14:03:06,988 : DEBUG : lisy : Sending 9 b''
2019-08-04 14:03:06,988 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:06,992 : DEBUG : lisy : Received 73
2019-08-04 14:03:06,992 : DEBUG : lisy : Sending 19 b''
2019-08-04 14:03:06,992 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:06,996 : DEBUG : lisy : Received 0
2019-08-04 14:03:06,996 : DEBUG : lisy : Sending 40 b'\x00'
2019-08-04 14:03:06,996 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,000 : DEBUG : lisy : Received 1
2019-08-04 14:03:07,000 : DEBUG : lisy : Sending 40 b'\x01'
2019-08-04 14:03:07,000 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,004 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,004 : DEBUG : lisy : Sending 40 b'\x02'
2019-08-04 14:03:07,004 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,008 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,008 : DEBUG : lisy : Sending 40 b'\x03'
2019-08-04 14:03:07,008 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,012 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,012 : DEBUG : lisy : Sending 40 b'\x04'
2019-08-04 14:03:07,012 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,016 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,016 : DEBUG : lisy : Sending 40 b'\x05'
2019-08-04 14:03:07,016 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,021 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,021 : DEBUG : lisy : Sending 40 b'\x06'
2019-08-04 14:03:07,021 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,025 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,025 : DEBUG : lisy : Sending 40 b'\x07'
2019-08-04 14:03:07,025 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,029 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,029 : DEBUG : lisy : Sending 40 b'\x08'
2019-08-04 14:03:07,029 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,033 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,033 : DEBUG : lisy : Sending 40 b'\t'
2019-08-04 14:03:07,033 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,037 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,037 : DEBUG : lisy : Sending 40 b'\n'
2019-08-04 14:03:07,038 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,041 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,041 : DEBUG : lisy : Sending 40 b'\x0b'
2019-08-04 14:03:07,041 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,045 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,045 : DEBUG : lisy : Sending 40 b'\x0c'
2019-08-04 14:03:07,045 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,049 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,049 : DEBUG : lisy : Sending 40 b'\r'
2019-08-04 14:03:07,049 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,053 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,053 : DEBUG : lisy : Sending 40 b'\x0e'
2019-08-04 14:03:07,053 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,058 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,058 : DEBUG : lisy : Sending 40 b'\x0f'
2019-08-04 14:03:07,058 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,062 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,062 : DEBUG : lisy : Sending 40 b'\x10'
2019-08-04 14:03:07,062 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,066 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,066 : DEBUG : lisy : Sending 40 b'\x11'
2019-08-04 14:03:07,066 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,070 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,070 : DEBUG : lisy : Sending 40 b'\x12'
2019-08-04 14:03:07,070 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,074 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,074 : DEBUG : lisy : Sending 40 b'\x13'
2019-08-04 14:03:07,074 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,078 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,078 : DEBUG : lisy : Sending 40 b'\x14'
2019-08-04 14:03:07,078 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,082 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,082 : DEBUG : lisy : Sending 40 b'\x15'
2019-08-04 14:03:07,082 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,086 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,086 : DEBUG : lisy : Sending 40 b'\x16'
2019-08-04 14:03:07,086 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,090 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,090 : DEBUG : lisy : Sending 40 b'\x17'
2019-08-04 14:03:07,090 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,095 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,095 : DEBUG : lisy : Sending 40 b'\x18'
2019-08-04 14:03:07,095 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,099 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,099 : DEBUG : lisy : Sending 40 b'\x19'
2019-08-04 14:03:07,099 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,103 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,103 : DEBUG : lisy : Sending 40 b'\x1a'
2019-08-04 14:03:07,103 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,107 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,107 : DEBUG : lisy : Sending 40 b'\x1b'
2019-08-04 14:03:07,107 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,111 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,111 : DEBUG : lisy : Sending 40 b'\x1c'
2019-08-04 14:03:07,111 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,115 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,115 : DEBUG : lisy : Sending 40 b'\x1d'
2019-08-04 14:03:07,115 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,119 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,119 : DEBUG : lisy : Sending 40 b'\x1e'
2019-08-04 14:03:07,119 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,123 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,123 : DEBUG : lisy : Sending 40 b'\x1f'
2019-08-04 14:03:07,123 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,127 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,127 : DEBUG : lisy : Sending 40 b' '
2019-08-04 14:03:07,127 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,131 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,131 : DEBUG : lisy : Sending 40 b'!'
2019-08-04 14:03:07,131 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,136 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,136 : DEBUG : lisy : Sending 40 b'"'
2019-08-04 14:03:07,136 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,140 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,140 : DEBUG : lisy : Sending 40 b'#'
2019-08-04 14:03:07,140 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,144 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,144 : DEBUG : lisy : Sending 40 b'$'
2019-08-04 14:03:07,144 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,148 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,148 : DEBUG : lisy : Sending 40 b'%'
2019-08-04 14:03:07,148 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,152 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,152 : DEBUG : lisy : Sending 40 b'&'
2019-08-04 14:03:07,152 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,156 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,156 : DEBUG : lisy : Sending 40 b"'"
2019-08-04 14:03:07,156 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,160 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,160 : DEBUG : lisy : Sending 40 b'('
2019-08-04 14:03:07,160 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,164 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,164 : DEBUG : lisy : Sending 40 b')'
2019-08-04 14:03:07,164 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,168 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,168 : DEBUG : lisy : Sending 40 b'*'
2019-08-04 14:03:07,168 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,173 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,173 : DEBUG : lisy : Sending 40 b'+'
2019-08-04 14:03:07,173 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,177 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,177 : DEBUG : lisy : Sending 40 b','
2019-08-04 14:03:07,177 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,181 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,181 : DEBUG : lisy : Sending 40 b'-'
2019-08-04 14:03:07,181 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,185 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,185 : DEBUG : lisy : Sending 40 b'.'
2019-08-04 14:03:07,185 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,189 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,189 : DEBUG : lisy : Sending 40 b'/'
2019-08-04 14:03:07,189 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,193 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,193 : DEBUG : lisy : Sending 40 b'0'
2019-08-04 14:03:07,193 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,197 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,197 : DEBUG : lisy : Sending 40 b'1'
2019-08-04 14:03:07,197 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,201 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,201 : DEBUG : lisy : Sending 40 b'2'
2019-08-04 14:03:07,201 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,205 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,205 : DEBUG : lisy : Sending 40 b'3'
2019-08-04 14:03:07,205 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,209 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,209 : DEBUG : lisy : Sending 40 b'4'
2019-08-04 14:03:07,209 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,214 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,214 : DEBUG : lisy : Sending 40 b'5'
2019-08-04 14:03:07,214 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,218 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,218 : DEBUG : lisy : Sending 40 b'6'
2019-08-04 14:03:07,218 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,222 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,222 : DEBUG : lisy : Sending 40 b'7'
2019-08-04 14:03:07,222 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,226 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,226 : DEBUG : lisy : Sending 40 b'8'
2019-08-04 14:03:07,226 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,230 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,230 : DEBUG : lisy : Sending 40 b'9'
2019-08-04 14:03:07,230 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,234 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,234 : DEBUG : lisy : Sending 40 b':'
2019-08-04 14:03:07,234 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,237 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,237 : DEBUG : lisy : Sending 40 b';'
2019-08-04 14:03:07,237 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,241 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,241 : DEBUG : lisy : Sending 40 b'<'
2019-08-04 14:03:07,241 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,245 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,245 : DEBUG : lisy : Sending 40 b'='
2019-08-04 14:03:07,245 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,250 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,250 : DEBUG : lisy : Sending 40 b'>'
2019-08-04 14:03:07,250 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,254 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,254 : DEBUG : lisy : Sending 40 b'?'
2019-08-04 14:03:07,255 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,257 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,257 : DEBUG : lisy : Sending 40 b'@'
2019-08-04 14:03:07,258 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,262 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,262 : DEBUG : lisy : Sending 40 b'A'
2019-08-04 14:03:07,262 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,266 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,266 : DEBUG : lisy : Sending 40 b'B'
2019-08-04 14:03:07,266 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,270 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,270 : DEBUG : lisy : Sending 40 b'C'
2019-08-04 14:03:07,270 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,274 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,274 : DEBUG : lisy : Sending 40 b'D'
2019-08-04 14:03:07,274 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,278 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,278 : DEBUG : lisy : Sending 40 b'E'
2019-08-04 14:03:07,278 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,282 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,282 : DEBUG : lisy : Sending 40 b'F'
2019-08-04 14:03:07,282 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,287 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,287 : DEBUG : lisy : Sending 40 b'G'
2019-08-04 14:03:07,287 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,291 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,291 : DEBUG : lisy : Sending 40 b'H'
2019-08-04 14:03:07,291 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,295 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,295 : DEBUG : lisy : Sending 101 b''
2019-08-04 14:03:07,295 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,299 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,617 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:07,617 : DEBUG : asciimatics.widgets : New event: MouseEvent (48, 22) 0
2019-08-04 14:03:07,617 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:07,617 : DEBUG : asciimatics.widgets : New event: MouseEvent (48, 22) 0
2019-08-04 14:03:07,618 : DEBUG : asciimatics.screen : Processing key: 0
2019-08-04 14:03:07,618 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:07,618 : DEBUG : asciimatics.widgets : New event: MouseEvent (48, 22) 0
2019-08-04 14:03:07,618 : DEBUG : asciimatics.screen : Processing key: 0
2019-08-04 14:03:07,618 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:07,618 : DEBUG : asciimatics.widgets : New event: MouseEvent (48, 22) 0
2019-08-04 14:03:07,619 : DEBUG : asciimatics.screen : Processing key: 0
2019-08-04 14:03:07,619 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:07,619 : DEBUG : asciimatics.widgets : New event: MouseEvent (48, 22) 0
2019-08-04 14:03:07,619 : DEBUG : asciimatics.screen : Processing key: 0
2019-08-04 14:03:07,619 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:07,619 : DEBUG : asciimatics.widgets : New event: MouseEvent (48, 22) 0
2019-08-04 14:03:07,772 : DEBUG : lisy : Sending 101 b''
2019-08-04 14:03:07,772 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:07,775 : DEBUG : lisy : Received 0
2019-08-04 14:03:07,884 : INFO : EventManager : Event: ======'machine_var_credits_string'====== Args={'value': 'FREE PLAY', 'prev_value': None, 'change': True}
2019-08-04 14:03:07,945 : DEBUG : YAML File Interface : Loading file: c:\MPF\F14-MPF\data/machine_vars.yaml
2019-08-04 14:03:07,947 : INFO : EventManager : Event: ======'machine_var_player1_score'====== Args={'value': 477120, 'prev_value': None, 'change': True}
2019-08-04 14:03:07,947 : INFO : EventManager : Event: ======'machine_var_mpf_version'====== Args={'value': 'MPF v0.53.0-dev.50', 'prev_value': None, 'change': True}
2019-08-04 14:03:07,947 : INFO : EventManager : Event: ======'machine_var_mpf_extended_version'====== Args={'value': 'MPF v0.53.0-dev.50, Config version:5, Show version: 5, BCP version:1.1', 'prev_value': None, 'change': True}
2019-08-04 14:03:07,948 : INFO : EventManager : Event: ======'machine_var_python_version'====== Args={'value': '3.6.4', 'prev_value': None, 'change': True}
2019-08-04 14:03:07,948 : INFO : EventManager : Event: ======'machine_var_platform'====== Args={'value': 'Windows-10-10.0.17763-SP0', 'prev_value': None, 'change': True}
2019-08-04 14:03:07,948 : INFO : EventManager : Event: ======'machine_var_platform_system'====== Args={'value': 'Windows', 'prev_value': None, 'change': True}
2019-08-04 14:03:07,948 : INFO : EventManager : Event: ======'machine_var_platform_release'====== Args={'value': '10', 'prev_value': None, 'change': True}
2019-08-04 14:03:07,948 : INFO : EventManager : Event: ======'machine_var_platform_version'====== Args={'value': '10.0.17763', 'prev_value': None, 'change': True}
2019-08-04 14:03:07,948 : INFO : EventManager : Event: ======'machine_var_platform_machine'====== Args={'value': 'AMD64', 'prev_value': None, 'change': True}
2019-08-04 14:03:07,948 : INFO : EventManager : Event: ======'init_phase_1'====== Args={}
2019-08-04 14:03:07,999 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\ebebaddf59797dad917ffe460814d4fd.mpf_cache
2019-08-04 14:03:08,005 : DEBUG : ModeController : Loaded mode bonus
2019-08-04 14:03:08,005 : WARNING : ConfigProcessor : Config file in cache changed: c:\MPF\F14-MPF\modes\attract\config\attract.yaml
2019-08-04 14:03:08,005 : INFO : ConfigProcessor : Loading config from file c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpf\modes\attract\config\attract.yaml.
2019-08-04 14:03:08,006 : DEBUG : YAML File Interface : Loading file: c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpf\modes\attract\config\attract.yaml
2019-08-04 14:03:08,009 : INFO : ConfigProcessor : Loading config: c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpf\modes\attract\config\attract.yaml
2019-08-04 14:03:08,009 : INFO : ConfigProcessor : Loading config from file c:\MPF\F14-MPF\modes\attract\config\attract.yaml.
2019-08-04 14:03:08,010 : DEBUG : YAML File Interface : Loading file: c:\MPF\F14-MPF\modes\attract\config\attract.yaml
2019-08-04 14:03:08,016 : INFO : ConfigProcessor : Loading config: c:\MPF\F14-MPF\modes\attract\config\attract.yaml
2019-08-04 14:03:08,016 : INFO : ConfigProcessor : Config file cache created: C:\Users\Mark\AppData\Local\Temp\cb0935060a7174a9b62a12239215d944.mpf_cache
2019-08-04 14:03:08,029 : DEBUG : ModeController : Loaded mode attract
2019-08-04 14:03:08,029 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\c862559e9591919ea13c3727b9446afd.mpf_cache
2019-08-04 14:03:08,033 : DEBUG : ModeController : Loaded mode rescue
2019-08-04 14:03:08,033 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\c8f1b858e910c9b66e715944390be3e0.mpf_cache
2019-08-04 14:03:08,036 : DEBUG : ModeController : Loaded mode targets1_6
2019-08-04 14:03:08,036 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\6b8c446828608a743b2b3bab849c59ff.mpf_cache
2019-08-04 14:03:08,040 : DEBUG : ModeController : Loaded mode base
2019-08-04 14:03:08,040 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\b4987ecda36e222bab8ccccbaa40623a.mpf_cache
2019-08-04 14:03:08,043 : DEBUG : ModeController : Loaded mode bonus_manager
2019-08-04 14:03:08,043 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\9bf0658ab107ec9d776f13cc89924114.mpf_cache
2019-08-04 14:03:08,046 : DEBUG : ModeController : Loaded mode tomcat_targets
2019-08-04 14:03:08,046 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\e197f708aa8182b037da7b0affbb3018.mpf_cache
2019-08-04 14:03:08,049 : DEBUG : ModeController : Loaded mode multiball
2019-08-04 14:03:08,049 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\ddab8760621c9da0bb867edd832307e0.mpf_cache
2019-08-04 14:03:08,052 : DEBUG : ModeController : Loaded mode lock_handler
2019-08-04 14:03:08,052 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\1c6b2ee569cab67a0eb3842b078b32f4.mpf_cache
2019-08-04 14:03:08,055 : DEBUG : ModeController : Loaded mode yagov
2019-08-04 14:03:08,055 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\c85505fc95cfa4f888bd0c9a36d1fec0.mpf_cache
2019-08-04 14:03:08,058 : DEBUG : ModeController : Loaded mode orbit
2019-08-04 14:03:08,058 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\3f228795d90752df90ec6db8c575578a.mpf_cache
2019-08-04 14:03:08,061 : DEBUG : ModeController : Loaded mode hurry_up_right
2019-08-04 14:03:08,061 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\23c0b4d5a428ad05cfb37442b8cdbb05.mpf_cache
2019-08-04 14:03:08,068 : DEBUG : ModeController : Loaded mode game
2019-08-04 14:03:08,114 : DEBUG : ball_device.bd_trough : Configuring device with settings: '{'ball_switches': [<switch.s_trough1>, <switch.s_trough2>, <switch.s_trough3>, <switch.s_trough4>], 'eject_coil': <coil.c_troughEject>, 'eject_targets': [<ball_device.bd_plunger_lane>], 'eject_events': {'trough_eject': 0}, 'debug': True, 'tags': ['home', 'trough'], 'label': '%', 'console_log': 'full', 'file_log': 'full', 'exit_count_delay': 500, 'entrance_count_delay': 500, 'eject_coil_jam_pulse': None, 'eject_coil_retry_pulse': None, 'eject_coil_reorder_pulse': None, 'eject_coil_max_wait_ms': 200, 'eject_coil_enable_time': [], 'retries_before_increasing_pulse': 4, 'hold_coil': None, 'hold_coil_release_time': 1000, 'hold_events': {}, 'hold_switches': [], 'entrance_switch': None, 'entrance_switch_ignore_window_ms': 0, 'entrance_switch_full_timeout': 0, 'entrance_events': {}, 'entrance_event_timeout': 5.0, 'idle_missing_ball_timeout': 5.0, 'jam_switch': None, 'confirm_eject_type': 'target', 'captures_from': <playfield.playfield>, 'eject_timeouts': [], 'ball_missing_timeouts': [], 'ball_missing_target': <playfield.playfield>, 'confirm_eject_switch': None, 'confirm_eject_event': None, 'max_eject_attempts': 0, 'ball_capacity': None, 'request_ball_events': {}, 'eject_all_events': {}, 'mechanical_eject': False, 'player_controlled_eject_event': None, 'ball_search_order': 200, 'auto_fire_on_unexpected_ball': True, 'target_on_unexpected_ball': None}'
2019-08-04 14:03:08,115 : DEBUG : ball_device.bd_lock1 : Configuring device with settings: '{'ball_switches': [<switch.s_lock1>], 'eject_coil': <coil.c_lock1_eject>, 'request_ball_events': {'catch1': 0}, 'eject_events': {'eject_lock1': 0}, 'confirm_eject_type': 'event', 'confirm_eject_event': 'sw_playfield_active', 'debug': True, 'label': '%', 'tags': [], 'console_log': 'full', 'file_log': 'full', 'exit_count_delay': 500, 'entrance_count_delay': 500, 'eject_coil_jam_pulse': None, 'eject_coil_retry_pulse': None, 'eject_coil_reorder_pulse': None, 'eject_coil_max_wait_ms': 200, 'eject_coil_enable_time': [], 'retries_before_increasing_pulse': 4, 'hold_coil': None, 'hold_coil_release_time': 1000, 'hold_events': {}, 'hold_switches': [], 'entrance_switch': None, 'entrance_switch_ignore_window_ms': 0, 'entrance_switch_full_timeout': 0, 'entrance_events': {}, 'entrance_event_timeout': 5.0, 'idle_missing_ball_timeout': 5.0, 'jam_switch': None, 'captures_from': <playfield.playfield>, 'eject_targets': [<playfield.playfield>], 'eject_timeouts': [], 'ball_missing_timeouts': [], 'ball_missing_target': <playfield.playfield>, 'confirm_eject_switch': None, 'max_eject_attempts': 0, 'ball_capacity': None, 'eject_all_events': {}, 'mechanical_eject': False, 'player_controlled_eject_event': None, 'ball_search_order': 200, 'auto_fire_on_unexpected_ball': True, 'target_on_unexpected_ball': None}'
2019-08-04 14:03:08,116 : DEBUG : ball_device.bd_lock3 : Configuring device with settings: '{'ball_switches': [<switch.s_lock3>], 'eject_coil': <coil.c_lock3_eject>, 'request_ball_events': {'catch3': 0}, 'eject_events': {'eject_lock3': 0}, 'confirm_eject_type': 'event', 'confirm_eject_event': 'sw_playfield_active', 'debug': True, 'label': '%', 'tags': [], 'console_log': 'full', 'file_log': 'full', 'exit_count_delay': 500, 'entrance_count_delay': 500, 'eject_coil_jam_pulse': None, 'eject_coil_retry_pulse': None, 'eject_coil_reorder_pulse': None, 'eject_coil_max_wait_ms': 200, 'eject_coil_enable_time': [], 'retries_before_increasing_pulse': 4, 'hold_coil': None, 'hold_coil_release_time': 1000, 'hold_events': {}, 'hold_switches': [], 'entrance_switch': None, 'entrance_switch_ignore_window_ms': 0, 'entrance_switch_full_timeout': 0, 'entrance_events': {}, 'entrance_event_timeout': 5.0, 'idle_missing_ball_timeout': 5.0, 'jam_switch': None, 'captures_from': <playfield.playfield>, 'eject_targets': [<playfield.playfield>], 'eject_timeouts': [], 'ball_missing_timeouts': [], 'ball_missing_target': <playfield.playfield>, 'confirm_eject_switch': None, 'max_eject_attempts': 0, 'ball_capacity': None, 'eject_all_events': {}, 'mechanical_eject': False, 'player_controlled_eject_event': None, 'ball_search_order': 200, 'auto_fire_on_unexpected_ball': True, 'target_on_unexpected_ball': None}'
2019-08-04 14:03:08,131 : DEBUG : multiball_lock.lock1 : Configuring device with settings: '{'balls_to_lock': 1, 'lock_devices': [<ball_device.bd_lock1>], 'disable_events': {'multiball_tomcat_multiball_started': 0}, 'balls_to_replace': 0, 'reset_count_for_current_player_events': {'balldevice_bd_lock1_ejecting_ball': 0}, 'enable_events': {'light_lock_1': 0, 'enable_lock_1': 0}, 'debug': True, 'label': '%', 'tags': [], 'console_log': 'full', 'file_log': 'full', 'source_playfield': <playfield.playfield>, 'locked_ball_counting_strategy': 'virtual_only', 'reset_all_counts_events': {}}'
2019-08-04 14:03:08,131 : DEBUG : multiball_lock.lock2 : Configuring device with settings: '{'balls_to_lock': 1, 'lock_devices': [<ball_device.bd_lock2>], 'disable_events': {'multiball_tomcat_multiball_started': 0}, 'enable_events': {'light_lock_2': 0, 'enable_lock_2': 0}, 'balls_to_replace': 0, 'debug': True, 'reset_count_for_current_player_events': {'balldevice_bd_lock2_ejecting_ball': 0}, 'label': '%', 'tags': [], 'console_log': 'full', 'file_log': 'full', 'source_playfield': <playfield.playfield>, 'locked_ball_counting_strategy': 'virtual_only', 'reset_all_counts_events': {}}'
2019-08-04 14:03:08,131 : DEBUG : multiball_lock.lock3 : Configuring device with settings: '{'balls_to_lock': 1, 'lock_devices': [<ball_device.bd_lock3>], 'disable_events': {'multiball_tomcat_multiball_started': 0}, 'enable_events': {'light_lock_3': 0, 'enable_lock_3': 0}, 'balls_to_replace': 0, 'locked_ball_counting_strategy': 'min_virtual_physical', 'debug': True, 'reset_count_for_current_player_events': {'balldevice_bd_lock3_ejecting_ball': 0}, 'label': '%', 'tags': [], 'console_log': 'full', 'file_log': 'full', 'source_playfield': <playfield.playfield>, 'reset_all_counts_events': {}}'
2019-08-04 14:03:08,182 : DEBUG : lisy : Sending 7 bytearray(b'\x01')
2019-08-04 14:03:08,184 : DEBUG : lisy : Sending 31 bytearray(b'\x0e\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00')
2019-08-04 14:03:08,184 : DEBUG : lisy : Sending 7 bytearray(b'\x02')
2019-08-04 14:03:08,188 : DEBUG : lisy : Sending 32 bytearray(b'\x0e\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00')
2019-08-04 14:03:08,188 : DEBUG : lisy : Sending 7 bytearray(b'\x03')
2019-08-04 14:03:08,192 : DEBUG : lisy : Sending 33 bytearray(b'\x07\x00\x00\x00\x00\x00\x00\x00')
2019-08-04 14:03:08,192 : DEBUG : Platform.System11 : Configuring A/C Select Relay for driver c_acSelect
2019-08-04 14:03:08,193 : DEBUG : Platform.System11 : Configuring A/C Select Relay transition delay for 75ms
2019-08-04 14:03:08,193 : INFO : Platform.System11 : Configuring System11 driver to prefer A side on event game_ended
2019-08-04 14:03:08,193 : INFO : Platform.System11 : Configuring System11 driver to prefer C side on event game_will_start
2019-08-04 14:03:08,194 : INFO : EventManager : Event: ======'init_phase_2'====== Args={}
2019-08-04 14:03:08,280 : INFO : EventManager : Event: ======'bcp_connection_attempt'====== Args={'name': 'local_display', 'host': 'localhost', 'port': 5050}
2019-08-04 14:03:08,282 : INFO : BCPClientSocket.local_display : Connecting BCP to 'local_display' at localhost:5050...
2019-08-04 14:03:08,283 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:08,283 : DEBUG : asciimatics.widgets : New event: MouseEvent (11, 5) 0
2019-08-04 14:03:08,284 : DEBUG : asciimatics.screen : Processing key: 0
2019-08-04 14:03:08,284 : DEBUG : asciimatics.screen : Processing key: 0
2019-08-04 14:03:08,284 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:08,284 : DEBUG : asciimatics.widgets : New event: MouseEvent (11, 5) 0
2019-08-04 14:03:08,295 : DEBUG : lisy : Sending 101 b''
2019-08-04 14:03:08,295 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:08,299 : DEBUG : lisy : Received 0
2019-08-04 14:03:08,616 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:08,616 : DEBUG : asciimatics.widgets : New event: MouseEvent (11, 5) 0
2019-08-04 14:03:08,772 : DEBUG : lisy : Sending 101 b''
2019-08-04 14:03:08,772 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:08,774 : DEBUG : lisy : Received 0
2019-08-04 14:03:09,272 : DEBUG : lisy : Sending 101 b''
2019-08-04 14:03:09,273 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,277 : DEBUG : lisy : Received 0
2019-08-04 14:03:09,299 : INFO : BCPClientSocket.local_display : Connected BCP to 'local_display' localhost:5050
2019-08-04 14:03:09,301 : INFO : EventManager : Event: ======'bcp_clients_connected'====== Args={}
2019-08-04 14:03:09,303 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:09,304 : DEBUG : asciimatics.widgets : New event: MouseEvent (48, 22) 0
2019-08-04 14:03:09,345 : INFO : EventManager : Event: ======'machine_var_master_volume'====== Args={'value': 0.75, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,391 : DEBUG : ball_device.bd_trough : Counted 0 balls. Active switches: []. Old: None
2019-08-04 14:03:09,391 : DEBUG : ball_device.bd_trough : State: idle
2019-08-04 14:03:09,392 : DEBUG : ball_device.bd_trough : Waiting for eject request.
2019-08-04 14:03:09,393 : DEBUG : ball_device.bd_lock1 : Counted 0 balls. Active switches: []. Old: None
2019-08-04 14:03:09,394 : DEBUG : ball_device.bd_lock1 : State: idle
2019-08-04 14:03:09,394 : DEBUG : ball_device.bd_lock1 : Waiting for eject request.
2019-08-04 14:03:09,395 : DEBUG : ball_device.bd_lock3 : Counted 0 balls. Active switches: []. Old: None
2019-08-04 14:03:09,395 : DEBUG : ball_device.bd_lock3 : State: idle
2019-08-04 14:03:09,395 : DEBUG : ball_device.bd_lock3 : Waiting for eject request.
2019-08-04 14:03:09,398 : DEBUG : YAML File Interface : Loading file: c:\MPF\F14-MPF\data/audits.yaml
2019-08-04 14:03:09,421 : DEBUG : Machine : "info_lights:" section not found in machine configuration, so the Info Lights plugin will not be used.
2019-08-04 14:03:09,422 : DEBUG : Machine : "switch_player:" section not found in machine configuration, so the Switch Playerplugin will not be used.
2019-08-04 14:03:09,424 : DEBUG : Machine : "osc_plugin:" section not found in machine configuration, so the OSCplugin will not be used.
2019-08-04 14:03:09,424 : INFO : EventManager : Event: ======'init_phase_3'====== Args={}
2019-08-04 14:03:09,425 : INFO : AssetManager : Registering Asset: attract_display_loop, File: attract_display_loop.yaml, Default Group: default, Final Config: {'load': 'preload', 'file': 'c:\\MPF\\F14-MPF\\modes\\attract\\shows\\attract_display_loop.yaml'}
2019-08-04 14:03:09,425 : INFO : AssetManager : Registering Asset: attract_light_show, File: attract_light_show.yaml, Default Group: default, Final Config: {'load': 'preload', 'file': 'c:\\MPF\\F14-MPF\\modes\\attract\\shows\\attract_light_show.yaml'}
2019-08-04 14:03:09,425 : INFO : AssetManager : Registering Asset: done, File: done.yaml, Default Group: default, Final Config: {'load': 'preload', 'file': 'c:\\MPF\\F14-MPF\\modes\\attract\\shows\\done.yaml'}
2019-08-04 14:03:09,425 : INFO : AssetManager : Registering Asset: output2, File: output2.yaml, Default Group: default, Final Config: {'load': 'preload', 'file': 'c:\\MPF\\F14-MPF\\modes\\attract\\shows\\output2.yaml'}
2019-08-04 14:03:09,425 : INFO : AssetManager : Registering Asset: output3, File: output3.yaml, Default Group: default, Final Config: {'load': 'preload', 'file': 'c:\\MPF\\F14-MPF\\modes\\attract\\shows\\output3.yaml'}
2019-08-04 14:03:09,431 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\fd1c9800874b4ee4e79e79cde1efca3e.mpf_cache
2019-08-04 14:03:09,442 : INFO : EventManager : Event: ======'loading_assets'====== Args={'total': 5, 'loaded': 1, 'remaining': 4, 'percent': 20}
2019-08-04 14:03:09,442 : INFO : AssetManager : Loading assets: 1/5 (20%)
2019-08-04 14:03:09,442 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\ada32ba5658a7d6682eae3e87971bb3f.mpf_cache
2019-08-04 14:03:09,444 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\e7f32f774d37f5de2d68dffce5423c5c.mpf_cache
2019-08-04 14:03:09,457 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\0930f35dfee33f834ab014b684c43311.mpf_cache
2019-08-04 14:03:09,463 : INFO : ConfigProcessor : Loading config from cache: C:\Users\Mark\AppData\Local\Temp\5dd92ec1abedaa53024d98c2cbeef376.mpf_cache
2019-08-04 14:03:09,468 : INFO : EventManager : Event: ======'loading_assets'====== Args={'total': 5, 'loaded': 5, 'remaining': 0, 'percent': 100}
2019-08-04 14:03:09,468 : INFO : EventManager : Event: ======'asset_loading_complete'====== Args={}
2019-08-04 14:03:09,468 : INFO : AssetManager : Loading assets: 5/5 (100%)
2019-08-04 14:03:09,468 : INFO : EventManager : Event: ======'init_phase_4'====== Args={}
2019-08-04 14:03:09,469 : DEBUG : asciimatics.screen : Processing mouse: 48, 22
2019-08-04 14:03:09,470 : DEBUG : asciimatics.widgets : New event: MouseEvent (48, 22) 0
2019-08-04 14:03:09,475 : DEBUG : ball_device.bd_trough : Counted 0 balls. Active switches: []. Old: 0
2019-08-04 14:03:09,476 : DEBUG : ball_device.bd_lock1 : Counted 0 balls. Active switches: []. Old: 0
2019-08-04 14:03:09,476 : DEBUG : ball_device.bd_lock3 : Counted 0 balls. Active switches: []. Old: 0
2019-08-04 14:03:09,477 : INFO : EventManager : Event: ======'machine_var_audits_events_game_ended'====== Args={'value': 34, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,478 : INFO : EventManager : Event: ======'machine_var_audits_events_game_started'====== Args={'value': 515, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,478 : INFO : EventManager : Event: ======'machine_var_audits_player_score'====== Args={'value': {'average': 29041.714285714286, 'top': [189840, 183660, 167080, 73900, 63000, 58280, 55640, 46150, 46100, 29620], 'total': 35}, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,478 : INFO : EventManager : Event: ======'machine_var_audits_shots_generic1_6'====== Args={'value': 147, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,478 : INFO : EventManager : Event: ======'machine_var_audits_shots_generic_ramp_made'====== Args={'value': 154, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,479 : INFO : EventManager : Event: ======'machine_var_audits_shots_inc_bonus'====== Args={'value': 1221, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,479 : INFO : EventManager : Event: ======'machine_var_audits_shots_leftRescue'====== Args={'value': 140, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,479 : INFO : EventManager : Event: ======'machine_var_audits_shots_lower_left_t'====== Args={'value': 8, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,479 : INFO : EventManager : Event: ======'machine_var_audits_shots_rightRescue'====== Args={'value': 145, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,479 : INFO : EventManager : Event: ======'machine_var_audits_shots_target1'====== Args={'value': 3, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,480 : INFO : EventManager : Event: ======'machine_var_audits_shots_target2'====== Args={'value': 4, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,480 : INFO : EventManager : Event: ======'machine_var_audits_shots_target3'====== Args={'value': 4, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,480 : INFO : EventManager : Event: ======'machine_var_audits_shots_target4'====== Args={'value': 1, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,480 : INFO : EventManager : Event: ======'machine_var_audits_shots_target5'====== Args={'value': 1, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,480 : INFO : EventManager : Event: ======'machine_var_audits_shots_target6'====== Args={'value': 1, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,480 : INFO : EventManager : Event: ======'machine_var_audits_shots_tomcat_a'====== Args={'value': 179, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,481 : INFO : EventManager : Event: ======'machine_var_audits_shots_tomcat_c'====== Args={'value': 141, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,481 : INFO : EventManager : Event: ======'machine_var_audits_shots_tomcat_m'====== Args={'value': 190, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,481 : INFO : EventManager : Event: ======'machine_var_audits_shots_tomcat_o'====== Args={'value': 216, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,481 : INFO : EventManager : Event: ======'machine_var_audits_shots_tomcat_second_t'====== Args={'value': 183, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,481 : INFO : EventManager : Event: ======'machine_var_audits_shots_tomcat_t'====== Args={'value': 305, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,482 : INFO : EventManager : Event: ======'machine_var_audits_shots_upper_left_t'====== Args={'value': 5, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,482 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_bonusXLeft'====== Args={'value': 498, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,482 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_bonusXRight'====== Args={'value': 1206, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,482 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_coinCenter'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,482 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_coinLeft'====== Args={'value': 11, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,483 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_coinRight'====== Args={'value': 7, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,483 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_debug'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,483 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_down'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,483 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_downSys11'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,484 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_drain'====== Args={'value': 1, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,484 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_enter'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,484 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_enterSys11'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,485 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_exit'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,485 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_exitSys11'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,485 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_flipperLwL'====== Args={'value': 113, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,486 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_flipperLwR'====== Args={'value': 74, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,486 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_inlaneLeft'====== Args={'value': 200, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,487 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_inlaneRight'====== Args={'value': 146, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,487 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_jetBumper'====== Args={'value': 114, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,487 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_leftRescue'====== Args={'value': 162, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,488 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_left_inlane'====== Args={'value': 4, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,488 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lock1'====== Args={'value': 464, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,488 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lock1_ramp'====== Args={'value': 276, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,488 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lock2'====== Args={'value': 168, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,489 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lock2_ramp'====== Args={'value': 43, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,489 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lock3'====== Args={'value': 285, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,489 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lock3_ramp'====== Args={'value': 217, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,490 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lowerEject'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,490 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lowerLeftM'====== Args={'value': 178, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,490 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lowerLeftO'====== Args={'value': 205, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,491 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lowerLeftT'====== Args={'value': 193, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,491 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lowerRampMade'====== Args={'value': 22, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,491 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lowerRightA'====== Args={'value': 177, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,492 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lowerRightC'====== Args={'value': 131, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,492 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_lowerRightT'====== Args={'value': 155, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,493 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_midLeftEject'====== Args={'value': 36, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,493 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_midRightEject'====== Args={'value': 287, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,493 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_middleEject'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,494 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_middleRampMade'====== Args={'value': 18, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,494 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_outhole'====== Args={'value': 420, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,495 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_outlaneLeft'====== Args={'value': 239, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,495 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_outlaneRight'====== Args={'value': 1236, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,495 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_plunger'====== Args={'value': 1, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,496 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_rampEntry'====== Args={'value': 573, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,496 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_rightEject'====== Args={'value': 25, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,497 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_rightRescue'====== Args={'value': 182, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,497 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_right_inlane'====== Args={'value': 4, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,498 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_shooter'====== Args={'value': 902, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,498 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_slamTilt'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,498 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_slingL'====== Args={'value': 151, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,499 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_slingR'====== Args={'value': 186, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,499 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_spinner'====== Args={'value': 2901, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,500 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_start'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,500 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_startButton'====== Args={'value': 33, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,501 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_target1'====== Args={'value': 62, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,501 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_target2'====== Args={'value': 30, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,502 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_target3'====== Args={'value': 45, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,502 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_target4'====== Args={'value': 17, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,503 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_target5'====== Args={'value': 6, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,503 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_target6'====== Args={'value': 2, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,504 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_tilt'====== Args={'value': 9, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,505 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_trough1'====== Args={'value': 193, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,506 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_trough2'====== Args={'value': 664, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,507 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_trough3'====== Args={'value': 302, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,508 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_trough4'====== Args={'value': 726, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,508 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_trough_centre'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,509 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_trough_left'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,509 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_trough_right'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,510 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_unused57'====== Args={'value': 23, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,510 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_unused58'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,511 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_up'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,511 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_upSys11'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,512 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_upperEject'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,512 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_upperLeftM'====== Args={'value': 0, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,513 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_upperLeftO'====== Args={'value': 6, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,513 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_upperLeftT'====== Args={'value': 144, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,514 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_upperRampMade'====== Args={'value': 78, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,514 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_upperRightA'====== Args={'value': 25, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,515 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_upperRightC'====== Args={'value': 32, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,515 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_upperRightT'====== Args={'value': 34, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,516 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_vUK'====== Args={'value': 276, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,516 : INFO : EventManager : Event: ======'machine_var_audits_switches_s_yagov'====== Args={'value': 68, 'prev_value': None, 'change': True}
2019-08-04 14:03:09,517 : INFO : EventManager : Event: ======'init_phase_5'====== Args={}
2019-08-04 14:03:09,544 : INFO : EventManager : Event: ======'init_done'====== Args={}
2019-08-04 14:03:09,545 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,545 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,546 : INFO : EventManager : Event: ======'machine_reset_phase_1'====== Args={}
2019-08-04 14:03:09,548 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,549 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,549 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,552 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,553 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,553 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,556 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,558 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,558 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,560 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,560 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,561 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,561 : INFO : EventManager : Event: ======'machine_reset_phase_2'====== Args={}
2019-08-04 14:03:09,561 : INFO : EventManager : Event: ======'machine_reset_phase_3'====== Args={}
2019-08-04 14:03:09,562 : INFO : EventManager : Event: ======'diverter_top_diverter_deactivating'====== Args={}
2019-08-04 14:03:09,562 : INFO : coil.c_upperDivertor : Disabling Driver
2019-08-04 14:03:09,562 : DEBUG : lisy : Sending 22 b'\x15'
2019-08-04 14:03:09,562 : INFO : EventManager : Event: ======'diverter_lower_diverter_deactivating'====== Args={}
2019-08-04 14:03:09,563 : INFO : coil.c_lowerDivertor : Disabling Driver
2019-08-04 14:03:09,563 : DEBUG : lisy : Sending 22 b'\x16'
2019-08-04 14:03:09,563 : INFO : EventManager : Event: ======'reset_complete'====== Args={}
2019-08-04 14:03:09,564 : INFO : EventManager : Event: ======'mode_attract_will_start'====== Args={}
2019-08-04 14:03:09,564 : INFO : EventManager : Event: ======'mode_attract_starting'====== Args={}
2019-08-04 14:03:09,564 : INFO : Mode.attract : Started. Priority: 10
2019-08-04 14:03:09,564 : INFO : EventManager : Event: ======'mode_attract_started'====== Args={}
2019-08-04 14:03:09,565 : DEBUG : lisy : Sending 31 bytearray(b'\x0e\x06\x04[\x08\x0f\x08f\x08i@}\x08\x07\x00')
2019-08-04 14:03:09,565 : DEBUG : lisy : Sending 32 bytearray(b'\x0e6\x05s\x08q\x00@\x08w\x08s\x089\x00')
2019-08-04 14:03:09,565 : DEBUG : lisy : Sending 33 bytearray(b'\x07OOOOOOO')
2019-08-04 14:03:09,566 : INFO : EventManager : Event: ======'collecting_balls_complete'====== Args={}
2019-08-04 14:03:09,600 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,601 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,601 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,601 : INFO : Machine : Starting the main run loop.
2019-08-04 14:03:09,606 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,607 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,607 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,609 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,610 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,610 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,613 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,614 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,614 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,632 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,633 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,633 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,638 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,639 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,639 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,642 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,643 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,643 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,647 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,648 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,648 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,651 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,652 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,652 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,655 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,656 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,657 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,659 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,660 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,660 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,663 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,664 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,664 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,667 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,668 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,668 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,671 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,672 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,672 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,675 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,676 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,676 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,679 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,680 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,680 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,683 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,684 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,684 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,688 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,689 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,689 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,692 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,693 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,693 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,696 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,697 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,697 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,700 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,701 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,701 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,704 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,705 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,705 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,708 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,709 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,709 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,712 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,713 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,713 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,716 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,717 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,717 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,720 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,721 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,721 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,725 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,726 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,726 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,729 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,730 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,730 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,733 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,734 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,734 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,737 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,738 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,738 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,741 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,742 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,742 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,745 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,746 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,746 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,749 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,750 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,750 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,753 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,754 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,754 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,757 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,758 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,758 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,761 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,762 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,762 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,765 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,766 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,766 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,769 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,770 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,770 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,773 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,773 : DEBUG : lisy : Sending 101 b''
2019-08-04 14:03:09,773 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,777 : DEBUG : lisy : Received 0
2019-08-04 14:03:09,777 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,777 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,782 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,783 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,783 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,786 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,787 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,787 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,789 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,790 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,790 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,794 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,795 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,795 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,798 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,799 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,799 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,802 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,803 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,803 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,806 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,807 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,807 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,810 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,811 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,811 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,814 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,815 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,815 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,818 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,819 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,819 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,822 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,823 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,823 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,826 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,827 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,827 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,831 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,832 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,832 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,835 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,836 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,836 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,838 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,839 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,839 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,843 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,844 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,845 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,847 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,848 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,848 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,851 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,852 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,852 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,855 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,857 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,858 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,859 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,860 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,860 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,863 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,864 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,864 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,867 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,869 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,870 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,871 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,872 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,873 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,875 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,876 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,876 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,879 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,880 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,880 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,884 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,885 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,885 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,887 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,888 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,888 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,892 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,893 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,893 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,896 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,897 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,897 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,900 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,901 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,901 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,904 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,905 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,906 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,908 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,909 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,909 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,912 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,913 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,914 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,917 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,918 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,918 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,921 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,922 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,922 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,925 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,926 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,926 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,929 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,930 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,930 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,933 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,934 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,934 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,937 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,938 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,938 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,942 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,943 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,943 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,946 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,947 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,947 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,950 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,951 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,951 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,954 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,955 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,956 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,958 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,960 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,961 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,962 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,963 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,963 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,966 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,967 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,967 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,970 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,972 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,972 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,974 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,976 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,977 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,978 : INFO : Machine : Shutting down...
2019-08-04 14:03:09,978 : INFO : EventManager : Event: ======'shutdown'====== Args={}
2019-08-04 14:03:09,985 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,986 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,987 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,988 : DEBUG : ball_device.bd_trough : Stopping ball device
2019-08-04 14:03:09,989 : DEBUG : ball_device.bd_lock1 : Stopping ball device
2019-08-04 14:03:09,991 : DEBUG : lisy : Received 127
2019-08-04 14:03:09,991 : DEBUG : lisy : Sending 41 b''
2019-08-04 14:03:09,992 : DEBUG : lisy : Reading one byte
2019-08-04 14:03:09,993 : DEBUG : ball_device.bd_lock3 : Stopping ball device
2019-08-04 14:03:09,995 : INFO : root : MPF run loop ended.

4. August 2019

Ich verstehe das Log File leider nicht. Die Initialisierung sieht noch gut aus:

2019-08-04 14:03:08,182 : DEBUG : lisy : Sending 7 bytearray(b'\x01')
2019-08-04 14:03:08,184 : DEBUG : lisy : Sending 31 bytearray(b'\x0e\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00')
2019-08-04 14:03:08,184 : DEBUG : lisy : Sending 7 bytearray(b'\x02')
2019-08-04 14:03:08,188 : DEBUG : lisy : Sending 32 bytearray(b'\x0e\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00')
2019-08-04 14:03:08,188 : DEBUG : lisy : Sending 7 bytearray(b'\x03')
2019-08-04 14:03:08,192 : DEBUG : lisy : Sending 33 bytearray(b'\x07\x00\x00\x00\x00\x00\x00\x00')

Da fragt er mit Befehl 7 den Typ der Displays 1, 2 und 3 ab (warum nicht 4?). Er schreibt zwar komischerweise nicht was er da als Antwort kriegt, aber das ist auch erst mal nicht so wichtig. Anschließend löscht er den Inhalt der entsprechenden Displays.

So weit so gut, aber jetzt hört's bei mir auf:

2019-08-04 14:03:09,565 : DEBUG : lisy : Sending 31 bytearray(b'\x0e\x06\x04[\x08\x0f\x08f\x08i@}\x08\x07\x00')
2019-08-04 14:03:09,565 : DEBUG : lisy : Sending 32 bytearray(b'\x0e6\x05s\x08q\x00@\x08w\x08s\x089\x00')
2019-08-04 14:03:09,565 : DEBUG : lisy : Sending 33 bytearray(b'\x07OOOOOOO')

Da versucht er wohl, den eigentlichen Inhalt zu schreiben, aber ich kann da leider nicht mal mehr die einzelnen Bytes erkennen.

Da muss der Jan wohl was zu sagen.

4. August 2019

vor 2 Stunden schrieb Black Knight:

Da muss der Jan wohl was zu sagen.

Ich hab in 0.53.0-dev.51 eine Log Ausgabe in Hex eingebaut. Bitte probier das mal aus. Ich bin mir auch nicht sicher ob die Displayänderungen in dev.50 überhaupt schon drin waren wenn du es nicht aus git gezogen hast.

Jan

4. August 2019

Mit dev.51 kommt ein Fehler mit MPF-MC...

2019-08-04 19:39:05,266 : kivy : Exception while processing RegisteredHandler(callback=<bound method ConfigPlayer.config_play_callback of McConfigPlayer.slides>, priority=0, kwargs={'calling_context': 'mc_ready', 'mode': None, 'settings': {'slides': {'base_slide': {'target': 'laptop', 'priority': None, 'background_color': [0.0, 0.0, 0.0, 1.0], 'show': True, 'force': False, 'expire': None, 'slide': None, 'tokens': {}, 'action': 'play', 'transition': None, 'transition_out': None}}}}, key=UUID('4cc5f49f-9805-4e4f-9b2e-0e18100748bc'), condition=None, blocking_facility=None) for event mc_ready
Traceback (most recent call last):
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpf\core\events.py", line 664, in _run_handlers
    result = handler.callback(**merged_kwargs)
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpf\core\config_player.py", line 341, in config_play_callback
    **kwargs)
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpfmc\config_players\slide_player.py", line 113, in play
    if slide_dict["condition"] and not slide_dict["condition"].evaluate(kwargs):
TypeError: 'ConditionalEvent' object is not subscriptable

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpfmc\commands\mc.py", line 213, in __init__
    thread_stopper=thread_stopper).run()
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\kivy\app.py", line 826, in run
    runTouchApp()
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\kivy\base.py", line 502, in runTouchApp
    EventLoop.window.mainloop()
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\kivy\core\window\window_sdl2.py", line 727, in mainloop
    self._mainloop()
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\kivy\core\window\window_sdl2.py", line 460, in _mainloop
    EventLoop.idle()
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\kivy\base.py", line 337, in idle
    Clock.tick()
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\kivy\clock.py", line 581, in tick
    self._process_events()
File "kivy\_clock.pyx", line 384, in kivy._clock.CyClockBase._process_events
File "kivy\_clock.pyx", line 414, in kivy._clock.CyClockBase._process_events
File "kivy\_clock.pyx", line 412, in kivy._clock.CyClockBase._process_events
File "kivy\_clock.pyx", line 167, in kivy._clock.ClockEvent.tick
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpfmc\uix\display.py", line 205, in _displays_initialized
    self.mc.displays_initialized()
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpfmc\core\mc.py", line 336, in displays_initialized
    self._init()
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpfmc\core\mc.py", line 377, in _init
    self.events.process_event_queue()
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpf\core\events.py", line 745, in process_event_queue
    **event[3])
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpf\core\events.py", line 710, in _process_event
    result = self._run_handlers(event, ev_type, kwargs)
File "c:\users\mark\appdata\local\programs\python\python36\lib\site-packages\mpf\core\events.py", line 666, in _run_handlers
    raise Exception("Exception while processing {} for event {}".format(handler, event)) from e
Exception: Exception while processing RegisteredHandler(callback=<bound method ConfigPlayer.config_play_callback of McConfigPlayer.slides>, priority=0, kwargs={'calling_context': 'mc_ready', 'mode': None, 'settings': {'slides': {'base_slide': {'target': 'laptop', 'priority': None, 'background_color': [0.0, 0.0, 0.0, 1.0], 'show': True, 'force': False, 'expire': None, 'slide': None, 'tokens': {}, 'action': 'play', 'transition': None, 'transition_out': None}}}}, key=UUID('4cc5f49f-9805-4e4f-9b2e-0e18100748bc'), condition=None, blocking_facility=None) for event mc_ready

4. August 2019

Da fehlt vermutlich noch ein mpf-mc Release. Mache ich gleich. Kannst erstmal "mpf game -b" probieren (ohne Grafik und Sound). Das sollte zum Testen von APC reichen.

4. August 2019

Das sieht viel besser aus. Gibt's noch ein paar Segment-fehler (und display 4 geht noch nicht, wegen "index" issue).

IMG_20190804_200758.jpg.9f63fd1c40152cf97d9ea319d1b286a4.jpg IMG_20190804_200845.jpg.aaed74655b32c4f4b7b259b2fb78cf5b.jpg IMG_20190804_200939.jpg.1de199cb06e9a6e09be47f1c815a91e1.jpg

5. August 2019

Ich habe die Mapping-Tabelle gefunden, ich kann es ändern und direkt testen

5. August 2019

2 hours ago, Snux said:

Ich habe die Mapping-Tabelle gefunden, ich kann es ändern und direkt testen

Bei mir sind die entsprechenden Mappings ja auch schon drin:

const byte AlphaUpper[118] = {0,0,0,0,0,0,0,0,107,21,0,0,0,0,0,0,0,0,0,0,64,191,64,21,0,0,64,4,0,0,0,40, // Blank $ * + - / for upper row alphanumeric displays
		63,0,6,0,93,4,15,4,102,4,107,4,123,4,14,0,127,4,111,4,0,0,0,0,136,0,65,4,0,34,0,0,0,0, // 0 1 2 3 4 5 6 7 8 9 < = > and fill bytes
		126,4,15,21,57,0,15,17,121,4,120,4,59,4,118,4,0,17,23,0,112,136,49,0,54,10,54,130,63,0, // Pattern A B C D E F G H I J K L M N O
		124,4,63,128,124,132,107,4,8,17,55,0,48,40,54,160,0,170,0,26,9,40}; // Pattern P Q R S T U V W X Y Z

5. August 2019

Ich hab's gefunden.

@jabdoa - in segment_mappings.py sind Segments "l" und "n" falsch rum. Line 315.

So, anstatt...

bitte

Dann sieht alles gut aus...

IMG_20190805_171058.jpg.34c4fb2fe155fd8b2298f0b8e166d6b7.jpg

5. August 2019

21 hours ago, Snux said:

(und display 4 geht noch nicht, wegen "index" issue).

@jabdoa Wie war da eigentlich der letzte Stand? Kannst du das ändern oder muss ich da nochmal ran?

5. August 2019

vor einer Stunde schrieb Black Knight:

vor 22 Stunden schrieb Snux:

(und display 4 geht noch nicht, wegen "index" issue).

@jabdoa Wie war da eigentlich der letzte Stand? Kannst du das ändern oder muss ich da nochmal ran?

Da müsstest du dann 5 als Anzahl zurück geben. Die fangen immer bei 0 an zu zählen.

vor einer Stunde schrieb Snux:

jabdoa - in segment_mappings.py sind Segments "l" und "n" falsch rum. Line 315.

Machste einen Pull Request? Oder soll ich das machen?

6. August 2019

12 hours ago, jabdoa said:

Da müsstest du dann 5 als Anzahl zurück geben.

OK.

@Snux Falls du das schon mal ausprobieren möchtest:

	case 6:										// get number of displays
		switch (APC_settings[DisplayType]) {
		case 0:									// 4 ALPHA+CREDIT
		case 1:									// Sys11 Pinbot
		case 6:									// Sys3 - 6
		case 7:									// Sys7 + 9
			Serial.write((byte) 5);
			break;
		case 2:									// Sys11 F-14
		case 5:									// Sys11 Riverboat Gambler
			Serial.write((byte) 5);
			break;
		case 3:									// Sys11 BK2K
			Serial.write((byte) 2);
			break;
		case 4:									// Sys11 Taxi
			Serial.write((byte) 3);
			break;}
		break;

Dazu müsstest du USBcontrol.ino öffnen und bei dem 'Serial.write' unter dem 'case 5:' aus der 4 eine 5 machen, wie oben gezeigt.

Anmelden

Arduino Pinball Controller

Empfohlene Beiträge

bontango

Top-Benutzer in diesem Thema

Aktive Tage

Top-Benutzer in diesem Thema

Aktive Tage

Veröffentlichte Bilder

jabdoa

Snux

Black Knight

Snux

Snux

Black Knight

Snux

Snux

Black Knight

Snux

Snux

Black Knight

Snux

Black Knight

jabdoa

Snux

jabdoa

Snux

Snux

Black Knight

Snux

Black Knight

jabdoa

Black Knight

Erstelle ein Benutzerkonto oder melde Dich an, um zu kommentieren

Benutzerkonto erstellen

Anmelden

Umsehen

Aktivitäten

Wichtige Information