[BRLTTY] Build brltty with support for espeak-ng

[Tage Johansson]

> Thanks for the log. I think what we need to do is to verify that what we think
> should be happening is indeed actually happening. In addition, we can get more
> timing clues.
>
> I've attached a patch (as speech-test-1.patch). It adds "before" and "after"
> logs to explicit mute requests, to say mute requests, and to say text requests.
> Could you please capture another log after applying this patch?
>

I ran:

$ make distclean

$ git pull origin

$ ./autogen

$ ./configure --with-espeak=no

$ make -j8

$ patch -p1 < speech-test-1.patch

$ sudo ./run-brltty -Lbrltty.log -ldebug,speech


I moved between two lines again and I've attached the log.


I think the problem is that the mute request locks until the previous 
speech is done. The following lines in the log tells that:


286 2019-12-13 at 05:57:02.891 [speech-driver] speech: before say mute
287 2019-12-13 at 05:57:05.037 [speech-driver] speech: sending speech 
message: speech finished
288 2019-12-13 at 05:57:05.037 [core-main] speech: handling speech message: 
speech finished
289 2019-12-13 at 05:57:05.039 [speech-driver] speech: after say mute

Best regards,

Tage


-------------- next part --------------
2019-12-13 at 05:56:36.289 [core-main] BRLTTY 6.0 rev BRLTTY-6.0-409-g5a7d94e1M [http://brltty.app/]
2019-12-13 at 05:56:36.290 [core-main] lock descriptor allocated: queue-discarded-elements
2019-12-13 at 05:56:36.290 [core-main] Log Level: debug,speech
2019-12-13 at 05:56:36.290 [core-main] pushed command environment: initial
2019-12-13 at 05:56:36.290 [core-main] report listener registered: 0: handleUpdateBrailleDeviceOnline
2019-12-13 at 05:56:36.291 [core-main] program exit event added: screen-data
2019-12-13 at 05:56:36.291 [core-main] Working Directory: /home/tage/brltty
2019-12-13 at 05:56:36.291 [core-main] Configuration File: /etc/brltty.conf
2019-12-13 at 05:56:36.292 [core-main] Preferences File: brltty.prefs
2019-12-13 at 05:56:36.292 [core-main] file opened: /var/lib/brltty/brltty.prefs fd=6
2019-12-13 at 05:56:36.292 [core-main] file opened: /var/lib/brltty/brltty.prefs fd=6
2019-12-13 at 05:56:36.292 [core-main] program exit event added: tunes
2019-12-13 at 05:56:36.292 [core-main] tune thread state change: 0 -> 1
2019-12-13 at 05:56:36.293 [core-main] tune thread state change: 1 -> 3
2019-12-13 at 05:56:36.293 [core-main] regions: text=0.0 status=0.0
2019-12-13 at 05:56:36.293 [core-main] shifts: full=1 half=0 vertical=5
2019-12-13 at 05:56:36.293 [core-main] Updatable Directory: /var/lib/brltty
2019-12-13 at 05:56:36.293 [core-main] Writable Directory: /var/run/brltty
2019-12-13 at 05:56:36.293 [core-main] Drivers Directory: /home/tage/brltty/lib
2019-12-13 at 05:56:36.293 [core-main] Tables Directory: /home/tage/brltty/Tables
2019-12-13 at 05:56:36.293 [core-main] Text Table: en-nabcc
2019-12-13 at 05:56:36.293 [core-main] program exit event added: text-table
2019-12-13 at 05:56:36.293 [core-main] Attributes Table: left_right
2019-12-13 at 05:56:36.293 [core-main] program exit event added: attributes-table
2019-12-13 at 05:56:36.293 [core-main] program exit event added: contraction-table
2019-12-13 at 05:56:36.293 [core-main] Contraction Table: none
2019-12-13 at 05:56:36.293 [core-main] Keyboard Property: type=
2019-12-13 at 05:56:36.293 [core-main] Keyboard Property: vendor=
2019-12-13 at 05:56:36.293 [core-main] Keyboard Property: product=
2019-12-13 at 05:56:36.293 [core-main] program exit event added: keyboard-table
2019-12-13 at 05:56:36.293 [core-main] Keyboard Table: none
2019-12-13 at 05:56:36.293 [core-main] program exit event added: screen-driver
2019-12-13 at 05:56:36.293 [core-main] activity action request: screen-driver: start
2019-12-13 at 05:56:36.293 [core-main] activity state change: screen-driver: 4[preparing]
2019-12-13 at 05:56:36.293 [core-main] activity state change: screen-driver: 1[prepared]
2019-12-13 at 05:56:36.293 [core-main] activity state change: screen-driver: 2[scheduled]
2019-12-13 at 05:56:36.293 [core-main] program exit event added: braille-data
2019-12-13 at 05:56:36.293 [core-main] program exit event added: braille-driver
2019-12-13 at 05:56:36.293 [core-main] activity action request: braille-driver: start
2019-12-13 at 05:56:36.293 [core-main] activity state change: braille-driver: 4[preparing]
2019-12-13 at 05:56:36.293 [core-main] Braille Display Dimensions: 1 row, 1 column
2019-12-13 at 05:56:36.293 [core-main] regions: text=0.1 status=0.0
2019-12-13 at 05:56:36.293 [core-main] shifts: full=1 half=0 vertical=5
2019-12-13 at 05:56:36.293 [core-main] activity state change: braille-driver: 1[prepared]
2019-12-13 at 05:56:36.294 [core-main] activity state change: braille-driver: 2[scheduled]
2019-12-13 at 05:56:36.294 [core-main] program exit event added: speech-data
2019-12-13 at 05:56:36.294 [core-main] program exit event added: speech-driver
2019-12-13 at 05:56:36.294 [core-main] activity action request: speech-driver: start
2019-12-13 at 05:56:36.294 [core-main] activity state change: speech-driver: 4[preparing]
2019-12-13 at 05:56:36.294 [core-main] activity state change: speech-driver: 1[prepared]
2019-12-13 at 05:56:36.294 [core-main] activity state change: speech-driver: 2[scheduled]
2019-12-13 at 05:56:36.294 [core-main] Speech Input: none
2019-12-13 at 05:56:36.294 [core-main] BrlAPI Server: release 0.7.0
2019-12-13 at 05:56:36.294 [core-main] API Parameter: auth=
2019-12-13 at 05:56:36.294 [core-main] API Parameter: host=
2019-12-13 at 05:56:36.294 [core-main] API Parameter: stacksize=
2019-12-13 at 05:56:36.294 [core-main] program exit event added: address-table
2019-12-13 at 05:56:36.294 [core-main] program exit event added: api-server
2019-12-13 at 05:56:36.294 [core-main] program exit event added: sessions
2019-12-13 at 05:56:36.294 [core-main] pushed command environment: main
2019-12-13 at 05:56:36.294 [core-main] pushed command handler: unhandled
2019-12-13 at 05:56:36.294 [core-main] pushed command handler: miscellaneous
2019-12-13 at 05:56:36.294 [core-main] pushed command handler: learn
2019-12-13 at 05:56:36.294 [core-main] pushed command handler: speech
2019-12-13 at 05:56:36.294 [core-main] lock descriptor allocated: main-clipboard
2019-12-13 at 05:56:36.294 [core-main] program exit event added: main-clipboard
2019-12-13 at 05:56:36.294 [core-main] pushed command handler: clipboard
2019-12-13 at 05:56:36.294 [core-main] pushed command handler: preferences
2019-12-13 at 05:56:36.294 [core-main] pushed command handler: toggle
2019-12-13 at 05:56:36.294 [core-main] report listener registered: 3: brailleWindowUpdatedListener
2019-12-13 at 05:56:36.294 [core-main] pushed command handler: touch
2019-12-13 at 05:56:36.295 [core-main] report listener registered: 0: keycodeCommandDataResetListener
2019-12-13 at 05:56:36.295 [core-main] pushed command handler: keycodes
2019-12-13 at 05:56:36.295 [core-main] report listener registered: 0: inputCommandDataResetListener
2019-12-13 at 05:56:36.295 [core-main] pushed command handler: input
2019-12-13 at 05:56:36.295 [core-main] pushed command handler: navigation
2019-12-13 at 05:56:36.295 [core-main] pushed command handler: screen
2019-12-13 at 05:56:36.295 [core-main] pushed command handler: custom
2019-12-13 at 05:56:36.295 [core-main] pushed command handler: API
2019-12-13 at 05:56:36.295 [core-main] activity state change: screen-driver: 6[starting]
2019-12-13 at 05:56:36.295 [core-main] checking for screen driver: lx
2019-12-13 at 05:56:36.296 [core-main] initializing screen driver: lx
2019-12-13 at 05:56:36.296 [core-main] device directory: /dev
2019-12-13 at 05:56:36.296 [core-main] checking screen device: /dev/vcsa
2019-12-13 at 05:56:36.296 [core-main] screen device: vcsa
2019-12-13 at 05:56:36.296 [core-main] checking console device: /dev/tty0
2019-12-13 at 05:56:36.296 [core-main] console device: tty0
2019-12-13 at 05:56:36.296 [core-main] checking unicode device: /dev/vcsu
2019-12-13 at 05:56:36.296 [core-main] unicode device: vcsu
2019-12-13 at 05:56:36.296 [core-main] device opened: /dev/tty1: fd=12
2019-12-13 at 05:56:36.297 [core-main] device opened: /dev/vcsa: fd=13
2019-12-13 at 05:56:36.297 [core-main] starting host command: /sbin/modprobe -q uinput
2019-12-13 at 05:56:36.304 [core-main] host command exit status: 0: /sbin/modprobe
2019-12-13 at 05:56:36.305 [core-main] activity state change: braille-driver: 6[starting]
2019-12-13 at 05:56:36.305 [core-main] checking braille device: usb:
2019-12-13 at 05:56:36.305 [core-main] braille device type: USB
2019-12-13 at 05:56:36.305 [core-main] performing braille driver autodetection
2019-12-13 at 05:56:36.305 [core-main] checking for braille driver: al
2019-12-13 at 05:56:36.305 [core-main] initializing braille driver: al -> usb:
2019-12-13 at 05:56:36.307 [core-main] braille driver initialization failed: al -> usb:
2019-12-13 at 05:56:36.307 [core-main] checking for braille driver: bm
2019-12-13 at 05:56:36.307 [core-main] initializing braille driver: bm -> usb:
2019-12-13 at 05:56:36.309 [core-main] USB: Manufacturer Name: BAUM Systeme GmbH
2019-12-13 at 05:56:36.310 [core-main] USB: Product Description: VarioUltra40
2019-12-13 at 05:56:36.311 [core-main] USB: Serial Number: a7af605f-7740-4dae-b329-202daf5ab80d
2019-12-13 at 05:56:36.314 [core-main] program exit event added: sorted-usb-serial-adapters
2019-12-13 at 05:56:36.316 [core-main] probing with Baum HID2 protocol
2019-12-13 at 05:56:36.316 [core-main] activity state change: speech-driver: 6[starting]
2019-12-13 at 05:56:36.316 [core-main] checking for speech driver: en
2019-12-13 at 05:56:36.328 [core-main] initializing speech driver: en
2019-12-13 at 05:56:36.328 [core-main] speech: driver thread constructing
2019-12-13 at 05:56:36.328 [speech-driver] speech: driver thread starting
2019-12-13 at 05:56:36.328 [speech-driver] speech: starting driver
2019-12-13 at 05:56:36.328 [speech-driver] eSpeak-NG version 1.50
2019-12-13 at 05:56:36.352 [speech-driver] speech: driver thread ready
2019-12-13 at 05:56:36.352 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:56:36.352 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:56:36.352 [core-main] Speech Driver: en [eSpeak-NG]
2019-12-13 at 05:56:36.352 [core-main] eSpeak-NG Speech Driver: version 0.1
2019-12-13 at 05:56:36.352 [core-main] Speech Parameter: path=
2019-12-13 at 05:56:36.352 [core-main] Speech Parameter: punctlist=
2019-12-13 at 05:56:36.352 [core-main] Speech Parameter: voice=
2019-12-13 at 05:56:36.352 [core-main] Speech Parameter: maxrate=
2019-12-13 at 05:56:36.352 [core-main] speech: set volume: 10
2019-12-13 at 05:56:36.352 [core-main] speech: enqueuing speech request: set volume
2019-12-13 at 05:56:36.352 [core-main] speech: sending speech request: set volume
2019-12-13 at 05:56:36.352 [core-main] speech: set rate: 15
2019-12-13 at 05:56:36.352 [speech-driver] speech: handling speech request: set volume
2019-12-13 at 05:56:36.352 [core-main] speech: enqueuing speech request: set rate
2019-12-13 at 05:56:36.352 [core-main] speech: set pitch: 10
2019-12-13 at 05:56:36.352 [core-main] speech: enqueuing speech request: set pitch
2019-12-13 at 05:56:36.352 [core-main] speech: set punctuation: 1
2019-12-13 at 05:56:36.352 [core-main] speech: enqueuing speech request: set punctuation
2019-12-13 at 05:56:36.352 [core-main] speech: say: BRLTTY 6.0
2019-12-13 at 05:56:36.352 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:56:36.352 [core-main] activity state change: speech-driver: 3[started]
2019-12-13 at 05:56:36.352 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:56:36.352 [core-main] Baum Device Identity: VarioUltra40
2019-12-13 at 05:56:36.353 [core-main] Cell Count: 40 (40 text, 0 status)
2019-12-13 at 05:56:36.353 [core-main] Braille Display Dimensions: 1 row, 40 columns
2019-12-13 at 05:56:36.353 [core-main] regions: text=0.40 status=0.0
2019-12-13 at 05:56:36.353 [core-main] shifts: full=40 half=20 vertical=5
2019-12-13 at 05:56:36.353 [core-main] Key Bindings: ultra
2019-12-13 at 05:56:36.354 [core-main] program exit event added: sorted-command-table
2019-12-13 at 05:56:36.354 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/ultra.ktb fd=25
2019-12-13 at 05:56:36.354 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/ultra.ktb
2019-12-13 at 05:56:36.354 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/display6.kti fd=26
2019-12-13 at 05:56:36.354 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/display6.kti
2019-12-13 at 05:56:36.354 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/d6.kti fd=27
2019-12-13 at 05:56:36.354 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/d6.kti
2019-12-13 at 05:56:36.355 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/routing6.kti fd=26
2019-12-13 at 05:56:36.355 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/routing6.kti
2019-12-13 at 05:56:36.355 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/routing.kti fd=27
2019-12-13 at 05:56:36.355 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/routing.kti
2019-12-13 at 05:56:36.355 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/b9b10.kti fd=26
2019-12-13 at 05:56:36.355 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/b9b10.kti
2019-12-13 at 05:56:36.355 [core-main] program exit event added: sorted-keyboard-functions
2019-12-13 at 05:56:36.355 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/keyboard.kti fd=27
2019-12-13 at 05:56:36.355 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/keyboard.kti
2019-12-13 at 05:56:36.355 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../chords.kti fd=28
2019-12-13 at 05:56:36.356 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../chords.kti
2019-12-13 at 05:56:36.356 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.356 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.356 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.356 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.356 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.356 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.356 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.356 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.356 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.356 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.356 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.357 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.357 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.357 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.357 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.357 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.357 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.357 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.357 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../toggle.kti fd=29
2019-12-13 at 05:56:36.357 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../toggle.kti
2019-12-13 at 05:56:36.358 [core-main] file opened: /home/tage/brltty/Tables/Input/bm/../menu.kti fd=29
2019-12-13 at 05:56:36.358 [core-main] including data file: /home/tage/brltty/Tables/Input/bm/../menu.kti
2019-12-13 at 05:56:36.418 [core-main] Key Table: /home/tage/brltty/Tables/Input/bm/ultra.ktb
2019-12-13 at 05:56:36.418 [core-main] constructing special screen: help
2019-12-13 at 05:56:36.420 [core-main] lock descriptor allocated: braille-driver
2019-12-13 at 05:56:36.420 [core-main] braille is online
2019-12-13 at 05:56:36.420 [core-main] Baum Serial Number: 28000070
2019-12-13 at 05:56:36.420 [core-main] Braille Driver: bm [Baum]
2019-12-13 at 05:56:36.420 [core-main] Baum Braille Driver:
2019-12-13 at 05:56:36.420 [core-main] Braille Parameter: protocol=
2019-12-13 at 05:56:36.420 [core-main] Braille Parameter: variokeys=
2019-12-13 at 05:56:36.420 [core-main] Braille Device: usb:
2019-12-13 at 05:56:36.420 [core-main] Old Preferences File: /etc/brltty-bm.prefs
2019-12-13 at 05:56:36.420 [core-main] report listener registered: 0: brlapi_handleReports
2019-12-13 at 05:56:36.420 [core-main] regions: text=0.40 status=0.0
2019-12-13 at 05:56:36.420 [core-main] shifts: full=40 half=20 vertical=5
2019-12-13 at 05:56:36.420 [core-main] program exit event added: alert-tunes
2019-12-13 at 05:56:36.420 [core-main] activity state change: braille-driver: 3[started]
2019-12-13 at 05:56:36.421 [tune-thread] starting host command: /sbin/modprobe -q pcspkr
2019-12-13 at 05:56:36.422 [core-main] device opened: /dev/vcsu: fd=26
2019-12-13 at 05:56:36.422 [core-main] device opened: /dev/tty0: fd=27
2019-12-13 at 05:56:36.423 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:56:36.424 [core-main] speech: sending speech request: set rate
2019-12-13 at 05:56:36.424 [speech-driver] speech: handling speech request: set rate
2019-12-13 at 05:56:36.424 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:56:36.424 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:56:36.424 [core-main] speech: sending speech request: set pitch
2019-12-13 at 05:56:36.424 [speech-driver] speech: handling speech request: set pitch
2019-12-13 at 05:56:36.424 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:56:36.424 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:56:36.424 [core-main] speech: sending speech request: set punctuation
2019-12-13 at 05:56:36.424 [speech-driver] speech: handling speech request: set punctuation
2019-12-13 at 05:56:36.424 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:56:36.424 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:56:36.424 [core-main] speech: sending speech request: say text
2019-12-13 at 05:56:36.424 [speech-driver] speech: handling speech request: say text
2019-12-13 at 05:56:36.424 [speech-driver] speech: before say mute
2019-12-13 at 05:56:36.424 [speech-driver] speech: after say mute
2019-12-13 at 05:56:36.424 [speech-driver] speech: before say text
2019-12-13 at 05:56:36.424 [speech-driver] speech: after say text
2019-12-13 at 05:56:36.424 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:56:36.424 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:56:36.428 [tune-thread] host command exit status: 1: /sbin/modprobe
2019-12-13 at 05:56:36.429 [tune-thread] kernel module not installed: pcspkr
2019-12-13 at 05:56:36.429 [tune-thread] beeper enabled
2019-12-13 at 05:56:36.429 [tune-thread] tone: MSecs:60 Freq:329
2019-12-13 at 05:56:36.489 [tune-thread] tone: MSecs:100 Freq:440
2019-12-13 at 05:56:36.806 [core-main] checking uinput device: /dev/uinput
2019-12-13 at 05:56:36.806 [core-main] uinput device: uinput
2019-12-13 at 05:56:36.806 [core-main] device opened: /dev/uinput: fd=28
2019-12-13 at 05:56:36.806 [core-main] uinput opened: uinput: BRLTTY 6.0 Linux Screen Driver Keyboard fd=28
2019-12-13 at 05:56:36.807 [core-main] report listener registered: 1: lxBrailleDeviceOfflineListener
2019-12-13 at 05:56:36.807 [core-main] Screen Driver: lx [Linux]
2019-12-13 at 05:56:36.807 [core-main] Linux Screen Driver:
2019-12-13 at 05:56:36.807 [core-main] Screen Parameter: charset=
2019-12-13 at 05:56:36.807 [core-main] Screen Parameter: fallbacktext=
2019-12-13 at 05:56:36.807 [core-main] Screen Parameter: hfb=
2019-12-13 at 05:56:36.807 [core-main] Screen Parameter: logsfm=
2019-12-13 at 05:56:36.807 [core-main] Screen Parameter: unicode=
2019-12-13 at 05:56:36.807 [core-main] Screen Parameter: vt=
2019-12-13 at 05:56:36.807 [core-main] activity state change: screen-driver: 3[started]
2019-12-13 at 05:56:36.807 [core-main] report listener unregistered: 0: brlapi_handleReports
2019-12-13 at 05:56:36.807 [core-main] pushed command environment: message
2019-12-13 at 05:56:36.807 [core-main] pushed command handler: message
2019-12-13 at 05:56:37.407 [speech-driver] speech: sending speech message: speech finished
2019-12-13 at 05:56:37.407 [core-main] speech: handling speech message: speech finished
2019-12-13 at 05:56:38.490 [tune-thread] beeper disabled
2019-12-13 at 05:56:40.811 [core-main] popped command handler: message
2019-12-13 at 05:56:40.811 [core-main] popped command environment: message
2019-12-13 at 05:56:40.811 [core-main] report listener registered: 0: brlapi_handleReports
2019-12-13 at 05:56:40.811 [core-main] speech: autospeak: current line: [40,10] 0.82
2019-12-13 at 05:56:40.811 [core-main] speech: say: [tage at alarm ~]$ bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb                   
2019-12-13 at 05:56:40.811 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:56:40.811 [core-main] speech: sending speech request: say text
2019-12-13 at 05:56:40.811 [speech-driver] speech: handling speech request: say text
2019-12-13 at 05:56:40.811 [speech-driver] speech: before say mute
2019-12-13 at 05:56:40.813 [speech-driver] speech: after say mute
2019-12-13 at 05:56:40.813 [speech-driver] speech: before say text
2019-12-13 at 05:56:40.813 [speech-driver] speech: after say text
2019-12-13 at 05:56:40.813 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:56:40.813 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:56:44.717 [speech-driver] speech: sending speech message: speech finished
2019-12-13 at 05:56:44.717 [core-main] speech: handling speech message: speech finished
2019-12-13 at 05:57:02.587 [core-main] program exit event added: command-queue
2019-12-13 at 05:57:02.587 [core-main] command: 000000 (NOOP: do nothing)
2019-12-13 at 05:57:02.595 [core-main] command: 000001 (LNUP: go up one line)
2019-12-13 at 05:57:02.602 [core-main] speech: autospeak: line selected: [40,9] 0.82
2019-12-13 at 05:57:02.602 [core-main] speech: say: -bash: bbbbbbbbbbbbbbbbbbbbbbbbbb: command not found                              
2019-12-13 at 05:57:02.603 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:57:02.603 [core-main] speech: sending speech request: say text
2019-12-13 at 05:57:02.603 [speech-driver] speech: handling speech request: say text
2019-12-13 at 05:57:02.603 [speech-driver] speech: before say mute
2019-12-13 at 05:57:02.605 [speech-driver] speech: after say mute
2019-12-13 at 05:57:02.605 [speech-driver] speech: before say text
2019-12-13 at 05:57:02.605 [speech-driver] speech: after say text
2019-12-13 at 05:57:02.605 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:57:02.605 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:57:02.875 [core-main] command: 000000 (NOOP: do nothing)
2019-12-13 at 05:57:02.883 [core-main] command: 000002 (LNDN: go down one line)
2019-12-13 at 05:57:02.890 [core-main] speech: autospeak: line selected: [40,10] 0.82
2019-12-13 at 05:57:02.891 [core-main] speech: say: [tage at alarm ~]$ bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb                   
2019-12-13 at 05:57:02.891 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:57:02.891 [core-main] speech: sending speech request: say text
2019-12-13 at 05:57:02.891 [speech-driver] speech: handling speech request: say text
2019-12-13 at 05:57:02.891 [speech-driver] speech: before say mute
2019-12-13 at 05:57:05.037 [speech-driver] speech: sending speech message: speech finished
2019-12-13 at 05:57:05.037 [core-main] speech: handling speech message: speech finished
2019-12-13 at 05:57:05.039 [speech-driver] speech: after say mute
2019-12-13 at 05:57:05.039 [speech-driver] speech: before say text
2019-12-13 at 05:57:05.039 [speech-driver] speech: after say text
2019-12-13 at 05:57:05.039 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:57:05.039 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:57:05.619 [core-main] command: 000000 (NOOP: do nothing)
2019-12-13 at 05:57:05.627 [core-main] command: 000001 (LNUP: go up one line)
2019-12-13 at 05:57:05.634 [core-main] speech: autospeak: line selected: [40,9] 0.82
2019-12-13 at 05:57:05.635 [core-main] speech: say: -bash: bbbbbbbbbbbbbbbbbbbbbbbbbb: command not found                              
2019-12-13 at 05:57:05.635 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:57:05.635 [core-main] speech: sending speech request: say text
2019-12-13 at 05:57:05.635 [speech-driver] speech: handling speech request: say text
2019-12-13 at 05:57:05.635 [speech-driver] speech: before say mute
2019-12-13 at 05:57:05.679 [speech-driver] speech: sending speech message: speech finished
2019-12-13 at 05:57:05.679 [core-main] speech: handling speech message: speech finished
2019-12-13 at 05:57:05.681 [speech-driver] speech: after say mute
2019-12-13 at 05:57:05.681 [speech-driver] speech: before say text
2019-12-13 at 05:57:05.681 [speech-driver] speech: after say text
2019-12-13 at 05:57:05.681 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:57:05.681 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:57:05.811 [core-main] command: 000000 (NOOP: do nothing)
2019-12-13 at 05:57:05.819 [core-main] command: 000002 (LNDN: go down one line)
2019-12-13 at 05:57:05.826 [core-main] speech: autospeak: line selected: [40,10] 0.82
2019-12-13 at 05:57:05.827 [core-main] speech: say: [tage at alarm ~]$ bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb                   
2019-12-13 at 05:57:05.827 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:57:05.827 [core-main] speech: sending speech request: say text
2019-12-13 at 05:57:05.827 [speech-driver] speech: handling speech request: say text
2019-12-13 at 05:57:05.827 [speech-driver] speech: before say mute
2019-12-13 at 05:57:06.099 [core-main] command: 000000 (NOOP: do nothing)
2019-12-13 at 05:57:06.107 [core-main] command: 000001 (LNUP: go up one line)
2019-12-13 at 05:57:06.114 [core-main] speech: autospeak: line selected: [40,9] 0.82
2019-12-13 at 05:57:06.114 [core-main] speech: say: -bash: bbbbbbbbbbbbbbbbbbbbbbbbbb: command not found                              
2019-12-13 at 05:57:06.115 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:57:06.371 [core-main] command: 000000 (NOOP: do nothing)
2019-12-13 at 05:57:06.379 [core-main] command: 000002 (LNDN: go down one line)
2019-12-13 at 05:57:06.387 [core-main] speech: autospeak: line selected: [40,10] 0.82
2019-12-13 at 05:57:06.387 [core-main] speech: say: [tage at alarm ~]$ bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb                   
2019-12-13 at 05:57:06.387 [core-main] speech: unqueuing speech request: say text
2019-12-13 at 05:57:06.387 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:57:06.723 [core-main] command: 000000 (NOOP: do nothing)
2019-12-13 at 05:57:06.731 [core-main] command: 000001 (LNUP: go up one line)
2019-12-13 at 05:57:06.738 [core-main] speech: autospeak: line selected: [40,9] 0.82
2019-12-13 at 05:57:06.738 [core-main] speech: say: -bash: bbbbbbbbbbbbbbbbbbbbbbbbbb: command not found                              
2019-12-13 at 05:57:06.739 [core-main] speech: unqueuing speech request: say text
2019-12-13 at 05:57:06.739 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:57:07.059 [core-main] command: 000000 (NOOP: do nothing)
2019-12-13 at 05:57:07.067 [core-main] command: 000002 (LNDN: go down one line)
2019-12-13 at 05:57:07.074 [core-main] speech: autospeak: line selected: [40,10] 0.82
2019-12-13 at 05:57:07.074 [core-main] speech: say: [tage at alarm ~]$ bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb                   
2019-12-13 at 05:57:07.075 [core-main] speech: unqueuing speech request: say text
2019-12-13 at 05:57:07.075 [core-main] speech: enqueuing speech request: say text
2019-12-13 at 05:57:08.117 [speech-driver] speech: sending speech message: speech finished
2019-12-13 at 05:57:08.117 [core-main] speech: handling speech message: speech finished
2019-12-13 at 05:57:08.119 [speech-driver] speech: after say mute
2019-12-13 at 05:57:08.119 [speech-driver] speech: before say text
2019-12-13 at 05:57:08.119 [speech-driver] speech: after say text
2019-12-13 at 05:57:08.119 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:57:08.119 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:57:08.119 [core-main] speech: sending speech request: say text
2019-12-13 at 05:57:08.119 [speech-driver] speech: handling speech request: say text
2019-12-13 at 05:57:08.119 [speech-driver] speech: before say mute
2019-12-13 at 05:57:08.123 [speech-driver] speech: after say mute
2019-12-13 at 05:57:08.123 [speech-driver] speech: before say text
2019-12-13 at 05:57:08.124 [speech-driver] speech: after say text
2019-12-13 at 05:57:08.124 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:57:08.124 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:57:08.172 [speech-driver] speech: sending speech message: speech finished
2019-12-13 at 05:57:08.172 [core-main] speech: handling speech message: speech finished
2019-12-13 at 05:57:12.017 [speech-driver] speech: sending speech message: speech finished
2019-12-13 at 05:57:12.017 [core-main] speech: handling speech message: speech finished
2019-12-13 at 05:57:23.622 [core-main] stopping program components
2019-12-13 at 05:57:23.622 [core-main] stopping program component: command-queue
2019-12-13 at 05:57:23.622 [core-main] stopping program component: alert-tunes
2019-12-13 at 05:57:23.622 [core-main] stopping program component: sorted-keyboard-functions
2019-12-13 at 05:57:23.623 [core-main] stopping program component: sorted-command-table
2019-12-13 at 05:57:23.623 [core-main] stopping program component: sorted-usb-serial-adapters
2019-12-13 at 05:57:23.623 [core-main] stopping program component: main-clipboard
2019-12-13 at 05:57:23.623 [core-main] stopping program component: sessions
2019-12-13 at 05:57:23.623 [core-main] popped command handler: API
2019-12-13 at 05:57:23.623 [core-main] popped command handler: custom
2019-12-13 at 05:57:23.623 [core-main] popped command handler: screen
2019-12-13 at 05:57:23.623 [core-main] popped command handler: navigation
2019-12-13 at 05:57:23.623 [core-main] popped command handler: input
2019-12-13 at 05:57:23.623 [core-main] report listener unregistered: 0: inputCommandDataResetListener
2019-12-13 at 05:57:23.623 [core-main] popped command handler: keycodes
2019-12-13 at 05:57:23.623 [core-main] report listener unregistered: 0: keycodeCommandDataResetListener
2019-12-13 at 05:57:23.623 [core-main] popped command handler: touch
2019-12-13 at 05:57:23.623 [core-main] report listener unregistered: 3: brailleWindowUpdatedListener
2019-12-13 at 05:57:23.623 [core-main] popped command handler: toggle
2019-12-13 at 05:57:23.623 [core-main] popped command handler: preferences
2019-12-13 at 05:57:23.623 [core-main] popped command handler: clipboard
2019-12-13 at 05:57:23.623 [core-main] popped command handler: speech
2019-12-13 at 05:57:23.623 [core-main] popped command handler: learn
2019-12-13 at 05:57:23.623 [core-main] popped command handler: miscellaneous
2019-12-13 at 05:57:23.623 [core-main] popped command handler: unhandled
2019-12-13 at 05:57:23.623 [core-main] popped command environment: main
2019-12-13 at 05:57:23.623 [core-main] stopping program component: api-server
2019-12-13 at 05:57:23.623 [core-main] report listener unregistered: 0: brlapi_handleReports
2019-12-13 at 05:57:23.623 [server-main] select: Interrupted system call
2019-12-13 at 05:57:23.625 [core-main] stopping program component: address-table
2019-12-13 at 05:57:23.625 [core-main] stopping program component: speech-driver
2019-12-13 at 05:57:23.625 [core-main] activity action request: speech-driver: stop
2019-12-13 at 05:57:23.625 [core-main] activity state change: speech-driver: 9[stopping]
2019-12-13 at 05:57:23.625 [core-main] speech: mute: driver stop
2019-12-13 at 05:57:23.625 [core-main] speech: enqueuing speech request: mute speech
2019-12-13 at 05:57:23.625 [core-main] speech: sending speech request: mute speech
2019-12-13 at 05:57:23.625 [core-main] speech: enqueuing speech request: stop
2019-12-13 at 05:57:23.625 [speech-driver] speech: handling speech request: mute speech
2019-12-13 at 05:57:23.625 [speech-driver] speech: before explicit mute
2019-12-13 at 05:57:23.628 [speech-driver] speech: after explicit mute
2019-12-13 at 05:57:23.628 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:57:23.628 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:57:23.628 [core-main] speech: sending speech request: stop
2019-12-13 at 05:57:23.628 [speech-driver] speech: handling speech request: stop
2019-12-13 at 05:57:23.628 [speech-driver] speech: driver thread stopping
2019-12-13 at 05:57:23.628 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:57:23.628 [speech-driver] speech: stopping driver
2019-12-13 at 05:57:23.628 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:57:23.631 [speech-driver] speech: sending speech message: request finished
2019-12-13 at 05:57:23.631 [speech-driver] speech: driver thread finished
2019-12-13 at 05:57:23.631 [core-main] speech: handling speech message: request finished
2019-12-13 at 05:57:23.632 [core-main] activity state change: speech-driver: 0[stopped]
2019-12-13 at 05:57:23.632 [core-main] stopping program component: speech-data
2019-12-13 at 05:57:23.632 [core-main] stopping program component: braille-driver
2019-12-13 at 05:57:23.632 [core-main] pushed command environment: message
2019-12-13 at 05:57:23.632 [core-main] pushed command handler: message
2019-12-13 at 05:57:23.633 [core-main] popped command handler: message
2019-12-13 at 05:57:23.633 [core-main] popped command environment: message
2019-12-13 at 05:57:23.633 [core-main] activity action request: braille-driver: stop
2019-12-13 at 05:57:23.633 [core-main] activity state change: braille-driver: 9[stopping]
2019-12-13 at 05:57:23.635 [core-main] braille is offline
2019-12-13 at 05:57:23.636 [core-main] program exit event added: alert-tunes
2019-12-13 at 05:57:23.636 [core-main] activity state change: braille-driver: 0[stopped]
2019-12-13 at 05:57:23.636 [core-main] stopping program component: alert-tunes
2019-12-13 at 05:57:23.637 [tune-thread] beeper enabled
2019-12-13 at 05:57:23.637 [tune-thread] tone: MSecs:60 Freq:329
2019-12-13 at 05:57:23.697 [tune-thread] tone: MSecs:60 Freq:220
2019-12-13 at 05:57:23.757 [core-main] stopping program component: braille-data
2019-12-13 at 05:57:23.757 [core-main] stopping program component: screen-driver
2019-12-13 at 05:57:23.757 [core-main] activity action request: screen-driver: stop
2019-12-13 at 05:57:23.757 [core-main] activity state change: screen-driver: 9[stopping]
2019-12-13 at 05:57:23.757 [core-main] report listener unregistered: 1: lxBrailleDeviceOfflineListener
2019-12-13 at 05:57:23.914 [core-main] activity state change: screen-driver: 0[stopped]
2019-12-13 at 05:57:23.914 [core-main] stopping program component: keyboard-table
2019-12-13 at 05:57:23.914 [core-main] stopping program component: contraction-table
2019-12-13 at 05:57:23.914 [core-main] lock descriptor allocated: contraction-table
2019-12-13 at 05:57:23.914 [core-main] stopping program component: attributes-table
2019-12-13 at 05:57:23.914 [core-main] lock descriptor allocated: attributes-table
2019-12-13 at 05:57:23.914 [core-main] stopping program component: text-table
2019-12-13 at 05:57:23.914 [core-main] lock descriptor allocated: text-table
2019-12-13 at 05:57:23.914 [core-main] stopping program component: tunes
2019-12-13 at 05:57:23.914 [tune-thread] beeper disabled
2019-12-13 at 05:57:23.914 [tune-thread] tune thread state change: 3 -> 4
2019-12-13 at 05:57:23.914 [core-main] tune thread state change: 4 -> 5
2019-12-13 at 05:57:23.914 [core-main] stopping program component: screen-data
2019-12-13 at 05:57:23.914 [core-main] destructing special screen: help
2019-12-13 at 05:57:23.914 [core-main] stopping program component: log