search

albfan / miraclecast

Connect external monitors to your system via Wifi-Display specification also known as Miracast
Other
3.8k stars 411 forks source link

Miraclecast does not handle a "crashing" USB wifi device well #229

Open haukepribnow opened 7 years ago

haukepribnow commented 7 years ago

I have a Raspberry 2 with the brand-new Raspbian Stretch installed. I use a Atheros AR9001-based USB wifi dongle (AVM GmbH Fritz!WLAN N 2.4) that runs on the carl9170 driver.

The following log entries are from journalctl -f.

When running miracle-sinkctl and use my smartphone to do screen mirroring, I get a video stream for some time (a few seconds usually). Then my usb device seems to "crash":

Aug 29 09:31:56 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[709]: p2p-3: CTRL-EVENT-DISCONNECTED bssid=ba:dc:ab:le:00:01 reason=4 locally_generated=1
Aug 29 09:31:56 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[709]: p2p-3: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Aug 29 09:31:58 raspberrypi kernel: usb 1-1.4.3: no command feedback received (-110).
Aug 29 09:31:58 raspberrypi kernel: carl9170 cmd: 38 01 00 00 00 58 1c 00 07 00 00 00 04 58 1c 00  8....X.......X..
Aug 29 09:31:58 raspberrypi kernel: carl9170 cmd: 00 03 00 00 08 58 1c 00 00 00 00 00 0c 58 1c 00  .....X.......X..
Aug 29 09:31:58 raspberrypi kernel: carl9170 cmd: 19 8e 84 ad 10 58 1c 00 00 e0 14 7d 14 58 1c 00  .....X.....}.X..
Aug 29 09:31:58 raspberrypi kernel: carl9170 cmd: 6b 9f 0a 9c 18 58 1c 00 90 00 00 00              k....X......

The device restart will immediately be organized... but obviously some items in some processing queue cannot be processed during restart, leading to some failure log entries:

Aug 29 09:31:58 raspberrypi kernel: usb 1-1.4.3: restart device (6)
Aug 29 09:31:59 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)
Aug 29 09:32:00 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)
Aug 29 09:32:01 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)
Aug 29 09:32:02 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)
Aug 29 09:32:03 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)
Aug 29 09:32:04 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)
Aug 29 09:32:05 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)
Aug 29 09:32:06 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)

At some point, the miraclecast daemons will also notice that something went wrong:

Aug 29 09:32:06 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[709]: P2P-GROUP-REMOVED p2p-3 client reason=IDLE
Aug 29 09:32:06 raspberrypi miracle-dhcp-p2p-3[1928]: NOTICE: dhcp: received signal 15: Terminated
Aug 29 09:32:06 raspberrypi miracle-dhcp-p2p-3[1928]: INFO: dhcp: flushing local if-addr
Aug 29 09:32:06 raspberrypi miracle-dhcp-p2p-3[1928]: DEBUG: dhcp: successfully flushed local if-addr via /bin/ip (flush_if_addr() in /home/pi/miraclecast/src/dhcp/dhcp.c:187)
Aug 29 09:32:06 raspberrypi miracle-dhcp-p2p-3[1928]: DEBUG: dhcp: exiting.. (main() in /home/pi/miraclecast/src/dhcp/dhcp.c:937)
Aug 29 09:32:06 raspberrypi unknown[1928]: Source ID 1 was not found when attempting to remove it
Aug 29 09:32:06 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[709]: nl80211: deinit ifname=p2p-3 disabled_11b_rates=0

Now something interesting happens: miracle-wifid will try to re-initialize the interface and tries to bind to it somehow. Binding fails with an "Address already in use" error message:

Aug 29 09:32:07 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)
Aug 29 09:32:07 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8290]: supp-global-ctrl-iface-init(/run/miracle/wifi/wlx001f3f0b0c02-3.global) (will try fixup): bind(PF_UNIX): Address already in use
Aug 29 09:32:07 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8290]: ctrl_iface exists and seems to be in use - cannot override it
Aug 29 09:32:07 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8290]: Delete '/run/miracle/wifi/wlx001f3f0b0c02-3.global' manually if it is not used anymore
Aug 29 09:32:07 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8290]: Failed to initialize wpa_supplicant

Then some kernel messages appear that I don't really understand:

