Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Connection error: USB read: deadline has elapsed #5

Open
JoystickX02 opened this issue Jan 19, 2025 · 1 comment
Open

Connection error: USB read: deadline has elapsed #5

JoystickX02 opened this issue Jan 19, 2025 · 1 comment

Comments

@JoystickX02
Copy link

JoystickX02 commented Jan 19, 2025

Hi,
i did everything correctly like its described here. Somehow im always getting a black screen when my Phone starts Android Auto and connects to NaviP.i After a few seconds im getting "Android Auto is not available" message from OpenAuto Pro.

Here is also my aa-proxy-oap log, im somehow getting everytime the same error message when i get the Black screen on OpenAuto Pro "Connection error: USB read: deadline has elapsed" or sometimes "Connection error: TCP read: deadline has elapsed":

2025-01-19, 16:40:05.358 [INFO] 🛸 aa-proxy-rs is starting, build: 20241228_022643, git: 20241224-38a633c-dirty
2025-01-19, 16:40:05.359 [INFO] main: 📜 Log file path: /var/log/aa-proxy-rs.log
2025-01-19, 16:40:05.359 [INFO] main: ⚙️ Showing transfer statistics: disabled
2025-01-19, 16:40:05.436 [INFO] proxy: 🛰️ Starting TCP server...
2025-01-19, 16:40:05.437 [INFO] proxy: 🛰️ TCP server bound to: 0.0.0.0:5288
2025-01-19, 16:40:05.437 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:40:05.520 [INFO] usb: Using UDC: "dummy_udc.0"
2025-01-19, 16:40:05.520 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:40:05.521 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:40:05.521 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:40:06.295 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:40:06.547 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:40:06.586 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:40:06.587 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:40:18.778 [INFO] bluetooth: 📱 AA Wireless Profile: connect from: XX:XX:XX:XX:XX:XX
2025-01-19, 16:40:18.779 [INFO] bluetooth: 📲 Sending parameters via bluetooth to phone...
2025-01-19, 16:40:18.779 [INFO] bluetooth: 🛜 Sending Host IP Address: 10.0.0.1
2025-01-19, 16:40:18.779 [INFO] bluetooth: 📨 stage #1 of 5: Sending WifiStartRequest frame to phone...
2025-01-19, 16:40:18.824 [INFO] bluetooth: 📨 stage #2 of 5: Received WifiInfoRequest frame from phone (⏱️ 44 ms)
2025-01-19, 16:40:18.824 [INFO] bluetooth: 🛜 Sending Host SSID and Password: NaviPi, AANaviPi
2025-01-19, 16:40:18.824 [INFO] bluetooth: 📨 stage #3 of 5: Sending WifiInfoResponse frame to phone...
2025-01-19, 16:40:18.834 [INFO] bluetooth: 📨 stage #4 of 5: Received WifiStartResponse frame from phone (⏱️ 9 ms)
2025-01-19, 16:40:31.627 [ERROR] main: Bluetooth error: Software caused connection abort (os error 103)
2025-01-19, 16:40:31.628 [INFO] main: Trying to recover...
2025-01-19, 16:40:32.629 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:40:32.642 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:40:32.672 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:40:32.676 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:40:32.677 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:44:26.795 [INFO] bluetooth: 📱 AA Wireless Profile: connect from: XX:XX:XX:XX:XX:XX
2025-01-19, 16:44:26.795 [INFO] bluetooth: 📲 Sending parameters via bluetooth to phone...
2025-01-19, 16:44:26.795 [INFO] bluetooth: 🛜 Sending Host IP Address: 10.0.0.1
2025-01-19, 16:44:26.795 [INFO] bluetooth: 📨 stage #1 of 5: Sending WifiStartRequest frame to phone...
2025-01-19, 16:44:26.863 [INFO] bluetooth: 📨 stage #2 of 5: Received WifiInfoRequest frame from phone (⏱️ 67 ms)
2025-01-19, 16:44:26.863 [INFO] bluetooth: 🛜 Sending Host SSID and Password: NaviPi, AANaviPi
2025-01-19, 16:44:26.864 [INFO] bluetooth: 📨 stage #3 of 5: Sending WifiInfoResponse frame to phone...
2025-01-19, 16:44:26.875 [INFO] bluetooth: 📨 stage #4 of 5: Received WifiStartResponse frame from phone (⏱️ 10 ms)
2025-01-19, 16:44:40.677 [INFO] bluetooth: 📨 stage #5 of 5: Received WifiConnectStatus frame from phone (⏱️ 13802 ms)
2025-01-19, 16:44:40.677 [INFO] bluetooth: 🚀 Bluetooth launch sequence completed
2025-01-19, 16:44:40.677 [INFO] proxy: 🛰️ TCP server: listening for phone connection...
2025-01-19, 16:44:40.678 [INFO] proxy: 📳 TCP server: new client connected: 10.0.0.2:41522
2025-01-19, 16:44:40.678 [INFO] proxy: 📂 Opening USB accessory device: /dev/usb_accessory
2025-01-19, 16:44:40.678 [INFO] proxy: ♾️ Starting to proxy data between TCP and USB...
2025-01-19, 16:44:41.179 [INFO] usb: 🔌 USB Manager: Switched to accessory gadget
2025-01-19, 16:44:41.179 [INFO] bluetooth: 🥷 Unregistering default agent
2025-01-19, 16:44:41.180 [INFO] bluetooth: 📱 Removing AA profile
2025-01-19, 16:44:43.682 [WARN] bluetooth: 🎧 Error waiting for HSP profile task: deadline has elapsed
2025-01-19, 16:44:43.682 [INFO] bluetooth: 💤 Bluetooth adapter stays on
2025-01-19, 16:44:51.985 [ERROR] proxy: 🔴 Connection error: TCP read: deadline has elapsed
2025-01-19, 16:44:51.985 [INFO] proxy: ⌛ session time: 11s 307ms 35us 321ns
2025-01-19, 16:44:51.986 [INFO] main: 📵 TCP/USB connection closed or not started, trying again...
2025-01-19, 16:44:51.986 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:44:52.987 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:44:53.439 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:44:53.439 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:44:53.452 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:44:53.502 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:44:53.504 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:44:53.504 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:45:15.436 [INFO] 🛸 aa-proxy-rs is starting, build: 20241228_022643, git: 20241224-38a633c-dirty
2025-01-19, 16:45:15.454 [INFO] main: 📜 Log file path: /var/log/aa-proxy-rs.log
2025-01-19, 16:45:15.454 [INFO] main: ⚙️ Showing transfer statistics: disabled
2025-01-19, 16:45:15.517 [INFO] proxy: 🛰️ Starting TCP server...
2025-01-19, 16:45:15.517 [INFO] proxy: 🛰️ TCP server bound to: 0.0.0.0:5288
2025-01-19, 16:45:15.517 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:45:15.599 [INFO] usb: Using UDC: "dummy_udc.0"
2025-01-19, 16:45:15.600 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:45:15.600 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:45:15.601 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:45:16.046 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:45:16.167 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:45:16.173 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:45:16.173 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:45:29.490 [INFO] bluetooth: 📱 AA Wireless Profile: connect from: XX:XX:XX:XX:XX:XX
2025-01-19, 16:45:29.490 [INFO] bluetooth: 📲 Sending parameters via bluetooth to phone...
2025-01-19, 16:45:29.490 [INFO] bluetooth: 🛜 Sending Host IP Address: 10.0.0.1
2025-01-19, 16:45:29.491 [INFO] bluetooth: 📨 stage #1 of 5: Sending WifiStartRequest frame to phone...
2025-01-19, 16:45:29.616 [INFO] bluetooth: 📨 stage #2 of 5: Received WifiInfoRequest frame from phone (⏱️ 124 ms)
2025-01-19, 16:45:29.616 [INFO] bluetooth: 🛜 Sending Host SSID and Password: NaviPi, AANaviPi
2025-01-19, 16:45:29.616 [INFO] bluetooth: 📨 stage #3 of 5: Sending WifiInfoResponse frame to phone...
2025-01-19, 16:45:29.651 [INFO] bluetooth: 📨 stage #4 of 5: Received WifiStartResponse frame from phone (⏱️ 34 ms)
2025-01-19, 16:45:40.557 [INFO] bluetooth: 📨 stage #5 of 5: Received WifiConnectStatus frame from phone (⏱️ 10906 ms)
2025-01-19, 16:45:40.558 [INFO] proxy: 🛰️ TCP server: listening for phone connection...
2025-01-19, 16:45:40.558 [INFO] proxy: 📳 TCP server: new client connected: 10.0.0.2:57070
2025-01-19, 16:45:40.559 [INFO] proxy: 📂 Opening USB accessory device: /dev/usb_accessory
2025-01-19, 16:45:40.559 [INFO] proxy: ♾️ Starting to proxy data between TCP and USB...
2025-01-19, 16:45:40.560 [INFO] bluetooth: 🚀 Bluetooth launch sequence completed
2025-01-19, 16:45:41.035 [INFO] usb: 🔌 USB Manager: Switched to accessory gadget
2025-01-19, 16:45:41.036 [INFO] bluetooth: 🥷 Unregistering default agent
2025-01-19, 16:45:41.036 [INFO] bluetooth: 📱 Removing AA profile
2025-01-19, 16:45:43.538 [WARN] bluetooth: 🎧 Error waiting for HSP profile task: deadline has elapsed
2025-01-19, 16:45:43.538 [INFO] bluetooth: 💤 Bluetooth adapter stays on
2025-01-19, 16:46:05.893 [ERROR] proxy: 🔴 Connection error: USB read: deadline has elapsed
2025-01-19, 16:46:05.893 [INFO] proxy: ⌛ session time: 11s 305ms 958us 660ns
2025-01-19, 16:46:05.894 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:46:05.894 [INFO] main: 📵 TCP/USB connection closed or not started, trying again...
2025-01-19, 16:46:06.895 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:46:07.314 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:46:07.314 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:46:07.324 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:46:07.344 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:46:07.347 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:46:07.347 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:46:19.503 [INFO] bluetooth: 📱 AA Wireless Profile: connect from: XX:XX:XX:XX:XX:XX
2025-01-19, 16:46:19.504 [INFO] bluetooth: 📲 Sending parameters via bluetooth to phone...
2025-01-19, 16:46:19.504 [INFO] bluetooth: 🛜 Sending Host IP Address: 10.0.0.1
2025-01-19, 16:46:19.504 [INFO] bluetooth: 📨 stage #1 of 5: Sending WifiStartRequest frame to phone...
2025-01-19, 16:46:19.523 [INFO] bluetooth: 📨 stage #2 of 5: Received WifiInfoRequest frame from phone (⏱️ 18 ms)
2025-01-19, 16:46:19.523 [INFO] bluetooth: 🛜 Sending Host SSID and Password: NaviPi, AANaviPi
2025-01-19, 16:46:19.524 [INFO] bluetooth: 📨 stage #3 of 5: Sending WifiInfoResponse frame to phone...
2025-01-19, 16:46:19.534 [INFO] bluetooth: 📨 stage #4 of 5: Received WifiStartResponse frame from phone (⏱️ 10 ms)
2025-01-19, 16:46:32.677 [INFO] bluetooth: 📨 stage #5 of 5: Received WifiConnectStatus frame from phone (⏱️ 13142 ms)
2025-01-19, 16:46:32.677 [INFO] bluetooth: 🚀 Bluetooth launch sequence completed
2025-01-19, 16:46:32.678 [INFO] proxy: 🛰️ TCP server: listening for phone connection...
2025-01-19, 16:46:32.726 [INFO] proxy: 📳 TCP server: new client connected: 10.0.0.2:55852
2025-01-19, 16:46:32.726 [INFO] proxy: 📂 Opening USB accessory device: /dev/usb_accessory
2025-01-19, 16:46:32.726 [INFO] proxy: ♾️ Starting to proxy data between TCP and USB...
2025-01-19, 16:46:33.254 [INFO] usb: 🔌 USB Manager: Switched to accessory gadget
2025-01-19, 16:46:33.254 [INFO] bluetooth: 🥷 Unregistering default agent
2025-01-19, 16:46:33.254 [INFO] bluetooth: 📱 Removing AA profile
2025-01-19, 16:46:35.757 [WARN] bluetooth: 🎧 Error waiting for HSP profile task: deadline has elapsed
2025-01-19, 16:46:35.757 [INFO] bluetooth: 💤 Bluetooth adapter stays on
2025-01-19, 16:46:37.147 [ERROR] proxy: 🔴 Connection error: Connection reset by peer (os error 104)
2025-01-19, 16:46:37.148 [INFO] proxy: ⌛ session time: 4s 420ms 924us 221ns
2025-01-19, 16:46:37.148 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:46:37.148 [INFO] main: 📵 TCP/USB connection closed or not started, trying again...
2025-01-19, 16:46:38.149 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:46:38.653 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:46:38.653 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:46:38.664 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:46:38.684 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:46:38.686 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:46:38.686 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:47:42.648 [INFO] bluetooth: 📱 AA Wireless Profile: connect from: XX:XX:XX:XX:XX:XX
2025-01-19, 16:47:42.648 [INFO] bluetooth: 📲 Sending parameters via bluetooth to phone...
2025-01-19, 16:47:42.648 [INFO] bluetooth: 🛜 Sending Host IP Address: 10.0.0.1
2025-01-19, 16:47:42.648 [INFO] bluetooth: 📨 stage #1 of 5: Sending WifiStartRequest frame to phone...
2025-01-19, 16:47:42.756 [INFO] bluetooth: 📨 stage #2 of 5: Received WifiInfoRequest frame from phone (⏱️ 106 ms)
2025-01-19, 16:47:42.756 [INFO] bluetooth: 🛜 Sending Host SSID and Password: NaviPi, AANaviPi
2025-01-19, 16:47:42.756 [INFO] bluetooth: 📨 stage #3 of 5: Sending WifiInfoResponse frame to phone...
2025-01-19, 16:47:42.758 [INFO] bluetooth: 📨 stage #4 of 5: Received WifiStartResponse frame from phone (⏱️ 1 ms)
2025-01-19, 16:47:52.692 [INFO] bluetooth: 📨 stage #5 of 5: Received WifiConnectStatus frame from phone (⏱️ 9933 ms)
2025-01-19, 16:47:52.693 [INFO] proxy: 🛰️ TCP server: listening for phone connection...
2025-01-19, 16:47:52.693 [INFO] bluetooth: 🚀 Bluetooth launch sequence completed
2025-01-19, 16:47:53.030 [INFO] proxy: 📳 TCP server: new client connected: 10.0.0.2:50530
2025-01-19, 16:47:53.030 [INFO] proxy: 📂 Opening USB accessory device: /dev/usb_accessory
2025-01-19, 16:47:53.030 [INFO] proxy: ♾️ Starting to proxy data between TCP and USB...
2025-01-19, 16:47:53.273 [INFO] usb: 🔌 USB Manager: Switched to accessory gadget
2025-01-19, 16:47:53.273 [INFO] bluetooth: 🥷 Unregistering default agent
2025-01-19, 16:47:53.274 [INFO] bluetooth: 📱 Removing AA profile
2025-01-19, 16:47:55.776 [WARN] bluetooth: 🎧 Error waiting for HSP profile task: deadline has elapsed
2025-01-19, 16:47:55.776 [INFO] bluetooth: 💤 Bluetooth adapter stays on
2025-01-19, 16:48:04.091 [ERROR] proxy: 🔴 Connection error: TCP read: deadline has elapsed
2025-01-19, 16:48:04.092 [INFO] proxy: ⌛ session time: 11s 61ms 578us 745ns
2025-01-19, 16:48:04.092 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:48:04.092 [INFO] main: 📵 TCP/USB connection closed or not started, trying again...
2025-01-19, 16:48:05.094 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:48:05.572 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:48:05.573 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:48:05.579 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:48:05.600 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:48:05.601 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:48:05.601 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:48:17.622 [INFO] bluetooth: 📱 AA Wireless Profile: connect from: XX:XX:XX:XX:XX:XX
2025-01-19, 16:48:17.623 [INFO] bluetooth: 📲 Sending parameters via bluetooth to phone...
2025-01-19, 16:48:17.623 [INFO] bluetooth: 🛜 Sending Host IP Address: 10.0.0.1
2025-01-19, 16:48:17.623 [INFO] bluetooth: 📨 stage #1 of 5: Sending WifiStartRequest frame to phone...
2025-01-19, 16:48:17.649 [INFO] bluetooth: 📨 stage #2 of 5: Received WifiInfoRequest frame from phone (⏱️ 25 ms)
2025-01-19, 16:48:17.650 [INFO] bluetooth: 🛜 Sending Host SSID and Password: NaviPi, AANaviPi
2025-01-19, 16:48:17.650 [INFO] bluetooth: 📨 stage #3 of 5: Sending WifiInfoResponse frame to phone...
2025-01-19, 16:48:17.659 [INFO] bluetooth: 📨 stage #4 of 5: Received WifiStartResponse frame from phone (⏱️ 9 ms)
2025-01-19, 16:48:18.391 [INFO] bluetooth: 📨 stage #5 of 5: Received WifiConnectStatus frame from phone (⏱️ 731 ms)
2025-01-19, 16:48:18.391 [INFO] bluetooth: 🚀 Bluetooth launch sequence completed
2025-01-19, 16:48:18.392 [INFO] proxy: 🛰️ TCP server: listening for phone connection...
2025-01-19, 16:48:18.560 [INFO] proxy: 📳 TCP server: new client connected: 10.0.0.2:55888
2025-01-19, 16:48:18.560 [INFO] proxy: 📂 Opening USB accessory device: /dev/usb_accessory
2025-01-19, 16:48:18.561 [INFO] proxy: ♾️ Starting to proxy data between TCP and USB...
2025-01-19, 16:48:18.973 [INFO] usb: 🔌 USB Manager: Switched to accessory gadget
2025-01-19, 16:48:18.974 [INFO] bluetooth: 🥷 Unregistering default agent
2025-01-19, 16:48:18.974 [INFO] bluetooth: 📱 Removing AA profile
2025-01-19, 16:48:21.475 [WARN] bluetooth: 🎧 Error waiting for HSP profile task: deadline has elapsed
2025-01-19, 16:48:21.475 [INFO] bluetooth: 💤 Bluetooth adapter stays on
2025-01-19, 16:48:39.829 [ERROR] proxy: 🔴 Connection error: TCP read: deadline has elapsed
2025-01-19, 16:48:39.830 [INFO] proxy: ⌛ session time: 21s 268ms 774us 603ns
2025-01-19, 16:48:39.830 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:48:39.830 [INFO] main: 📵 TCP/USB connection closed or not started, trying again...
2025-01-19, 16:48:40.831 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:48:41.293 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:48:41.293 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:48:41.308 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:48:41.327 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:48:41.328 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:48:41.328 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:49:47.185 [INFO] 🛸 aa-proxy-rs is starting, build: 20241228_022643, git: 20241224-38a633c-dirty
2025-01-19, 16:49:47.223 [INFO] main: 📜 Log file path: /var/log/aa-proxy-rs.log
2025-01-19, 16:49:47.224 [INFO] main: ⚙️ Showing transfer statistics: disabled
2025-01-19, 16:49:47.286 [INFO] proxy: 🛰️ Starting TCP server...
2025-01-19, 16:49:47.287 [INFO] proxy: 🛰️ TCP server bound to: 0.0.0.0:5288
2025-01-19, 16:49:47.287 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:49:47.351 [INFO] usb: Using UDC: "dummy_udc.0"
2025-01-19, 16:49:47.352 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:49:47.352 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:49:47.353 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:49:48.532 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:49:48.659 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:49:48.668 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:49:48.669 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:51:07.554 [INFO] bluetooth: 📱 AA Wireless Profile: connect from: XX:XX:XX:XX:XX:XX
2025-01-19, 16:51:07.555 [INFO] bluetooth: 📲 Sending parameters via bluetooth to phone...
2025-01-19, 16:51:07.555 [INFO] bluetooth: 🛜 Sending Host IP Address: 10.0.0.1
2025-01-19, 16:51:07.555 [INFO] bluetooth: 📨 stage #1 of 5: Sending WifiStartRequest frame to phone...
2025-01-19, 16:51:07.754 [INFO] bluetooth: 📨 stage #2 of 5: Received WifiInfoRequest frame from phone (⏱️ 198 ms)
2025-01-19, 16:51:07.754 [INFO] bluetooth: 🛜 Sending Host SSID and Password: NaviPi, AANaviPi
2025-01-19, 16:51:07.755 [INFO] bluetooth: 📨 stage #3 of 5: Sending WifiInfoResponse frame to phone...
2025-01-19, 16:51:07.763 [INFO] bluetooth: 📨 stage #4 of 5: Received WifiStartResponse frame from phone (⏱️ 7 ms)
2025-01-19, 16:51:21.363 [INFO] bluetooth: 📨 stage #5 of 5: Received WifiConnectStatus frame from phone (⏱️ 13600 ms)
2025-01-19, 16:51:21.364 [INFO] bluetooth: 🚀 Bluetooth launch sequence completed
2025-01-19, 16:51:21.364 [INFO] proxy: 🛰️ TCP server: listening for phone connection...
2025-01-19, 16:51:21.663 [INFO] proxy: 📳 TCP server: new client connected: 10.0.0.2:40864
2025-01-19, 16:51:21.663 [INFO] proxy: 📂 Opening USB accessory device: /dev/usb_accessory
2025-01-19, 16:51:21.663 [INFO] proxy: ♾️ Starting to proxy data between TCP and USB...
2025-01-19, 16:51:21.930 [INFO] usb: 🔌 USB Manager: Switched to accessory gadget
2025-01-19, 16:51:21.931 [INFO] bluetooth: 🥷 Unregistering default agent
2025-01-19, 16:51:21.931 [INFO] bluetooth: 📱 Removing AA profile
2025-01-19, 16:51:24.433 [WARN] bluetooth: 🎧 Error waiting for HSP profile task: deadline has elapsed
2025-01-19, 16:51:24.433 [INFO] bluetooth: 💤 Bluetooth adapter stays on
2025-01-19, 16:51:42.790 [ERROR] proxy: 🔴 Connection error: USB read: deadline has elapsed
2025-01-19, 16:51:42.791 [INFO] proxy: ⌛ session time: 21s 127ms 124us 545ns
2025-01-19, 16:51:42.791 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:51:42.791 [INFO] main: 📵 TCP/USB connection closed or not started, trying again...
2025-01-19, 16:51:43.792 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:51:44.250 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:51:44.250 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:51:44.262 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:51:44.282 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:51:44.286 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:51:44.286 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...
2025-01-19, 16:51:46.347 [INFO] bluetooth: 📱 AA Wireless Profile: connect from: XX:XX:XX:XX:XX:XX
2025-01-19, 16:51:46.347 [INFO] bluetooth: 📲 Sending parameters via bluetooth to phone...
2025-01-19, 16:51:46.347 [INFO] bluetooth: 🛜 Sending Host IP Address: 10.0.0.1
2025-01-19, 16:51:46.348 [INFO] bluetooth: 📨 stage #1 of 5: Sending WifiStartRequest frame to phone...
2025-01-19, 16:51:46.456 [INFO] bluetooth: 📨 stage #2 of 5: Received WifiInfoRequest frame from phone (⏱️ 108 ms)
2025-01-19, 16:51:46.456 [INFO] bluetooth: 🛜 Sending Host SSID and Password: NaviPi, AANaviPi
2025-01-19, 16:51:46.457 [INFO] bluetooth: 📨 stage #3 of 5: Sending WifiInfoResponse frame to phone...
2025-01-19, 16:51:46.466 [INFO] bluetooth: 📨 stage #4 of 5: Received WifiStartResponse frame from phone (⏱️ 8 ms)
2025-01-19, 16:51:47.243 [INFO] bluetooth: 📨 stage #5 of 5: Received WifiConnectStatus frame from phone (⏱️ 776 ms)
2025-01-19, 16:51:47.243 [INFO] bluetooth: 🚀 Bluetooth launch sequence completed
2025-01-19, 16:51:47.243 [INFO] proxy: 🛰️ TCP server: listening for phone connection...
2025-01-19, 16:51:47.484 [INFO] proxy: 📳 TCP server: new client connected: 10.0.0.2:44286
2025-01-19, 16:51:47.484 [INFO] proxy: 📂 Opening USB accessory device: /dev/usb_accessory
2025-01-19, 16:51:47.484 [INFO] proxy: ♾️ Starting to proxy data between TCP and USB...
2025-01-19, 16:51:47.860 [INFO] usb: 🔌 USB Manager: Switched to accessory gadget
2025-01-19, 16:51:47.860 [INFO] bluetooth: 🥷 Unregistering default agent
2025-01-19, 16:51:47.860 [INFO] bluetooth: 📱 Removing AA profile
2025-01-19, 16:51:50.362 [WARN] bluetooth: 🎧 Error waiting for HSP profile task: deadline has elapsed
2025-01-19, 16:51:50.362 [INFO] bluetooth: 💤 Bluetooth adapter stays on
2025-01-19, 16:51:58.640 [ERROR] proxy: 🔴 Connection error: USB read: deadline has elapsed
2025-01-19, 16:51:58.641 [INFO] proxy: ⌛ session time: 11s 156ms 205us 751ns
2025-01-19, 16:51:58.641 [INFO] proxy: 💤 waiting for bluetooth handshake...
2025-01-19, 16:51:58.641 [INFO] main: 📵 TCP/USB connection closed or not started, trying again...
2025-01-19, 16:51:59.643 [INFO] usb: 🔌 Initializing USB Manager
2025-01-19, 16:52:00.070 [INFO] usb: 🔌 USB Manager: Disabled all USB gadgets
2025-01-19, 16:52:00.070 [INFO] bluetooth: 🥏 Bluetooth alias: NaviPi
2025-01-19, 16:52:00.080 [INFO] bluetooth: 🥏 Opened bluetooth adapter hci0 with address XX:XX:XX:XX:XX:XX
2025-01-19, 16:52:00.106 [INFO] bluetooth: 📱 AA Wireless Profile: registered
2025-01-19, 16:52:00.108 [INFO] bluetooth: 🎧 Headset Profile (HSP): registered
2025-01-19, 16:52:00.108 [INFO] bluetooth: ⏳ Waiting for phone to connect via bluetooth...

@KreAch3R
Copy link
Owner

Also bad error messages are:

[ERROR] main: Bluetooth error: Software caused connection abort (os error 103)
[ERROR] proxy: 🔴 Connection error: USB read: deadline has elapsed
[ERROR] proxy: 🔴 Connection error: Connection reset by peer (os error 104)

These mean that a bluetooth connection was established, a tcp port was opened and the headunit is waiting on a wifi connection and video streaming, but it doesn't start.

Check the discussion here: https://www.reddit.com/r/raspberry_pi/comments/1hoxd63/wireless_android_auto_on_latest_openautopro_and/

Completely remove bluetooth from both devices and forget connected cars etc. Try to reset Android Auto completely.

Also check the wifi network, and that the phone's IP is on the same IP range (10.0.0.*).

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants