[BRLTTY] brltty crashing shortly after starting

Tue Dec 1 19:39:45 EST 2015

Hi Dave,

> The log is showing a real-world problem. For some reason, the driver is
> responding to what it thinks is a VarioPro-only packet that's causing it to
> believe that there are no text cells. Could you please send a new log that
> captures the communication between the driver and the device? To do this,
> replace the log level 7 with the log level debug,inpkts,outpkts.
>

New log attached. Note that, as far as I know, this only happens if brltty 
starts immediately after the device is connected. If I later start brltty 
via systemd, then it starts up and works fine.

Thanks,
-Mike
-------------- next part --------------
2015-12-01 at 18:34:46.994 [brltty] BRLTTY 5.2 rev unknown [http://brltty.com/]
2015-12-01 at 18:34:46.994 [brltty] lock descriptor allocated: queue-discarded-elements
2015-12-01 at 18:34:46.994 [brltty] Log Level: debug,inpkts,outpkts
2015-12-01 at 18:34:46.994 [brltty] pushed command environment: initial
2015-12-01 at 18:34:46.995 [brltty] report listener registered: 0: handleUpdateBrailleOnline
2015-12-01 at 18:34:46.995 [brltty] program exit event added: pid-file
2015-12-01 at 18:34:46.995 [brltty] program exit event added: screen-data
2015-12-01 at 18:34:46.995 [brltty] Working Directory: /
2015-12-01 at 18:34:46.995 [brltty] Configuration File: /etc/brltty.conf
2015-12-01 at 18:34:46.995 [brltty] Updatable Directory: /var/lib/brltty
2015-12-01 at 18:34:46.995 [brltty] Preferences File: brltty.prefs
2015-12-01 at 18:34:46.995 [brltty] Writable Directory: /var/run/brltty
2015-12-01 at 18:34:46.995 [brltty] Drivers Directory: /usr/lib64/brltty
2015-12-01 at 18:34:46.995 [brltty] Tables Directory: /etc/brltty
2015-12-01 at 18:34:46.995 [brltty] program exit event added: tunes
2015-12-01 at 18:34:46.995 [brltty] tune thread state change: 0 -> 1
2015-12-01 at 18:34:46.995 [brltty] tune thread state change: 1 -> 3
2015-12-01 at 18:34:46.995 [brltty] regions: text=0.0 status=0.0
2015-12-01 at 18:34:46.995 [brltty] shifts: full=1 half=0 vertical=5
2015-12-01 at 18:34:46.995 [brltty] Text Table: en-nabcc
2015-12-01 at 18:34:46.996 [brltty] program exit event added: text-table
2015-12-01 at 18:34:46.996 [brltty] Attributes Table: left_right
2015-12-01 at 18:34:46.996 [brltty] program exit event added: attributes-table
2015-12-01 at 18:34:46.996 [brltty] program exit event added: contraction-table
2015-12-01 at 18:34:46.996 [brltty] Contraction Table: none
2015-12-01 at 18:34:46.996 [brltty] Keyboard Property: type=
2015-12-01 at 18:34:46.996 [brltty] Keyboard Property: vendor=
2015-12-01 at 18:34:46.996 [brltty] Keyboard Property: product=
2015-12-01 at 18:34:46.996 [brltty] program exit event added: keyboard-table
2015-12-01 at 18:34:46.996 [brltty] Keyboard Table: none
2015-12-01 at 18:34:46.996 [brltty] program exit event added: screen-driver
2015-12-01 at 18:34:46.996 [brltty] activity action request: screen-driver: start
2015-12-01 at 18:34:46.996 [brltty] activity state change: screen-driver: 4[preparing]
2015-12-01 at 18:34:46.996 [brltty] activity state change: screen-driver: 1[prepared]
2015-12-01 at 18:34:46.996 [brltty] activity state change: screen-driver: 2[scheduled]
2015-12-01 at 18:34:46.996 [brltty] BrlAPI Server: release 0.6.3
2015-12-01 at 18:34:46.996 [brltty] API Parameter: auth=
2015-12-01 at 18:34:46.996 [brltty] API Parameter: host=
2015-12-01 at 18:34:46.996 [brltty] API Parameter: stacksize=
2015-12-01 at 18:34:46.996 [brltty] program exit event added: address-table
2015-12-01 at 18:34:46.996 [brltty] program exit event added: api-server
2015-12-01 at 18:34:46.996 [brltty] program exit event added: braille-data
2015-12-01 at 18:34:46.996 [brltty] program exit event added: braille-driver
2015-12-01 at 18:34:46.996 [brltty] activity action request: braille-driver: start
2015-12-01 at 18:34:46.996 [brltty] activity state change: braille-driver: 4[preparing]
2015-12-01 at 18:34:46.996 [brltty] Braille Display Dimensions: 1 row, 1 column
2015-12-01 at 18:34:46.996 [brltty] regions: text=0.1 status=0.0
2015-12-01 at 18:34:46.996 [brltty] shifts: full=1 half=0 vertical=5
2015-12-01 at 18:34:46.996 [brltty] activity state change: braille-driver: 1[prepared]
2015-12-01 at 18:34:46.996 [brltty] activity state change: braille-driver: 2[scheduled]
2015-12-01 at 18:34:46.996 [brltty] program exit event added: speech-data
2015-12-01 at 18:34:46.996 [brltty] program exit event added: speech-driver
2015-12-01 at 18:34:46.996 [brltty] activity action request: speech-driver: start
2015-12-01 at 18:34:46.996 [brltty] activity state change: speech-driver: 4[preparing]
2015-12-01 at 18:34:46.996 [brltty] activity state change: speech-driver: 1[prepared]
2015-12-01 at 18:34:46.996 [brltty] activity state change: speech-driver: 2[scheduled]
2015-12-01 at 18:34:46.996 [brltty] Speech Input: none
2015-12-01 at 18:34:46.996 [brltty] program exit event added: sessions
2015-12-01 at 18:34:46.996 [brltty] pushed command environment: main
2015-12-01 at 18:34:46.996 [brltty] pushed command handler: unhandled
2015-12-01 at 18:34:46.996 [brltty] pushed command handler: miscellaneous
2015-12-01 at 18:34:46.996 [brltty] pushed command handler: learn
2015-12-01 at 18:34:46.996 [brltty] pushed command handler: speech
2015-12-01 at 18:34:46.996 [brltty] pushed command handler: clipboard
2015-12-01 at 18:34:46.996 [brltty] pushed command handler: preferences
2015-12-01 at 18:34:46.996 [brltty] pushed command handler: toggle
2015-12-01 at 18:34:46.996 [brltty] report listener registered: 3: brailleWindowUpdatedListener
2015-12-01 at 18:34:46.996 [brltty] pushed command handler: touch
2015-12-01 at 18:34:46.997 [brltty] report listener registered: 0: keycodeCommandDataResetListener
2015-12-01 at 18:34:46.997 [brltty] pushed command handler: keycodes
2015-12-01 at 18:34:46.997 [brltty] report listener registered: 0: inputCommandDataResetListener
2015-12-01 at 18:34:46.997 [brltty] pushed command handler: input
2015-12-01 at 18:34:46.997 [brltty] pushed command handler: navigation
2015-12-01 at 18:34:46.997 [brltty] pushed command handler: screen
2015-12-01 at 18:34:46.997 [brltty] pushed command handler: custom
2015-12-01 at 18:34:46.997 [brltty] pushed command handler: API
2015-12-01 at 18:34:46.997 [brltty] activity state change: screen-driver: 6[starting]
2015-12-01 at 18:34:46.997 [brltty] checking for screen driver: lx
2015-12-01 at 18:34:46.997 [brltty] initializing screen driver: lx
2015-12-01 at 18:34:46.997 [brltty] device directory: /dev
2015-12-01 at 18:34:46.997 [brltty] checking screen device: /dev/vcsa
2015-12-01 at 18:34:46.997 [brltty] screen device: vcsa
2015-12-01 at 18:34:46.997 [brltty] checking console device: /dev/tty0
2015-12-01 at 18:34:46.997 [brltty] console device: tty0
2015-12-01 at 18:34:46.997 [brltty] device opened: /dev/vcsa: fd=12
2015-12-01 at 18:34:46.997 [brltty] device opened: /dev/tty0: fd=13
2015-12-01 at 18:34:46.997 [brltty] starting host command: /sbin/modprobe -q uinput
2015-12-01 at 18:34:46.999 [brltty] host command exit status: 0: /sbin/modprobe
2015-12-01 at 18:34:46.999 [brltty] activity state change: braille-driver: 6[starting]
2015-12-01 at 18:34:47.000 [brltty] checking braille device: usb:25000072
2015-12-01 at 18:34:47.000 [brltty] performing braille driver autodetection
2015-12-01 at 18:34:47.000 [brltty] checking for braille driver: al
2015-12-01 at 18:34:47.000 [brltty] initializing braille driver: al -> usb:25000072
2015-12-01 at 18:34:47.079 [brltty] braille driver initialization failed: al -> usb:25000072
2015-12-01 at 18:34:47.079 [brltty] checking for braille driver: bm
2015-12-01 at 18:34:47.079 [brltty] initializing braille driver: bm -> usb:25000072
2015-12-01 at 18:34:47.162 [brltty] USB: Manufacturer Name: HumanWare
2015-12-01 at 18:34:47.163 [brltty] USB: Product Description: Brailliant 40
2015-12-01 at 18:34:47.165 [brltty] USB: Serial Number: 25000072
2015-12-01 at 18:34:47.165 [brltty] program exit event added: sorted-usb-serial-adapters
2015-12-01 at 18:34:47.166 [brltty] probing with Baum Escape protocol
2015-12-01 at 18:34:47.166 [brltty] output packet: sent: 1B 8A
2015-12-01 at 18:34:47.166 [brltty] output packet: sent: 1B 84
2015-12-01 at 18:34:47.166 [brltty] output packet: sent: 1B 01 00
2015-12-01 at 18:34:47.167 [brltty] output packet: sent: 1B 08
2015-12-01 at 18:34:47.168 [brltty] output packet: sent: 1B 50 05 00 00 00 00 04
2015-12-01 at 18:34:47.169 [brltty] activity state change: speech-driver: 6[starting]
2015-12-01 at 18:34:47.169 [brltty] no autodetectable speech drivers
2015-12-01 at 18:34:47.169 [brltty] checking for speech driver: no
2015-12-01 at 18:34:47.169 [brltty] initializing speech driver: no
2015-12-01 at 18:34:47.169 [brltty] Speech Driver: no [NoSpeech]
2015-12-01 at 18:34:47.169 [brltty] NoSpeech Speech Driver:
2015-12-01 at 18:34:47.169 [brltty] activity state change: speech-driver: 3[started]
2015-12-01 at 18:34:47.182 [brltty] Ignored Byte: 00
2015-12-01 at 18:34:48.084 [brltty] probing with Baum Escape protocol
2015-12-01 at 18:34:48.084 [brltty] output packet: sent: 1B 8A
2015-12-01 at 18:34:48.084 [brltty] output packet: sent: 1B 84
2015-12-01 at 18:34:48.084 [brltty] output packet: sent: 1B 01 00
2015-12-01 at 18:34:48.084 [brltty] output packet: sent: 1B 08
2015-12-01 at 18:34:48.085 [brltty] output packet: sent: 1B 50 05 00 00 00 00 04
2015-12-01 at 18:34:48.110 [brltty] input packet: 8A 32 35 30 34 31 35 37 32
2015-12-01 at 18:34:48.110 [brltty] Baum Serial Number: 25041572
2015-12-01 at 18:34:48.126 [brltty] input packet: 84 48 57 47 20 42 72 61 69 6C 6C 69 61 6E 74 34 30
2015-12-01 at 18:34:48.126 [brltty] Baum Device Identity: HWG Brailliant40
2015-12-01 at 18:34:48.126 [brltty] input packet: 01 28
2015-12-01 at 18:34:48.126 [brltty] Cell Count: 40 (40 text, 0 status)
2015-12-01 at 18:34:48.126 [brltty] output packet: sent: 1B 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015-12-01 at 18:34:48.126 [brltty] Braille Display Dimensions: 1 row, 40 columns
2015-12-01 at 18:34:48.126 [brltty] regions: text=0.40 status=0.0
2015-12-01 at 18:34:48.126 [brltty] shifts: full=40 half=20 vertical=5
2015-12-01 at 18:34:48.126 [brltty] Key Bindings: sv
2015-12-01 at 18:34:48.126 [brltty] program exit event added: sorted-command-table
2015-12-01 at 18:34:48.127 [brltty] file opened: /etc/brltty/Input/bm/sv.ktb fd=21
2015-12-01 at 18:34:48.127 [brltty] including data file: /etc/brltty/Input/bm/sv.ktb
2015-12-01 at 18:34:48.127 [brltty] file opened: /etc/brltty/Input/bm/display6.kti fd=22
2015-12-01 at 18:34:48.127 [brltty] including data file: /etc/brltty/Input/bm/display6.kti
2015-12-01 at 18:34:48.127 [brltty] file opened: /etc/brltty/Input/bm/d6.kti fd=23
2015-12-01 at 18:34:48.127 [brltty] including data file: /etc/brltty/Input/bm/d6.kti
2015-12-01 at 18:34:48.127 [brltty] file opened: /etc/brltty/Input/bm/routing6.kti fd=22
2015-12-01 at 18:34:48.127 [brltty] including data file: /etc/brltty/Input/bm/routing6.kti
2015-12-01 at 18:34:48.128 [brltty] Key Table: /etc/brltty/Input/bm/sv.ktb
2015-12-01 at 18:34:48.128 [brltty] constructing special screen: help
2015-12-01 at 18:34:48.128 [brltty] input packet: 22 00 00 00 00 00
2015-12-01 at 18:34:48.128 [brltty] input packet: 24 00
2015-12-01 at 18:34:48.129 [brltty] output packet: sent: 1B 01 07 0A 1D 25 2D 24 0D 11 1B 1B 09 00 0C 13 15 0D 11 0C 0D 1B 1B 15 17 0E 11 3C C0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015-12-01 at 18:34:48.142 [brltty] input packet: 50 02 84 48
2015-12-01 at 18:34:48.142 [brltty] unknown module identifier: 4884
2015-12-01 at 18:34:48.142 [brltty] Cell Count: 0 (0 text, 0 status)
2015-12-01 at 18:34:48.142 [brltty] Ignored Byte: 57
2015-12-01 at 18:34:48.142 [brltty] Ignored Byte: 47
2015-12-01 at 18:34:48.142 [brltty] Ignored Byte: 20
2015-12-01 at 18:34:48.142 [brltty] Ignored Byte: 42
2015-12-01 at 18:34:48.142 [brltty] Braille Display Dimensions: 1 row, 0 columns
2015-12-01 at 18:34:48.142 [brltty] regions: text=0.0 status=0.0
2015-12-01 at 18:34:48.142 [brltty] shifts: full=1 half=0 vertical=5
2015-12-01 at 18:34:48.142 [brltty] report listener registered: 0: brlapi_handleReports
2015-12-01 at 18:34:48.142 [brltty] Braille Driver: bm [Baum]
2015-12-01 at 18:34:48.142 [brltty] Baum Braille Driver:
2015-12-01 at 18:34:48.142 [brltty] Braille Parameter: protocol=
2015-12-01 at 18:34:48.142 [brltty] Braille Parameter: variokeys=
2015-12-01 at 18:34:48.142 [brltty] Braille Device: usb:25000072
2015-12-01 at 18:34:48.142 [brltty] Old Preferences File: /etc/brltty-bm.prefs
2015-12-01 at 18:34:48.142 [brltty] cannot open file: /etc/brltty-bm.prefs: No such file or directory
2015-12-01 at 18:34:48.142 [brltty] regions: text=0.0 status=0.0
2015-12-01 at 18:34:48.142 [brltty] shifts: full=1 half=0 vertical=5