Aug 29 09:32:08 raspberrypi kernel: ieee80211 phy0: writing reg 0x1c36f0 (val 0x5000) failed (-110)
Aug 29 09:32:09 raspberrypi kernel: ------------[ cut here ]------------
Aug 29 09:32:09 raspberrypi kernel: WARNING: CPU: 0 PID: 1905 at drivers/net/wireless/ath/carl9170/main.c:1012 carl9170_op_configure_filter+0x17c/0x180 [carl9170]
Aug 29 09:32:09 raspberrypi kernel: Modules linked in: ctr ccm arc4 carl9170 ath mac80211 cfg80211 rfkill evdev snd_bcm2835 snd_pcm snd_timer snd bcm2835_gpiomem uio_pdrv_genirq uio fixed sr_mod cdrom sg ip_tables x_tables ipv6
Aug 29 09:32:09 raspberrypi kernel: CPU: 0 PID: 1905 Comm: kworker/u8:3 Not tainted 4.9.41-v7+ #1023
Aug 29 09:32:09 raspberrypi kernel: Hardware name: BCM2835
Aug 29 09:32:09 raspberrypi kernel: Workqueue: phy0 ieee80211_scan_work [mac80211]
Aug 29 09:32:09 raspberrypi kernel: [<8010fb3c>] (unwind_backtrace) from [<8010c058>] (show_stack+0x20/0x24)
Aug 29 09:32:09 raspberrypi kernel: [<8010c058>] (show_stack) from [<80455f84>] (dump_stack+0xd4/0x118)
Aug 29 09:32:09 raspberrypi kernel: [<80455f84>] (dump_stack) from [<8011d344>] (__warn+0xf8/0x110)
Aug 29 09:32:09 raspberrypi kernel: [<8011d344>] (__warn) from [<8011d42c>] (warn_slowpath_null+0x30/0x38)
Aug 29 09:32:09 raspberrypi kernel: [<8011d42c>] (warn_slowpath_null) from [<7f2e5480>] (carl9170_op_configure_filter+0x17c/0x180 [carl9170])
Aug 29 09:32:09 raspberrypi kernel: [<7f2e5480>] (carl9170_op_configure_filter [carl9170]) from [<7f209784>] (ieee80211_configure_filter+0x160/0x3b4 [mac80211])
Aug 29 09:32:09 raspberrypi kernel: [<7f209784>] (ieee80211_configure_filter [mac80211]) from [<7f2147f8>] (__ieee80211_scan_completed+0x168/0x414 [mac80211])
Aug 29 09:32:09 raspberrypi kernel: [<7f2147f8>] (__ieee80211_scan_completed [mac80211]) from [<7f215b68>] (ieee80211_scan_work+0x58/0x480 [mac80211])
Aug 29 09:32:09 raspberrypi kernel: [<7f215b68>] (ieee80211_scan_work [mac80211]) from [<80136698>] (process_one_work+0x158/0x454)
Aug 29 09:32:09 raspberrypi kernel: [<80136698>] (process_one_work) from [<801369f8>] (worker_thread+0x64/0x568)
Aug 29 09:32:09 raspberrypi kernel: [<801369f8>] (worker_thread) from [<8013c8f8>] (kthread+0x10c/0x124)
Aug 29 09:32:09 raspberrypi kernel: [<8013c8f8>] (kthread) from [<80108148>] (ret_from_fork+0x14/0x2c)
Aug 29 09:32:09 raspberrypi kernel: ---[ end trace 033e4335f1ad8853 ]---
Aug 29 09:32:10 raspberrypi kernel: ieee80211 phy0: writing reg 0x1d0104 (val 0x1) failed (-110)
Aug 29 09:32:11 raspberrypi kernel: ------------[ cut here ]------------
Aug 29 09:32:11 raspberrypi kernel: WARNING: CPU: 3 PID: 709 at drivers/net/wireless/ath/carl9170/main.c:802 carl9170_op_remove_interface+0x234/0x240 [carl9170]
Aug 29 09:32:11 raspberrypi kernel: Modules linked in: ctr ccm arc4 carl9170 ath mac80211 cfg80211 rfkill evdev snd_bcm2835 snd_pcm snd_timer snd bcm2835_gpiomem uio_pdrv_genirq uio fixed sr_mod cdrom sg ip_tables x_tables ipv6
Aug 29 09:32:11 raspberrypi kernel: CPU: 3 PID: 709 Comm: wpa_supplicant Tainted: G        W       4.9.41-v7+ #1023
Aug 29 09:32:11 raspberrypi kernel: Hardware name: BCM2835
Aug 29 09:32:11 raspberrypi kernel: [<8010fb3c>] (unwind_backtrace) from [<8010c058>] (show_stack+0x20/0x24)
Aug 29 09:32:11 raspberrypi kernel: [<8010c058>] (show_stack) from [<80455f84>] (dump_stack+0xd4/0x118)
Aug 29 09:32:11 raspberrypi kernel: [<80455f84>] (dump_stack) from [<8011d344>] (__warn+0xf8/0x110)
Aug 29 09:32:11 raspberrypi kernel: [<8011d344>] (__warn) from [<8011d42c>] (warn_slowpath_null+0x30/0x38)
Aug 29 09:32:11 raspberrypi kernel: [<8011d42c>] (warn_slowpath_null) from [<7f2e5ff0>] (carl9170_op_remove_interface+0x234/0x240 [carl9170])
Aug 29 09:32:11 raspberrypi kernel: [<7f2e5ff0>] (carl9170_op_remove_interface [carl9170]) from [<7f20bc18>] (drv_remove_interface+0x48/0x178 [mac80211])
Aug 29 09:32:11 raspberrypi kernel: [<7f20bc18>] (drv_remove_interface [mac80211]) from [<7f2210cc>] (ieee80211_do_stop+0x558/0x7d4 [mac80211])
Aug 29 09:32:11 raspberrypi kernel: [<7f2210cc>] (ieee80211_do_stop [mac80211]) from [<7f221368>] (ieee80211_stop+0x20/0x28 [mac80211])
Aug 29 09:32:11 raspberrypi kernel: [<7f221368>] (ieee80211_stop [mac80211]) from [<80623138>] (__dev_close_many+0xa4/0xf4)
Aug 29 09:32:11 raspberrypi kernel: [<80623138>] (__dev_close_many) from [<806232c0>] (__dev_close+0x38/0x50)
Aug 29 09:32:11 raspberrypi kernel: [<806232c0>] (__dev_close) from [<8062ce24>] (__dev_change_flags+0xa4/0x154)
Aug 29 09:32:11 raspberrypi kernel: [<8062ce24>] (__dev_change_flags) from [<8062cefc>] (dev_change_flags+0x28/0x58)
Aug 29 09:32:11 raspberrypi kernel: [<8062cefc>] (dev_change_flags) from [<806a475c>] (devinet_ioctl+0x680/0x744)
Aug 29 09:32:11 raspberrypi kernel: [<806a475c>] (devinet_ioctl) from [<806a6a10>] (inet_ioctl+0x1a4/0x1d0)
Aug 29 09:32:11 raspberrypi kernel: [<806a6a10>] (inet_ioctl) from [<8060a46c>] (sock_ioctl+0x1dc/0x2ac)
Aug 29 09:32:11 raspberrypi kernel: [<8060a46c>] (sock_ioctl) from [<80283730>] (do_vfs_ioctl+0xac/0x820)
Aug 29 09:32:11 raspberrypi kernel: [<80283730>] (do_vfs_ioctl) from [<80283ee8>] (SyS_ioctl+0x44/0x6c)
Aug 29 09:32:11 raspberrypi kernel: [<80283ee8>] (SyS_ioctl) from [<801080c0>] (ret_fast_syscall+0x0/0x1c)
Aug 29 09:32:11 raspberrypi kernel: ---[ end trace 033e4335f1ad8854 ]---

