Current version of the OpenAuto Pro is 9.0
Thread Rating:
  • 0 Vote(s) - 0 Average
  • 1
  • 2
  • 3
  • 4
  • 5
AA Crashes
#1
When I connect my phone, or the RPI boots up with my phone connected, AA loads and renders the map and start to play audio.  Within 2 minutes though, whether interacting with it or not AA will die, the audio will stop and it will go back to the Openauto screen to select USB, WIFI, etc.

My phone is a LG V20, using the new AA interface (but switching back to old has the same issue) and I'm running OAP 3.3.2b2 on a RPI 3 B+.

I can use the A2DP/Bluetooth to stream media without any stability issues, but if I plug my phone in it's guaranteed AA going back to the OAP screen.  On the phone it acts like AA is still functioning with no errors, I have to unplug the phone to get AA to reconnect.

These are the logs from a recent brief session booting up the RPI with phone already attached and launching straight into AA and crashing, followed by me unplugging the phone (around 00:49).

Any thoughts?


Quote:-- syslog --
Sep  6 00:44:22 lr3carpc systemd[1]: openautopro.splash.service: Succeeded.
Sep  6 00:44:22 lr3carpc systemd[1]: Started OpenAuto Pro Splash service.
Sep  6 00:44:22 lr3carpc systemd[1]: Startup finished in 1.468s (kernel) + 26.813s (userspace) = 28.282s.
Sep  6 00:44:30 lr3carpc systemd[1]: systemd-fsckd.service: Succeeded.
Sep  6 00:44:33 lr3carpc systemd[1]: systemd-hostnamed.service: Succeeded.
Sep  6 00:44:45 lr3carpc systemd-timesyncd[284]: Synchronized to time server for the first time [2606:c680:0:b:3830:34ff:fe66:6663]:123 (2.debian.pool.ntp.org).
Sep  6 00:44:52 lr3carpc pulseaudio[704]: E: [alsa-source-USB Audio] module-echo-cancel.c: Doing resync
Sep  6 00:44:52 lr3carpc pulseaudio[704]: E: [alsa-source-USB Audio] module-echo-cancel.c: Playback too far ahead (88942), drop source 11384
Sep  6 00:45:23 lr3carpc pulseaudio[704]: E: [alsa-source-USB Audio] module-echo-cancel.c: Doing resync
Sep  6 00:45:23 lr3carpc pulseaudio[704]: E: [alsa-source-USB Audio] module-echo-cancel.c: Playback too far ahead (96559), drop source 12356
Sep  6 00:46:55 lr3carpc kernel: [  172.275321] usb 1-1.1.2.1.1.2: USB disconnect, device number 14
Sep  6 00:48:58 lr3carpc systemd[1]: Started Session c3 of user pi.


Quote:-- messages --
Sep  6 00:44:16 lr3carpc kernel: [  22.313076] usb 1-1.1.2.1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Sep  6 00:44:16 lr3carpc kernel: [  22.313081] usb 1-1.1.2.1.1.2: Product: LG-H910
Sep  6 00:44:16 lr3carpc kernel: [  22.313086] usb 1-1.1.2.1.1.2: Manufacturer: LGE
Sep  6 00:44:16 lr3carpc kernel: [  22.313090] usb 1-1.1.2.1.1.2: SerialNumber: LGH910xxxxxxxxxxx
Sep  6 00:44:16 lr3carpc mtp-probe: checking bus 1, device 14: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2.1/1-1.1.2.1.1/1-1.1.2.1.1.2"
Sep  6 00:44:16 lr3carpc mtp-probe: bus: 1, device: 14 was not an MTP device
Sep  6 00:44:16 lr3carpc mtp-probe: checking bus 1, device 14: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2.1/1-1.1.2.1.1/1-1.1.2.1.1.2"
Sep  6 00:44:16 lr3carpc mtp-probe: bus: 1, device: 14 was not an MTP devicecd
Sep  6 00:44:17 lr3carpc kernel: [  23.682809] input: D0:13:FD:73:D8:D5 as /devices/virtual/input/input8
Sep  6 00:46:55 lr3carpc kernel: [  172.275321] usb 1-1.1.2.1.1.2: USB disconnect, device number 14

Quote:-- openauto.log --
[2019-09-06 00:44:13.606688] [0x7135c260] [warning] [OpenAuto] [RecentAddressesList] failed to read configuration file: .openauto/config/openauto_wifi_recent.ini, error: .openauto/config/openauto_wifi_recent.ini: cannot open file. Empty list will be used.
[2019-09-06 00:44:13.759555] [0x7135c260] [warning] [OpenAuto] [ObdConfiguration] failed to read configuration file: .openauto/config/openauto_obd_pid_info.ini, error: .openauto/config/openauto_obd_pid_info.ini: cannot open file. Empty list will be used.
[2019-09-06 00:44:13.760174] [0x7135c260] [warning] [OpenAuto] [ObdConfiguration] failed to read configuration file: .openauto/config/openauto_obd_sport_dashboard.ini, error: .openauto/config/openauto_obd_sport_dashboard.ini: cannot open file. Empty list will be used.
[2019-09-06 00:44:13.761095] [0x7135c260] [warning] [OpenAuto] [ObdConfiguration] failed to read configuration file: .openauto/config/openauto_obd_detailed_dashboard.ini, error: .openauto/config/openauto_obd_detailed_dashboard.ini: cannot open file. Empty list will be used.
[2019-09-06 00:44:13.960675] [0x7135c260] [debug]  [OpenAuto] [TemperatureSensorController] start.
[2019-09-06 00:44:13.962919] [0x7135c260] [warning] [OpenAuto] [DisplayManager] Recalculating available geometry.
[2019-09-06 00:44:13.963065] [0x7135c260] [warning] [OpenAuto] [DisplayManager] Recalculating available geometry.
[2019-09-06 00:44:13.963270] [0x7135c260] [info]    [OpenAuto] [DayNightManager] OpenAuto manual mode changed, mode: 1
[2019-09-06 00:44:13.963315] [0x657f9370] [info]    [OpenAuto] [AndroidAutoApp] Initialized display region width: 800, height: 455, x: 0, y: 25
[2019-09-06 00:44:13.963392] [0x7135c260] [debug]  [OpenAuto] [RearCameraManager] start.
[2019-09-06 00:44:13.963424] [0x657f9370] [info]    [OpenAuto] [AndroidAutoApp] Initialized screen geometry width: 800, height: 455, x: 0, y: 25
[2019-09-06 00:44:15.480998] [0x7135c260] [warning] [OpenAuto] [DisplayManager] Recalculating available geometry.
[2019-09-06 00:44:15.793134] [0x65ffa370] [info]    [OpenAuto] [AndroidAutoApp] Enable autostart.
[2019-09-06 00:44:15.813697] [0x7135c260] [info]    [OpenAuto] [BluetoothInitializer] starting backend...
[2019-09-06 00:44:15.815156] [0x7135c260] [info]    [OpenAuto] [HandsFreeModemsList] telephony availability changed: true
[2019-09-06 00:44:15.852047] [0x657f9370] [info]    [OpenAuto] [AndroidAutoApp] Initialized display region width: 800, height: 452, x: 0, y: 28
[2019-09-06 00:44:15.852244] [0x657f9370] [info]    [OpenAuto] [AndroidAutoApp] Initialized screen geometry width: 800, height: 452, x: 0, y: 28
[2019-09-06 00:44:16.841503] [0x7135c260] [info]    [OpenAuto] [HandsFreeModemsList] Found HFP modem, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5
[2019-09-06 00:44:16.843573] [0x7135c260] [info]    [OpenAuto] [AutoConnector] Adapter added, address: 00:1A:7D:DA:71:13
[2019-09-06 00:44:16.843691] [0x7135c260] [info]    [OpenAuto] [AutoConnector] setting up adapter, address: 00:1A:7D:DA:71:13
[2019-09-06 00:44:16.845045] [0x7135c260] [info]    [OpenAuto] [AutoConnector] start discovery.
[2019-09-06 00:44:16.847361] [0x7135c260] [info]    [OpenAuto] [AutoConnector] Found connectable device, address: D0:13:FD:73:D8:D5, trying to connect.
[2019-09-06 00:44:16.857379] [0x65ffa370] [info]    [OpenAuto] [AndroidAutoEntity] start.
[2019-09-06 00:44:16.872116] [0x65ffa370] [info]    [OpenAuto] [VideoService] start.
[2019-09-06 00:44:17.055452] [0x7135c260] [info]    [OpenAuto] [BluetoothInitializer] Obex service start result: 1, error: 0
[2019-09-06 00:44:17.055943] [0x7135c260] [debug]  [OpenAuto] [AudioInput] create.
[2019-09-06 00:44:17.105030] [0x65ffa370] [info]    [OpenAuto] [AudioInputService] start.
[2019-09-06 00:44:17.105479] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] start, channel: MEDIA_AUDIO
[2019-09-06 00:44:17.105713] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] start, channel: SPEECH_AUDIO
[2019-09-06 00:44:17.105851] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] start, channel: SYSTEM_AUDIO
[2019-09-06 00:44:17.109243] [0x65ffa370] [info]    [OpenAuto] [InputService] start.
[2019-09-06 00:44:17.130684] [0x7135c260] [debug]  [OpenAuto] [LocalBluetoothDevice] create.
[2019-09-06 00:44:17.187332] [0x65ffa370] [info]    [OpenAuto] [BluetoothService] start.
[2019-09-06 00:44:17.194902] [0x65ffa370] [info]    [OpenAuto] [SensorService] start.
[2019-09-06 00:44:17.203738] [0x64ff8370] [info]    [OpenAuto] [AndroidAutoEntity] version response, version: 1.5, status: 0
[2019-09-06 00:44:17.203890] [0x64ff8370] [info]    [OpenAuto] [AndroidAutoEntity] Begin handshake.
[2019-09-06 00:44:17.217269] [0x647f7370] [info]    [OpenAuto] [AndroidAutoEntity] Handshake, size: 2282
[2019-09-06 00:44:17.235511] [0x7135c260] [info]    [OpenAuto] [Phonebook] obex manager operational changed, value: true
[2019-09-06 00:44:17.267104] [0x7135c260] [info]    [OpenAuto] [AutoConnector] discovery already started.
[2019-09-06 00:44:17.267395] [0x7135c260] [info]    [OpenAuto] [TelephonyController] Activating.
[2019-09-06 00:44:17.271722] [0x7135c260] [info]    [OpenAuto] [OpenAuto] Initializing interprocess routines.
[2019-09-06 00:44:17.273357] [0x7135c260] [info]    [OpenAuto] [OpenAuto] Initialized interprocess routines.
[2019-09-06 00:44:17.275119] [0x647f7370] [info]    [OpenAuto] [AndroidAutoEntity] continue handshake.
[2019-09-06 00:44:17.283624] [0x7135c260] [info]    [OpenAuto] [TelephonyController] Modem error: Operation currently not available, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5
[2019-09-06 00:44:17.297369] [0x65ffa370] [info]    [OpenAuto] [AndroidAutoEntity] Handshake, size: 51
[2019-09-06 00:44:17.297969] [0x65ffa370] [info]    [OpenAuto] [AndroidAutoEntity] Auth completed.
[2019-09-06 00:44:17.311454] [0x65ffa370] [info]    [OpenAuto] [AndroidAutoEntity] Discovery request, device name: Android, brand: LGE LG-H910
[2019-09-06 00:44:17.311634] [0x65ffa370] [info]    [OpenAuto] [AudioInputService] fill features.
[2019-09-06 00:44:17.311784] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] fill features, channel: MEDIA_AUDIO
[2019-09-06 00:44:17.311838] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] fill features, channel: SPEECH_AUDIO
[2019-09-06 00:44:17.311872] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] fill features, channel: SYSTEM_AUDIO
[2019-09-06 00:44:17.311909] [0x65ffa370] [info]    [OpenAuto] [InputService] fill features.
[2019-09-06 00:44:17.311974] [0x65ffa370] [info]    [OpenAuto] [BluetoothService] fill features
[2019-09-06 00:44:17.313721] [0x65ffa370] [info]    [OpenAuto] [BluetoothService] sending local adapter adress: 00:1A:7D:DA:71:13
[2019-09-06 00:44:17.315286] [0x65ffa370] [info]    [OpenAuto] [SensorService] fill features.
[2019-09-06 00:44:17.315868] [0x65ffa370] [info]    [OpenAuto] [VideoService] fill features.
[2019-09-06 00:44:17.335488] [0x7135c260] [info]    [OpenAuto] [TelephonyController] New bluetooth device connected. Looking for modem.
[2019-09-06 00:44:17.338905] [0x7135c260] [info]    [OpenAuto] [BT::Network] Setting up device, address: D0:13:FD:73:D8:D5, ubi: /org/bluez/hci0/dev_D0_13_FD_73_D8_D5
[2019-09-06 00:44:17.342003] [0x7135c260] [info]    [OpenAuto] [TelephonyController] Modem error: Operation failed, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5
[2019-09-06 00:44:17.342750] [0x7135c260] [info]    [OpenAuto] [TelephonyController] Modem error: Operation currently not available, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5
[2019-09-06 00:44:17.342856] [0x7135c260] [info]    [OpenAuto] [TelephonyController] Modem error: Operation failed, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5
[2019-09-06 00:44:17.410489] [0x7135c260] [info]    [OpenAuto] [BT::Network] connected changed, status: true, address: D0:13:FD:73:D8:D5
[2019-09-06 00:44:17.515663] [0x647f7370] [info]    [OpenAuto] [AndroidAutoEntity] requested audio focus, type: 4
[2019-09-06 00:44:17.516061] [0x647f7370] [info]    [OpenAuto] [AndroidAutoEntity] audio focus state: 3
[2019-09-06 00:44:17.526161] [0x7135c260] [info]    [OpenAuto] [BT::Network] connected changed, status: false, address: D0:13:FD:73:D8:D5
[2019-09-06 00:44:17.591919] [0x647f7370] [info]    [OpenAuto] [AudioInputService] open request, priority: 0
[2019-09-06 00:44:17.592059] [0x647f7370] [info]    [OpenAuto] [AudioInputService] open status: 0
[2019-09-06 00:44:17.594562] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] open request, channel: MEDIA_AUDIO, priority: 0
[2019-09-06 00:44:17.594694] [0x647f7370] [debug]  [OpenAuto] [AudioOutputService] channel: MEDIA_AUDIO audio output sample rate: 48000, sample size: 16, channel count: 2
[2019-09-06 00:44:17.595473] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] open request, channel: SPEECH_AUDIO, priority: 0
[2019-09-06 00:44:17.595619] [0x65ffa370] [debug]  [OpenAuto] [AudioOutputService] channel: SPEECH_AUDIO audio output sample rate: 16000, sample size: 16, channel count: 1
[2019-09-06 00:44:17.596936] [0x657f9370] [info]    [OpenAuto] [AudioOutputService] open request, channel: SYSTEM_AUDIO, priority: 0
[2019-09-06 00:44:17.597084] [0x657f9370] [debug]  [OpenAuto] [AudioOutputService] channel: SYSTEM_AUDIO audio output sample rate: 16000, sample size: 16, channel count: 1
[2019-09-06 00:44:17.597514] [0x64ff8370] [info]    [OpenAuto] [InputService] open request, priority: 0
[2019-09-06 00:44:17.597611] [0x64ff8370] [info]    [OpenAuto] [InputService] open status: 0
[2019-09-06 00:44:17.598225] [0x64ff8370] [info]    [OpenAuto] [BluetoothService] open request, priority: 0
[2019-09-06 00:44:17.598355] [0x64ff8370] [info]    [OpenAuto] [BluetoothService] open status: 0
[2019-09-06 00:44:17.600100] [0x64ff8370] [info]    [OpenAuto] [SensorService] open request, priority: 0
[2019-09-06 00:44:17.600288] [0x64ff8370] [info]    [OpenAuto] [SensorService] open status: 0
[2019-09-06 00:44:17.603214] [0x64ff8370] [info]    [OpenAuto] [VideoService] open request, priority: 0
[2019-09-06 00:44:17.603328] [0x64ff8370] [info]    [OpenAuto] [OMXVideoOutput] open.
[2019-09-06 00:44:17.603364] [0x64ff8370] [info]    [OpenAuto] [OMXVideoPlayer] open.
[2019-09-06 00:44:17.608614] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] open status: 0, channel: SPEECH_AUDIO
[2019-09-06 00:44:17.609642] [0x65ffa370] [info]    [OpenAuto] [InputService] binding request, scan codes count: 20
[2019-09-06 00:44:17.609809] [0x65ffa370] [info]    [OpenAuto] [InputService] binding request, status: 0
[2019-09-06 00:44:17.613006] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] open status: 0, channel: MEDIA_AUDIO
[2019-09-06 00:44:17.613340] [0x647f7370] [info]    [OpenAuto] [SensorService] sensor start request, type: 1
[2019-09-06 00:44:17.613482] [0x647f7370] [info]    [OpenAuto] [SensorService] starting sensor, type: 1
[2019-09-06 00:44:17.613536] [0x647f7370] [info]    [OpenAuto] [GPSSensor] Starting GPS sensor.
[2019-09-06 00:44:17.613756] [0x7135c260] [info]    [OpenAuto] [TelephonyController] Modem powered status: true, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5
[2019-09-06 00:44:17.613852] [0x647f7370] [info]    [OpenAuto] [GPSSensor] Started GPS sensor.
[2019-09-06 00:44:17.613896] [0x7135c260] [warning] [OpenAuto] [TelephonyController] modem serial is not ready. Waiting...
[2019-09-06 00:44:17.614148] [0x7135c260] [warning] [OpenAuto] [TelephonyController] modem serial changed: D0:13:FD:73:D8:D5
[2019-09-06 00:44:17.614307] [0x7135c260] [info]    [OpenAuto] [TelephonyController] connected to modem, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5, name: Meloncholier, serial: D0:13:FD:73:D8:D5
[2019-09-06 00:44:17.614885] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] setup request, channel: SPEECH_AUDIO, config index: 1
[2019-09-06 00:44:17.615011] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] setup status: 2, channel: SPEECH_AUDIO
[2019-09-06 00:44:17.616417] [0x657f9370] [info]    [OpenAuto] [AudioOutputService] open status: 0, channel: SYSTEM_AUDIO
[2019-09-06 00:44:17.619359] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] setup request, channel: MEDIA_AUDIO, config index: 1
[2019-09-06 00:44:17.619486] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] setup status: 2, channel: MEDIA_AUDIO
[2019-09-06 00:44:17.620981] [0x65ffa370] [info]    [OpenAuto] [SensorService] sensor start request, type: 13
[2019-09-06 00:44:17.626683] [0x657f9370] [info]    [OpenAuto] [SensorService] sensor start request, type: 10
[2019-09-06 00:44:17.633172] [0x64ff8370] [info]    [OpenAuto] [VideoService] open status: 0
[2019-09-06 00:44:17.644997] [0x657f9370] [info]    [OpenAuto] [VideoService] setup request, config index: 3
[2019-09-06 00:44:17.645110] [0x657f9370] [info]    [OpenAuto] [OMXVideoPlayer] init, state: true
[2019-09-06 00:44:17.645444] [0x657f9370] [info]    [OpenAuto] [OMXVideoPlayer] Selected display region width: 800, height: 452, offset x: 0, offset y: 28
[2019-09-06 00:44:17.645639] [0x657f9370] [info]    [OpenAuto] [OMXVideoPlayer] Native resolution width: 800, height: 480
[2019-09-06 00:44:17.645780] [0x657f9370] [info]    [OpenAuto] [VideoService] setup status: 2
[2019-09-06 00:44:17.646583] [0x65ffa370] [info]    [OpenAuto] [AndroidAutoEntity] video service is ready.
[2019-09-06 00:44:17.650388] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] setup request, channel: SYSTEM_AUDIO, config index: 1
[2019-09-06 00:44:17.650577] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] setup status: 2, channel: SYSTEM_AUDIO
[2019-09-06 00:44:17.673453] [0x7135c260] [info]    [OpenAuto] [AutoConnector] stop discovery.
[2019-09-06 00:44:17.684565] [0x7135c260] [info]    [OpenAuto] [Phonebook] resync contacts, serial: D0:13:FD:73:D8:D5
[2019-09-06 00:44:17.684779] [0x7135c260] [info]    [OpenAuto] [Phonebook] starting phonebook transfer.
[2019-09-06 00:44:17.686757] [0x7135c260] [info]    [OpenAuto] [VoiceCallManager] modem connected, serial : D0:13:FD:73:D8:D5, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5
[2019-09-06 00:44:17.690395] [0x7135c260] [warning] [OpenAuto] [FavoriteContactsList] failed to read configuration file: .openauto/cache/hfp/favorites_3127352586.ini, error: .openauto/cache/hfp/favorites_3127352586.ini: cannot open file. Empty list will be used.
[2019-09-06 00:44:18.222798] [0x7135c260] [info]    [OpenAuto] [AndroidAutoManager] projection ready, focus: true
[2019-09-06 00:44:18.224487] [0x64ff8370] [info]    [OpenAuto] [AndroidAutoEntity] resume.
[2019-09-06 00:44:18.224641] [0x64ff8370] [info]    [OpenAuto] [VideoService] resume.
[2019-09-06 00:44:18.224719] [0x64ff8370] [info]    [OpenAuto] [VideoService] send video focus indication, mode: 1
[2019-09-06 00:44:18.226768] [0x657f9370] [info]    [OpenAuto] [VideoService] start indication, session: 0
[2019-09-06 00:44:18.226970] [0x657f9370] [info]    [OpenAuto] [OMXVideoOutput] start.
[2019-09-06 00:44:18.227077] [0x657f9370] [info]    [OpenAuto] [OMXVideoPlayer] start.
[2019-09-06 00:44:18.227431] [0x657f9370] [info]    [OpenAuto] [AndroidAutoEntity] video projection activated.
[2019-09-06 00:44:18.227508] [0x657f9370] [info]    [OpenAuto] [InputService] activate.
[2019-09-06 00:44:18.227570] [0x657f9370] [info]    [OpenAuto] [InputDevice] start.
[2019-09-06 00:44:18.352994] [0x7135c260] [info]    [OpenAuto] [Phonebook] Obex session added, serial: D0:13:FD:73:D8:D5/org/bluez/obex/client/session0
[2019-09-06 00:44:18.353308] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3c4, start, serial: D0:13:FD:73:D8:D5, target: pb
[2019-09-06 00:44:18.353501] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3c4, target selection, session: /org/bluez/obex/client/session0
[2019-09-06 00:44:18.354478] [0x7135c260] [info]    [OpenAuto] [Phonebook] obex session creation completed, serial: D0:13:FD:73:D8:D5, result: 0
[2019-09-06 00:44:18.356015] [0x7135c260] [info]    [OpenAuto] [A2DPController] Device added, looking for media player, address: D0:13:FD:73:D8:D5
[2019-09-06 00:44:18.356185] [0x7135c260] [info]    [OpenAuto] [A2DPController] Setting up A2DP device: 0xa6dd60
[2019-09-06 00:44:18.356265] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:18.356448] [0x7135c260] [warning] [OpenAuto] [A2DPController] Received empty track info.
[2019-09-06 00:44:18.356549] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 5
[2019-09-06 00:44:18.390157] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:18.390320] [0x7135c260] [warning] [OpenAuto] [A2DPController] Received empty track info.
[2019-09-06 00:44:18.390364] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 5
[2019-09-06 00:44:18.391025] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:18.391155] [0x7135c260] [warning] [OpenAuto] [A2DPController] Received empty track info.
[2019-09-06 00:44:18.391200] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 5
[2019-09-06 00:44:18.391968] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 0
[2019-09-06 00:44:18.392574] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:18.392776] [0x7135c260] [warning] [OpenAuto] [A2DPController] Received empty track info.
[2019-09-06 00:44:18.392824] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 0
[2019-09-06 00:44:18.393344] [0x7135c260] [debug]  [OpenAuto] [OMXVideoOutput] creating widget.
[2019-09-06 00:44:18.553286] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 2
[2019-09-06 00:44:18.553983] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:18.554121] [0x7135c260] [warning] [OpenAuto] [A2DPController] Received empty track info.
[2019-09-06 00:44:18.554160] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 2
[2019-09-06 00:44:18.554727] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:18.554815] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 2
[2019-09-06 00:44:18.555179] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:18.555315] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 2
[2019-09-06 00:44:18.555619] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:18.555682] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 2
[2019-09-06 00:44:18.555913] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:18.555969] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 2
[2019-09-06 00:44:18.576218] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3c4, target selection completed, serial: D0:13:FD:73:D8:D5, target: pb, error: 0
[2019-09-06 00:44:18.576391] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3c4, pull
[2019-09-06 00:44:18.675594] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3c4, target pull completed, serial: D0:13:FD:73:D8:D5, target: pb, error: 0
[2019-09-06 00:44:18.675926] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3c4, creating obex transfer, path: /org/bluez/obex/client/session0/transfer0
[2019-09-06 00:44:20.143952] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3c4, obex transfer status changed, serial: D0:13:FD:73:D8:D5, target: pb, status: 1
[2019-09-06 00:44:21.640580] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3c4, obex transfer status changed, serial: D0:13:FD:73:D8:D5, target: pb, status: 3
[2019-09-06 00:44:21.642549] [0x7135c260] [info]    [OpenAuto] [Phonebook] contacts transfer finished, filename: /home/pi/.openauto/cache/hfp/pb3127352586.vcf
[2019-09-06 00:44:21.643084] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3fc, start, serial: D0:13:FD:73:D8:D5, target: cch
[2019-09-06 00:44:21.643249] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3fc, target selection, session: /org/bluez/obex/client/session0
[2019-09-06 00:44:21.703852] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3fc, target selection completed, serial: D0:13:FD:73:D8:D5, target: cch, error: 0
[2019-09-06 00:44:21.704106] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3fc, pull
[2019-09-06 00:44:21.708233] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3fc, target pull completed, serial: D0:13:FD:73:D8:D5, target: cch, error: 0
[2019-09-06 00:44:21.708411] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3fc, creating obex transfer, path: /org/bluez/obex/client/session0/transfer1
[2019-09-06 00:44:21.743684] [0x7135c260] [info]    [OpenAuto] [Phonebook] Phonebook parse completed, handle: 0x688187c0
[2019-09-06 00:44:21.743874] [0x7135c260] [info]    [OpenAuto] [UI::VoiceCallController] setting new contacts, handle: 0x6e40b948
[2019-09-06 00:44:21.744081] [0x7135c260] [info]    [OpenAuto] [UI::TelephonyController] setting new phonebook, handle: 0x6e40b948
[2019-09-06 00:44:22.379362] [0x7135c260] [info]    [OpenAuto] [PhonebookTransfer] 0x0x7e86f3fc, obex transfer status changed, serial: D0:13:FD:73:D8:D5, target: cch, status: 3
[2019-09-06 00:44:22.380780] [0x7135c260] [info]    [OpenAuto] [Phonebook] calls history transfer finished, filename: /home/pi/.openauto/cache/hfp/cch3127352586.vcf
[2019-09-06 00:44:22.394033] [0x7135c260] [info]    [OpenAuto] [Phonebook] Call history parse completed, handle: 0x68808048
[2019-09-06 00:44:22.394210] [0x7135c260] [info]    [OpenAuto] [UI::VoiceCallController] setting new call history, handle: 0xccd0d8
[2019-09-06 00:44:22.822002] [0x65ffa370] [info]    [OpenAuto] [AndroidAutoEntity] requested audio focus, type: 1
[2019-09-06 00:44:22.822196] [0x65ffa370] [info]    [OpenAuto] [AndroidAutoEntity] audio focus state: 1
[2019-09-06 00:44:23.232660] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: MEDIA_AUDIO, session: 0
[2019-09-06 00:44:23.828217] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 0
[2019-09-06 00:44:23.838189] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:23.838446] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 0
[2019-09-06 00:44:23.845830] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:44:23.846109] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 0
[2019-09-06 00:44:50.157461] [0x64ff8370] [error]  [AaSdk] [ControlServiceChannel] message not handled: 17
[2019-09-06 00:44:50.405674] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 0
[2019-09-06 00:44:50.735053] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: MEDIA_AUDIO, session: 0
[2019-09-06 00:44:51.021426] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 0
[2019-09-06 00:44:52.015145] [0x657f9370] [info]    [OpenAuto] [AudioInputService] input open request, open: true, anc: false, ec: false, max unacked: 2
[2019-09-06 00:44:52.080834] [0x657f9370] [info]    [OpenAuto] [AudioInputService] audio input open succeed.
[2019-09-06 00:44:56.416644] [0x657f9370] [info]    [OpenAuto] [AudioInputService] input open request, open: false, anc: false, ec: false, max unacked: 0
[2019-09-06 00:44:56.417346] [0x657f9370] [info]    [OpenAuto] [AudioInputService] audio input read rejected.
[2019-09-06 00:44:56.907686] [0x657f9370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 1
[2019-09-06 00:44:57.672311] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 1
[2019-09-06 00:44:59.015488] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 2
[2019-09-06 00:45:02.149324] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 2
[2019-09-06 00:45:02.413968] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 3
[2019-09-06 00:45:07.134198] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 3
[2019-09-06 00:45:07.388877] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 4
[2019-09-06 00:45:10.082806] [0x65ffa370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 4
[2019-09-06 00:45:10.202579] [0x647f7370] [error]  [AaSdk] [ControlServiceChannel] message not handled: 17
[2019-09-06 00:45:10.362508] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: MEDIA_AUDIO, session: 1
[2019-09-06 00:45:21.592166] [0x65ffa370] [error]  [AaSdk] [ControlServiceChannel] message not handled: 17
[2019-09-06 00:45:22.059987] [0x657f9370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 5
[2019-09-06 00:45:22.352078] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: MEDIA_AUDIO, session: 1
[2019-09-06 00:45:22.693252] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 5
[2019-09-06 00:45:23.772077] [0x647f7370] [info]    [OpenAuto] [AudioInputService] input open request, open: true, anc: false, ec: false, max unacked: 2
[2019-09-06 00:45:23.830301] [0x64ff8370] [info]    [OpenAuto] [AudioInputService] audio input open succeed.
[2019-09-06 00:45:29.069166] [0x64ff8370] [info]    [OpenAuto] [AudioInputService] input open request, open: false, anc: false, ec: false, max unacked: 0
[2019-09-06 00:45:29.069736] [0x657f9370] [info]    [OpenAuto] [AudioInputService] audio input read rejected.
[2019-09-06 00:45:29.792145] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 6
[2019-09-06 00:45:30.564419] [0x657f9370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 6
[2019-09-06 00:45:32.032197] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 7
[2019-09-06 00:45:33.694636] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 7
[2019-09-06 00:45:34.223385] [0x657f9370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 8
[2019-09-06 00:45:40.428064] [0x657f9370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 8
[2019-09-06 00:45:40.809759] [0x657f9370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 9
[2019-09-06 00:45:47.204722] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 9
[2019-09-06 00:45:47.587462] [0x647f7370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 10
[2019-09-06 00:46:01.036656] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] stop indication, channel: SPEECH_AUDIO, session: 10
[2019-09-06 00:46:01.487170] [0x657f9370] [info]    [OpenAuto] [AudioOutputService] start indication, channel: SPEECH_AUDIO, session: 11
[2019-09-06 00:46:03.765951] [0x647f7370] [error]  [OpenAuto] [AudioOutputService] channel error: AaSdk error code: 10, native code: 1, channel: SPEECH_AUDIO
[2019-09-06 00:46:03.770314] [0x64ff8370] [error]  [OpenAuto] [VideoService] channel error: AaSdk error code: 10, native code: 1
[2019-09-06 00:46:03.770380] [0x657f9370] [error]  [OpenAuto] [AudioOutputService] channel error: AaSdk error code: 10, native code: 1, channel: MEDIA_AUDIO
[2019-09-06 00:46:03.770426] [0x64ff8370] [error]  [OpenAuto] [AudioOutputService] channel error: AaSdk error code: 10, native code: 1, channel: SYSTEM_AUDIO
[2019-09-06 00:46:03.770471] [0x64ff8370] [error]  [OpenAuto] [SensorService] channel error: AaSdk error code: 10, native code: 1
[2019-09-06 00:46:03.770504] [0x657f9370] [error]  [OpenAuto] [SensorService] channel error: AaSdk error code: 10, native code: 1
[2019-09-06 00:46:03.770582] [0x65ffa370] [error]  [OpenAuto] [AudioInputService] channel error: AaSdk error code: 10, native code: 1
[2019-09-06 00:46:03.770612] [0x647f7370] [error]  [OpenAuto] [AndroidAutoEntity] channel error: AaSdk error code: 10, native code: 1
[2019-09-06 00:46:03.770651] [0x64ff8370] [error]  [OpenAuto] [BluetoothService] channel error: AaSdk error code: 10, native code: 1
[2019-09-06 00:46:03.771595] [0x64ff8370] [info]    [OpenAuto] [AndroidAutoEntity] stop.
[2019-09-06 00:46:03.771691] [0x647f7370] [info]    [OpenAuto] [AndroidAutoApp] Enable autostart.
[2019-09-06 00:46:03.771752] [0x64ff8370] [info]    [OpenAuto] [SensorService] stop.
[2019-09-06 00:46:03.771792] [0x64ff8370] [info]    [OpenAuto] [GPSSensor] Stopping GPS sensor.
[2019-09-06 00:46:03.771833] [0x64ff8370] [info]    [OpenAuto] [BluetoothService] stop.
[2019-09-06 00:46:03.771868] [0x64ff8370] [info]    [OpenAuto] [InputService] stop.
[2019-09-06 00:46:03.771943] [0x64ff8370] [info]    [OpenAuto] [InputDevice] stop.
[2019-09-06 00:46:03.772133] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] stop, channel: SYSTEM_AUDIO
[2019-09-06 00:46:03.775479] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] stop, channel: SPEECH_AUDIO
[2019-09-06 00:46:04.015515] [0x64ff8370] [info]    [OpenAuto] [AudioOutputService] stop, channel: MEDIA_AUDIO
[2019-09-06 00:46:04.017255] [0x64ff8370] [info]    [OpenAuto] [AudioInputService] stop.
[2019-09-06 00:46:04.017475] [0x64ff8370] [info]    [OpenAuto] [VideoService] stop.
[2019-09-06 00:46:04.017519] [0x64ff8370] [info]    [OpenAuto] [OMXVideoOutput] close.
[2019-09-06 00:46:04.017571] [0x64ff8370] [info]    [OpenAuto] [OMXVideoPlayer] close.
[2019-09-06 00:46:04.035631] [0x64ff8370] [debug]  [OpenAuto] [AndroidAutoEntity] destroy.
[2019-09-06 00:46:58.765910] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 2
[2019-09-06 00:46:58.776480] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:46:58.777032] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 2
[2019-09-06 00:46:58.835772] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0x68807b40
[2019-09-06 00:46:58.835994] [0x7135c260] [info]    [OpenAuto] [A2DPController] Player status changed, status: 2
[2019-09-06 00:49:10.949618] [0x7135c260] [info]    [OpenAuto] [TelephonyController] Modem powered status: false, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5
[2019-09-06 00:49:10.949846] [0x7135c260] [info]    [OpenAuto] [TelephonyController] modem disconnected, path: /hfp/org/bluez/hci0/dev_D0_13_FD_73_D8_D5, name: Meloncholier, serial: D0:13:FD:73:D8:D5
[2019-09-06 00:49:10.962777] [0x7135c260] [info]    [OpenAuto] [AutoConnector] start discovery.
[2019-09-06 00:49:10.977219] [0x7135c260] [info]    [OpenAuto] [Phonebook] modem disconnected, serial: D0:13:FD:73:D8:D5
[2019-09-06 00:49:10.977416] [0x7135c260] [info]    [OpenAuto] [VoiceCallManager] modem disconnected.
[2019-09-06 00:49:10.979786] [0x7135c260] [info]    [OpenAuto] [VoiceCallManager] modem disconnected.
[2019-09-06 00:49:10.984766] [0x7135c260] [info]    [OpenAuto] [TelephonyController] New bluetooth device connected. Looking for modem.
[2019-09-06 00:49:10.986800] [0x7135c260] [info]    [OpenAuto] [A2DPController] Updating media player: 0
[2019-09-06 00:49:10.994886] [0x7135c260] [info]    [OpenAuto] [BT::Network] current device disconnected, address: D0:13:FD:73:D8:D5
[2019-09-06 00:49:10.996427] [0x7135c260] [info]    [OpenAuto] [A2DPController] Disconnected from current device, address: D0:13:FD:73:D8:D5
#2
Any ideas or extra logging I can enable to try to troubleshoot this?
#3
Hello,
First of all please provide more details about your setup:
-Touchscreen
-Power supply
-Sound card
-Microphone

According to the logs, it looks like your phone is breaking the connection with Raspberry PI. That looks like an issue with Android Auto app on the phone. Please try reinstall Android Auto application and if it will not help then try reset your phone to factory settings.
#4
Display is OEM panel, 800x480 via a gvif box
Power supply is a BC1.2 usb hub, i don't get lightning bolts on screen
using a 3W 5V step-down converter gives me the same issue
soundcard is the Ugreen 2RCA adapter
Microphone is OEM mic wired into the ugreen mic input - ok google works till AA crashes

I'll be reflashing the system soon and will also remove/reinstall android auto on my phone. I also have a 2nd device I can test with (galaxy S9+) if the issue persists to rule out the phone.
#5
I'm having this issue also. It's only been happening since 3.3.1 and update to android 9.

Raspberry Pi 3b+
Raspberry Pi touchscreen
OnePlus 3
12 to 5V drop down power supply

I am going to update to 3.3.2 and see if that helps, maybe redownload android auto

Android auto seems to crash, and I have unplug and plug the phone in to restart it. Could it be a power issue? Cable issue? Connection issue between cable and phone? Type of cable used?
#6
(10-15-2019, 10:43 PM)paddyjaki Wrote: I'm having this issue also. It's only been happening since 3.3.1 and update to android 9.

Raspberry Pi 3b+
Raspberry Pi touchscreen
OnePlus 3
12 to 5V drop down power supply

I am going to update to 3.3.2 and see if that helps, maybe redownload android auto

Android auto seems to crash, and I have unplug and plug the phone in to restart it. Could it be a power issue? Cable issue? Connection issue between cable and phone? Type of cable used?

If the Android Auto app is crashing itself then problem is on your phone. Please try reset it to factory settings.


Forum Jump:


Users browsing this thread: 1 Guest(s)