Zum Inhalt springen

Arduino Pinball Controller


Black Knight

Empfohlene Beiträge

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?

Link zu diesem Kommentar
Auf anderen Seiten teilen

  • Antworten 1,6Tsd
  • Erstellt
  • Letzte Antwort

Top-Benutzer in diesem Thema

  • Black Knight

    642

  • bontango

    438

  • Volley

    100

  • jabdoa

    97

Top-Benutzer in diesem Thema

Veröffentlichte Bilder

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.

Link zu diesem Kommentar
Auf anderen Seiten teilen

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?

Link zu diesem Kommentar
Auf anderen Seiten teilen

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.

Link zu diesem Kommentar
Auf anderen Seiten teilen

 

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 von Snux
Link zu diesem Kommentar
Auf anderen Seiten teilen

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.

Link zu diesem Kommentar
Auf anderen Seiten teilen

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..

Link zu diesem Kommentar
Auf anderen Seiten teilen

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

Link zu diesem Kommentar
Auf anderen Seiten teilen

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'?

Link zu diesem Kommentar
Auf anderen Seiten teilen

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

Link zu diesem Kommentar
Auf anderen Seiten teilen

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?

Link zu diesem Kommentar
Auf anderen Seiten teilen

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.

 

Link zu diesem Kommentar
Auf anderen Seiten teilen

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.

Link zu diesem Kommentar
Auf anderen Seiten teilen

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

Link zu diesem Kommentar
Auf anderen Seiten teilen

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

Link zu diesem Kommentar
Auf anderen Seiten teilen

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.

Link zu diesem Kommentar
Auf anderen Seiten teilen

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.jpgIMG_20190804_200845.jpg.aaed74655b32c4f4b7b259b2fb78cf5b.jpgIMG_20190804_200939.jpg.1de199cb06e9a6e09be47f1c815a91e1.jpg

Link zu diesem Kommentar
Auf anderen Seiten teilen

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

 

Link zu diesem Kommentar
Auf anderen Seiten teilen

Ich hab's gefunden.

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

So, anstatt...

return bytes([
            (self.dp << 7) | (self.g1 << 6) | (self.f << 5) | (self.e << 4) | (self.a << 3) | (self.b << 2) |
            (self.c << 1) | self.d,
            (self.n << 7) | (self.dp << 6) | (self.l << 5) | (self.m << 4) | (self.k << 3) | (self.g2 << 2) |
            (self.h << 1) | self.j])

bitte

return bytes([
            (self.dp << 7) | (self.g1 << 6) | (self.f << 5) | (self.e << 4) | (self.a << 3) | (self.b << 2) |
            (self.c << 1) | self.d,
            (self.l << 7) | (self.dp << 6) | (self.n << 5) | (self.m << 4) | (self.k << 3) | (self.g2 << 2) |
            (self.h << 1) | self.j])

 

Dann sieht alles gut aus...

IMG_20190805_171058.jpg.34c4fb2fe155fd8b2298f0b8e166d6b7.jpg

Link zu diesem Kommentar
Auf anderen Seiten teilen

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?

Link zu diesem Kommentar
Auf anderen Seiten teilen

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?

Link zu diesem Kommentar
Auf anderen Seiten teilen

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.

Link zu diesem Kommentar
Auf anderen Seiten teilen

Erstelle ein Benutzerkonto oder melde Dich an, um zu kommentieren

Du musst ein Benutzerkonto haben, um einen Kommentar verfassen zu können

Benutzerkonto erstellen

Neues Benutzerkonto für unsere Community erstellen. Es ist einfach!

Neues Benutzerkonto erstellen

Anmelden

Du hast bereits ein Benutzerkonto? Melde Dich hier an.

Jetzt anmelden

×
×
  • Neu erstellen...

Wichtige Information

Datenschutzerklärung und Registrierungsbedingungen