At some point, miracle-wifid realizes that it has lost its peer (because of the interface that went down):

Aug 29 09:32:12 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[709]: CTRL_IFACE: Detach monitor /tmp/.miracle-wpas-706-1\x00 that cannot receive messages
Aug 29 09:32:12 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[709]: P2P-DEVICE-LOST p2p_dev_addr=ba:dc:ab:le:00:02
Aug 29 09:32:12 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[709]: nl80211: deinit ifname=wlx001f3f0b0c02 disabled_11b_rates=0
Aug 29 09:32:13 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[709]: wlx001f3f0b0c02: CTRL-EVENT-TERMINATING

And at some point later, the USB dongle gets successfully restarted:

Aug 29 09:32:14 raspberrypi kernel: usb 1-1.4.3: kill pending tx urbs.
Aug 29 09:32:16 raspberrypi kernel: usb 1-1.4.3: device restarted successfully.
Aug 29 09:32:16 raspberrypi kernel: ieee80211 phy0: Hardware restart was requested

Then come some kernel error messages I don't really understand again:

Aug 29 09:32:17 raspberrypi kernel: ------------[ cut here ]------------
Aug 29 09:32:17 raspberrypi kernel: WARNING: CPU: 0 PID: 1905 at drivers/net/wireless/ath/carl9170/main.c:1012 carl9170_op_configure_filter+0x17c/0x180 [carl9170]
Aug 29 09:32:17 raspberrypi kernel: Modules linked in: ctr ccm arc4 carl9170 ath mac80211 cfg80211 rfkill evdev snd_bcm2835 snd_pcm snd_timer snd bcm2835_gpiomem uio_pdrv_genirq uio fixed sr_mod cdrom sg ip_tables x_tables ipv6
Aug 29 09:32:17 raspberrypi kernel: CPU: 0 PID: 1905 Comm: kworker/u8:3 Tainted: G        W       4.9.41-v7+ #1023
Aug 29 09:32:17 raspberrypi kernel: Hardware name: BCM2835
Aug 29 09:32:17 raspberrypi kernel: Workqueue: phy0 ieee80211_reconfig_filter [mac80211]
Aug 29 09:32:17 raspberrypi kernel: [<8010fb3c>] (unwind_backtrace) from [<8010c058>] (show_stack+0x20/0x24)
Aug 29 09:32:17 raspberrypi kernel: [<8010c058>] (show_stack) from [<80455f84>] (dump_stack+0xd4/0x118)
Aug 29 09:32:17 raspberrypi kernel: [<80455f84>] (dump_stack) from [<8011d344>] (__warn+0xf8/0x110)
Aug 29 09:32:17 raspberrypi kernel: [<8011d344>] (__warn) from [<8011d42c>] (warn_slowpath_null+0x30/0x38)
Aug 29 09:32:17 raspberrypi kernel: [<8011d42c>] (warn_slowpath_null) from [<7f2e5480>] (carl9170_op_configure_filter+0x17c/0x180 [carl9170])
Aug 29 09:32:17 raspberrypi kernel: [<7f2e5480>] (carl9170_op_configure_filter [carl9170]) from [<7f209784>] (ieee80211_configure_filter+0x160/0x3b4 [mac80211])
Aug 29 09:32:17 raspberrypi kernel: [<7f209784>] (ieee80211_configure_filter [mac80211]) from [<7f2099f4>] (ieee80211_reconfig_filter+0x1c/0x20 [mac80211])
Aug 29 09:32:17 raspberrypi kernel: [<7f2099f4>] (ieee80211_reconfig_filter [mac80211]) from [<80136698>] (process_one_work+0x158/0x454)
Aug 29 09:32:17 raspberrypi kernel: [<80136698>] (process_one_work) from [<801369f8>] (worker_thread+0x64/0x568)
Aug 29 09:32:17 raspberrypi kernel: [<801369f8>] (worker_thread) from [<8013c8f8>] (kthread+0x10c/0x124)
Aug 29 09:32:17 raspberrypi kernel: [<8013c8f8>] (kthread) from [<80108148>] (ret_from_fork+0x14/0x2c)
Aug 29 09:32:17 raspberrypi kernel: ---[ end trace 033e4335f1ad8855 ]---
Aug 29 09:32:19 raspberrypi kernel: ------------[ cut here ]------------
Aug 29 09:32:19 raspberrypi kernel: WARNING: CPU: 0 PID: 149 at net/mac80211/util.c:1863 ieee80211_reconfig+0xc4/0x1644 [mac80211]
Aug 29 09:32:19 raspberrypi kernel: Hardware became unavailable during restart.
Aug 29 09:32:19 raspberrypi kernel: Modules linked in: ctr ccm arc4 carl9170 ath mac80211 cfg80211 rfkill evdev snd_bcm2835 snd_pcm snd_timer snd bcm2835_gpiomem uio_pdrv_genirq uio fixed sr_mod cdrom sg ip_tables x_tables ipv6
Aug 29 09:32:19 raspberrypi kernel: CPU: 0 PID: 149 Comm: kworker/0:2 Tainted: G        W       4.9.41-v7+ #1023
Aug 29 09:32:19 raspberrypi kernel: Hardware name: BCM2835
Aug 29 09:32:19 raspberrypi kernel: Workqueue: events_freezable ieee80211_restart_work [mac80211]
Aug 29 09:32:19 raspberrypi kernel: [<8010fb3c>] (unwind_backtrace) from [<8010c058>] (show_stack+0x20/0x24)
Aug 29 09:32:19 raspberrypi kernel: [<8010c058>] (show_stack) from [<80455f84>] (dump_stack+0xd4/0x118)
Aug 29 09:32:19 raspberrypi kernel: [<80455f84>] (dump_stack) from [<8011d344>] (__warn+0xf8/0x110)
Aug 29 09:32:19 raspberrypi kernel: [<8011d344>] (__warn) from [<8011d3a4>] (warn_slowpath_fmt+0x48/0x50)
Aug 29 09:32:19 raspberrypi kernel: [<8011d3a4>] (warn_slowpath_fmt) from [<7f24042c>] (ieee80211_reconfig+0xc4/0x1644 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f24042c>] (ieee80211_reconfig [mac80211]) from [<7f2083a8>] (ieee80211_restart_work+0x88/0xb4 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f2083a8>] (ieee80211_restart_work [mac80211]) from [<80136698>] (process_one_work+0x158/0x454)
Aug 29 09:32:19 raspberrypi kernel: [<80136698>] (process_one_work) from [<801369f8>] (worker_thread+0x64/0x568)
Aug 29 09:32:19 raspberrypi kernel: [<801369f8>] (worker_thread) from [<8013c8f8>] (kthread+0x10c/0x124)
Aug 29 09:32:19 raspberrypi kernel: [<8013c8f8>] (kthread) from [<80108148>] (ret_from_fork+0x14/0x2c)
Aug 29 09:32:19 raspberrypi kernel: ---[ end trace 033e4335f1ad8856 ]---
Aug 29 09:32:19 raspberrypi kernel: ------------[ cut here ]------------
Aug 29 09:32:19 raspberrypi kernel: WARNING: CPU: 0 PID: 149 at net/mac80211/driver-ops.h:17 drv_remove_interface+0x170/0x178 [mac80211]
Aug 29 09:32:19 raspberrypi kernel: wlx001f3f0b0c02:  Failed check-sdata-in-driver check, flags: 0x0
Aug 29 09:32:19 raspberrypi kernel: Modules linked in: ctr ccm arc4 carl9170 ath mac80211 cfg80211 rfkill evdev snd_bcm2835 snd_pcm snd_timer snd bcm2835_gpiomem uio_pdrv_genirq uio fixed sr_mod cdrom sg ip_tables x_tables ipv6
Aug 29 09:32:19 raspberrypi kernel: CPU: 0 PID: 149 Comm: kworker/0:2 Tainted: G        W       4.9.41-v7+ #1023
Aug 29 09:32:19 raspberrypi kernel: Hardware name: BCM2835
Aug 29 09:32:19 raspberrypi kernel: Workqueue: events_freezable ieee80211_restart_work [mac80211]
Aug 29 09:32:19 raspberrypi kernel: [<8010fb3c>] (unwind_backtrace) from [<8010c058>] (show_stack+0x20/0x24)
Aug 29 09:32:19 raspberrypi kernel: [<8010c058>] (show_stack) from [<80455f84>] (dump_stack+0xd4/0x118)
Aug 29 09:32:19 raspberrypi kernel: [<80455f84>] (dump_stack) from [<8011d344>] (__warn+0xf8/0x110)
Aug 29 09:32:19 raspberrypi kernel: [<8011d344>] (__warn) from [<8011d3a4>] (warn_slowpath_fmt+0x48/0x50)
Aug 29 09:32:19 raspberrypi kernel: [<8011d3a4>] (warn_slowpath_fmt) from [<7f20bd40>] (drv_remove_interface+0x170/0x178 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f20bd40>] (drv_remove_interface [mac80211]) from [<7f2210cc>] (ieee80211_do_stop+0x558/0x7d4 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f2210cc>] (ieee80211_do_stop [mac80211]) from [<7f221368>] (ieee80211_stop+0x20/0x28 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f221368>] (ieee80211_stop [mac80211]) from [<80623138>] (__dev_close_many+0xa4/0xf4)
Aug 29 09:32:19 raspberrypi kernel: [<80623138>] (__dev_close_many) from [<80623204>] (dev_close_many+0x7c/0x100)
Aug 29 09:32:19 raspberrypi kernel: [<80623204>] (dev_close_many) from [<80628254>] (dev_close.part.10+0x40/0x58)
Aug 29 09:32:19 raspberrypi kernel: [<80628254>] (dev_close.part.10) from [<80628290>] (dev_close+0x24/0x2c)
Aug 29 09:32:19 raspberrypi kernel: [<80628290>] (dev_close) from [<7f152ed0>] (cfg80211_shutdown_all_interfaces+0x44/0xc8 [cfg80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f152ed0>] (cfg80211_shutdown_all_interfaces [cfg80211]) from [<7f23e418>] (ieee80211_handle_reconfig_failure+0xa8/0xdc [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f23e418>] (ieee80211_handle_reconfig_failure [mac80211]) from [<7f240434>] (ieee80211_reconfig+0xcc/0x1644 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f240434>] (ieee80211_reconfig [mac80211]) from [<7f2083a8>] (ieee80211_restart_work+0x88/0xb4 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f2083a8>] (ieee80211_restart_work [mac80211]) from [<80136698>] (process_one_work+0x158/0x454)
Aug 29 09:32:19 raspberrypi kernel: [<80136698>] (process_one_work) from [<801369f8>] (worker_thread+0x64/0x568)
Aug 29 09:32:19 raspberrypi kernel: [<801369f8>] (worker_thread) from [<8013c8f8>] (kthread+0x10c/0x124)
Aug 29 09:32:19 raspberrypi kernel: [<8013c8f8>] (kthread) from [<80108148>] (ret_from_fork+0x14/0x2c)
Aug 29 09:32:19 raspberrypi kernel: ---[ end trace 033e4335f1ad8857 ]---
Aug 29 09:32:19 raspberrypi kernel: ------------[ cut here ]------------
Aug 29 09:32:19 raspberrypi kernel: WARNING: CPU: 0 PID: 149 at net/mac80211/driver-ops.c:39 drv_stop+0x19c/0x1a0 [mac80211]
Aug 29 09:32:19 raspberrypi kernel: Modules linked in: ctr ccm arc4 carl9170 ath mac80211 cfg80211 rfkill evdev snd_bcm2835 snd_pcm snd_timer snd bcm2835_gpiomem uio_pdrv_genirq uio fixed sr_mod cdrom sg ip_tables x_tables ipv6
Aug 29 09:32:19 raspberrypi kernel: CPU: 0 PID: 149 Comm: kworker/0:2 Tainted: G        W       4.9.41-v7+ #1023
Aug 29 09:32:19 raspberrypi kernel: Hardware name: BCM2835
Aug 29 09:32:19 raspberrypi kernel: Workqueue: events_freezable ieee80211_restart_work [mac80211]
Aug 29 09:32:19 raspberrypi kernel: [<8010fb3c>] (unwind_backtrace) from [<8010c058>] (show_stack+0x20/0x24)
Aug 29 09:32:19 raspberrypi kernel: [<8010c058>] (show_stack) from [<80455f84>] (dump_stack+0xd4/0x118)
Aug 29 09:32:19 raspberrypi kernel: [<80455f84>] (dump_stack) from [<8011d344>] (__warn+0xf8/0x110)
Aug 29 09:32:19 raspberrypi kernel: [<8011d344>] (__warn) from [<8011d42c>] (warn_slowpath_null+0x30/0x38)
Aug 29 09:32:19 raspberrypi kernel: [<8011d42c>] (warn_slowpath_null) from [<7f20b8ac>] (drv_stop+0x19c/0x1a0 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f20b8ac>] (drv_stop [mac80211]) from [<7f240364>] (ieee80211_stop_device+0x48/0x4c [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f240364>] (ieee80211_stop_device [mac80211]) from [<7f2210ac>] (ieee80211_do_stop+0x538/0x7d4 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f2210ac>] (ieee80211_do_stop [mac80211]) from [<7f221368>] (ieee80211_stop+0x20/0x28 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f221368>] (ieee80211_stop [mac80211]) from [<80623138>] (__dev_close_many+0xa4/0xf4)
Aug 29 09:32:19 raspberrypi kernel: [<80623138>] (__dev_close_many) from [<80623204>] (dev_close_many+0x7c/0x100)
Aug 29 09:32:19 raspberrypi kernel: [<80623204>] (dev_close_many) from [<80628254>] (dev_close.part.10+0x40/0x58)
Aug 29 09:32:19 raspberrypi kernel: [<80628254>] (dev_close.part.10) from [<80628290>] (dev_close+0x24/0x2c)
Aug 29 09:32:19 raspberrypi kernel: [<80628290>] (dev_close) from [<7f152ed0>] (cfg80211_shutdown_all_interfaces+0x44/0xc8 [cfg80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f152ed0>] (cfg80211_shutdown_all_interfaces [cfg80211]) from [<7f23e418>] (ieee80211_handle_reconfig_failure+0xa8/0xdc [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f23e418>] (ieee80211_handle_reconfig_failure [mac80211]) from [<7f240434>] (ieee80211_reconfig+0xcc/0x1644 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f240434>] (ieee80211_reconfig [mac80211]) from [<7f2083a8>] (ieee80211_restart_work+0x88/0xb4 [mac80211])
Aug 29 09:32:19 raspberrypi kernel: [<7f2083a8>] (ieee80211_restart_work [mac80211]) from [<80136698>] (process_one_work+0x158/0x454)
Aug 29 09:32:19 raspberrypi kernel: [<80136698>] (process_one_work) from [<801369f8>] (worker_thread+0x64/0x568)
Aug 29 09:32:19 raspberrypi kernel: [<801369f8>] (worker_thread) from [<8013c8f8>] (kthread+0x10c/0x124)
Aug 29 09:32:19 raspberrypi kernel: [<8013c8f8>] (kthread) from [<80108148>] (ret_from_fork+0x14/0x2c)
Aug 29 09:32:19 raspberrypi kernel: ---[ end trace 033e4335f1ad8858 ]---

And finally, miracle-wifid tries to handle the situation after the restart of the device again:

Aug 29 09:32:37 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8296]: Successfully initialized wpa_supplicant
Aug 29 09:32:38 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8296]: Could not set interface wlx001f3f0b0c02 flags (UP): Connection timed out
Aug 29 09:32:38 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8296]: nl80211: Could not set interface 'wlx001f3f0b0c02' UP
Aug 29 09:32:38 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8296]: nl80211: deinit ifname=wlx001f3f0b0c02 disabled_11b_rates=0
Aug 29 09:32:38 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8296]: wlx001f3f0b0c02: Failed to initialize driver interface
Aug 29 09:32:39 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8297]: Successfully initialized wpa_supplicant
Aug 29 09:32:40 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8298]: supp-global-ctrl-iface-init(/run/miracle/wifi/wlx001f3f0b0c02-3.global) (will try fixup): bind(PF_UNIX): Address already in use
Aug 29 09:32:40 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8298]: Successfully initialized wpa_supplicant
Aug 29 09:33:11 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8301]: supp-global-ctrl-iface-init(/run/miracle/wifi/wlx001f3f0b0c02-3.global) (will try fixup): bind(PF_UNIX): Address already in use
Aug 29 09:33:11 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8301]: Successfully initialized wpa_supplicant
Aug 29 09:33:13 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8304]: supp-global-ctrl-iface-init(/run/miracle/wifi/wlx001f3f0b0c02-3.global) (will try fixup): bind(PF_UNIX): Address already in use
Aug 29 09:33:13 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8304]: Successfully initialized wpa_supplicant
Aug 29 09:33:14 raspberrypi miracle-wifid-wlx001f3f0b0c02-3[8305]: supp-global-ctrl-iface-init(/run/miracle/wifi/wlx001f3f0b0c02-3.global) (will try fixup): bind(PF_UNIX): Address already in use

Here, some binding fails again with a "Address already in use" error message. It appears that some process did not properly "release" its bind when the USB device "crashed".

Because of these binding errors, I will not be able to establish a new p2p connection before a system restart. (I haven't tried shutting down relevant services yet... that might also work.)

The relevant sections of miracle-wifid's log look like this:

TRACE: wpa: raw message: IFNAME=p2p-3 <3>CTRL-EVENT-DISCONNECTED bssid=ba:dc:ab:le:00:01 reason=4 locally_generated=1
TRACE: wpa: raw message: IFNAME=p2p-3 <3>CTRL-EVENT-SCAN-STARTED 
TRACE: wpa: raw message: IFNAME=p2p-3 <3>CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
DEBUG: supplicant: unhandled wpas-event: IFNAME=p2p-3 <3>CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD (supplicant_event() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:1499)
TRACE: wpa: raw message: <3>P2P-GROUP-REMOVED p2p-3 client reason=IDLE
DEBUG: supplicant: remove group p2p-3 (supplicant_event_p2p_group_removed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:1291)
DEBUG: supplicant: free group p2p-3 (supplicant_group_free() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:174)
DEBUG: supplicant: killing DHCP-process pid:1928.. (supplicant_group_free() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:198)
DEBUG: supplicant: sent P2P_FIND to wpas on wlx001f3f0b0c02 (supplicant_p2p_start_scan() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2036)
DEBUG: caught SIGCHLD for 1928, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
TRACE: wpa: raw message: IFNAME=p2p-3 <3>CTRL-EVENT-BSS-REMOVED 0 ba:dc:ab:le:00:01
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:709) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: terminating wpas (pid:709) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: peer: peer ba:dc:ab:le:00:02 @ wlx001f3f0b0c02 stopped (peer_supplicant_stopped() in /home/pi/miraclecast/src/wifi/wifid-peer.c:172)
DEBUG: peer: free peer: ba:dc:ab:le:00:02 @ wlx001f3f0b0c02 (peer_free() in /home/pi/miraclecast/src/wifi/wifid-peer.c:90)
INFO: peer: remove peer: ba:dc:ab:le:00:02
DEBUG: peer: peer ba:dc:ab:le:00:01 @ wlx001f3f0b0c02 stopped (peer_supplicant_stopped() in /home/pi/miraclecast/src/wifi/wifid-peer.c:172)
DEBUG: peer: free peer: ba:dc:ab:le:00:01 @ wlx001f3f0b0c02 (peer_free() in /home/pi/miraclecast/src/wifi/wifid-peer.c:90)
INFO: peer: remove peer: ba:dc:ab:le:00:01
DEBUG: link: link wlx001f3f0b0c02 stopped (link_supplicant_stopped() in /home/pi/miraclecast/src/wifi/wifid-link.c:297)
ERROR: supplicant: HUP on supplicant dev-socket of wlx001f3f0b0c02 (supplicant_dev_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2142)
ERROR: supplicant: wpas (pid:0) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8290
ERROR: supplicant: wpas (pid:8290) failed again.. entering grace period, waiting 30s before relaunching (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2335)
DEBUG: supplicant: terminating wpas (pid:8290) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8290, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: caught SIGCHLD for 709, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8296
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: wpas (pid:8296) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: terminating wpas (pid:8296) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8296, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8297
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8297) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: terminating wpas (pid:8297) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8297, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8298
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8298) failed again.. entering grace period, waiting 30s before relaunching (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2335)
DEBUG: supplicant: terminating wpas (pid:8298) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8298, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8301
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8301) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: terminating wpas (pid:8301) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8301, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8304
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8304) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: terminating wpas (pid:8304) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8304, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8305
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8305) failed again.. entering grace period, waiting 30s before relaunching (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2335)
DEBUG: supplicant: terminating wpas (pid:8305) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8305, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8334
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8334) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: terminating wpas (pid:8334) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8334, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8335
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8335) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: terminating wpas (pid:8335) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8335, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8336
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8336) failed again.. entering grace period, waiting 30s before relaunching (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2335)
DEBUG: supplicant: terminating wpas (pid:8336) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8336, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8338
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8338) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: terminating wpas (pid:8338) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8338, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8339
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8339) failed unexpectedly, relaunching after short grace period.. (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2331)
DEBUG: supplicant: terminating wpas (pid:8339) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8339, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)
DEBUG: supplicant: spawn supplicant of wlx001f3f0b0c02 (supplicant_spawn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2478)
INFO: supplicant: wpa_supplicant found: /sbin/wpa_supplicant
INFO: supplicant: wpas spawned as pid:8340
DEBUG: supplicant: open supplicant of wlx001f3f0b0c02 (supplicant_open() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2269)
ERROR: supplicant: HUP on supplicant socket of wlx001f3f0b0c02 (supplicant_global_fn() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2161)
ERROR: supplicant: wpas (pid:8340) failed again.. entering grace period, waiting 30s before relaunching (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2335)
DEBUG: supplicant: terminating wpas (pid:8340) (supplicant_failed() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2346)
DEBUG: supplicant: close supplicant of wlx001f3f0b0c02 (supplicant_close() in /home/pi/miraclecast/src/wifi/wifid-supplicant.c:2310)
DEBUG: caught SIGCHLD for 8340, reaping child (manager_signal_fn() in /home/pi/miraclecast/src/wifi/wifid.c:178)

Is there a way to make miraclecast handle situations like these more "gracefully"?

albfan commented 7 years ago

Thanks for detail info. I only have a rpi 3 so I cannot debug this miself. Let me see if I can point out where to hack to fix this, but I need you to test the changes

haukepribnow commented 7 years ago

Sure, I'm happy to test. :)

haukepribnow commented 6 years ago

Anything I can be of help in narrowing down the problem?