n8henrie/fauxmo

Echo not finding fauxmo devices after discovery

Closed this issue · 15 comments

calikw commented
  • Operating system and version: Ubuntu MATE 1.12.1
  • Python version: 3.5.2
  • Fauxmo version: 0.3.4

My Issue

Echo Dot 2 doesn't find any devices after discovery search.

WHYT

I seem to have gotten fauxmo installed, created a config file that is correctly formatted JSON, and it seems to run without error; however, when putting the Dot through discovery mode, it does not find any of the devices configured in config.json (currently using a direct copy of the config-sample.json provided on this project).

From the verbose output, I think it's showing that the Echo is requesting and fauxmo is responding, but it still doesn't register the fake devices. Admittedly, I don't have any of the "on_cmd" or "off_cmd" destinations actually setup yet (just the dummy ip/DNS addresses that are in the config-sample). Do these need to be actual working destinations for the Echo to recognize it as a device? Or is something else not working?

Any help would be greatly appreciated. And thank you for making this fork available :)

Verbose output

2016-12-24 09:19:53 fauxmo:19       INFO     Fauxmo version 0.3.4
2016-12-24 09:19:54 fauxmo:39       DEBUG    3.5.2 (default, Nov 17 2016, 17:05:23) 
[GCC 5.4.0 20160609]
2016-12-24 09:19:54 fauxmo:46       INFO     Using config: /etc/fauxmo/config.json
2016-12-24 09:19:54 fauxmo:16       DEBUG    Attempting to get IP address automatically
2016-12-24 09:19:54 fauxmo:29       DEBUG    Using IP address: 192.168.132.2
2016-12-24 09:19:54 asyncio:979      INFO     <Server sockets=[<socket.socket fd=8, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('192.168.132.2', 12340)>]> is serving
2016-12-24 09:19:54 fauxmo:81       DEBUG    {'name': 'fake switch one', 'action_handler': <fauxmo.handlers.rest.RESTAPIHandler object at 0xb5e31ed0>}
2016-12-24 09:19:54 asyncio:979      INFO     <Server sockets=[<socket.socket fd=9, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('192.168.132.2', 12341)>]> is serving
2016-12-24 09:19:54 fauxmo:81       DEBUG    {'name': 'fake Indigo switch', 'action_handler': <fauxmo.handlers.rest.RESTAPIHandler object at 0xb605b190>}
2016-12-24 09:19:54 asyncio:979      INFO     <Server sockets=[<socket.socket fd=10, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('192.168.132.2', 12342)>]> is serving
2016-12-24 09:19:54 fauxmo:81       DEBUG    {'name': 'fake hass switch by REST API', 'action_handler': <fauxmo.handlers.rest.RESTAPIHandler object at 0xb6352710>}
2016-12-24 09:19:54 fauxmo:107      INFO     Starting UDP server
2016-12-24 09:19:54 asyncio:853      INFO     Datagram endpoint local_addr=('0.0.0.0', 1900) remote_addr=None created: (<_SelectorDatagramTransport fd=11 read=idle write=<idle, bufsize=0>>, <fauxmo.protocols.SSDPServer object at 0xb5e315b0>)
2016-12-24 09:20:05 asyncio:1266     INFO     poll took 10579.304 ms: 1 events
2016-12-24 09:20:05 fauxmo:159      DEBUG    Received data below from ('192.168.132.112', 50000):
2016-12-24 09:20:05 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 15\r\nST: urn:Belkin:device:**\r\n\r\n'
2016-12-24 09:20:05 fauxmo:191      DEBUG    Sending response to ('192.168.132.112', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Sat, 24 Dec 2016 17:20:05 GMT
EXT:
LOCATION: http://192.168.132.2:12340/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: de208b1d-a1f0-43d1-816f-d7a87c66ddc9
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-2d4ac336-8683-3660-992a-d056b5382a8d::urn:Belkin:device:**


2016-12-24 09:20:05 fauxmo:191      DEBUG    Sending response to ('192.168.132.112', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Sat, 24 Dec 2016 17:20:05 GMT
EXT:
LOCATION: http://192.168.132.2:12341/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: cfd2d98c-0b0c-481b-bd68-3402745c9869
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-85a333cd-bad0-370b-b7d0-0d63a93d3c5c::urn:Belkin:device:**


2016-12-24 09:20:05 fauxmo:191      DEBUG    Sending response to ('192.168.132.112', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Sat, 24 Dec 2016 17:20:05 GMT
EXT:
LOCATION: http://192.168.132.2:12342/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: b3e72fc2-0527-4730-a541-970808fc0add
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-cbc4bc63-e0e2-3a78-8a9f-f0ff7e419b79::urn:Belkin:device:**


2016-12-24 09:20:05 fauxmo:159      DEBUG    Received data below from ('192.168.132.112', 50000):
2016-12-24 09:20:05 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 15\r\nST: urn:Belkin:device:**\r\n\r\n'
2016-12-24 09:20:05 fauxmo:191      DEBUG    Sending response to ('192.168.132.112', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Sat, 24 Dec 2016 17:20:05 GMT
EXT:
LOCATION: http://192.168.132.2:12340/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 0ff411e4-15b1-4651-a6a8-21eb15345360
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-2d4ac336-8683-3660-992a-d056b5382a8d::urn:Belkin:device:**


2016-12-24 09:20:05 fauxmo:191      DEBUG    Sending response to ('192.168.132.112', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Sat, 24 Dec 2016 17:20:05 GMT
EXT:
LOCATION: http://192.168.132.2:12341/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 2a832aef-ec62-45bd-9258-ed47a30d8f9f
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-85a333cd-bad0-370b-b7d0-0d63a93d3c5c::urn:Belkin:device:**


2016-12-24 09:20:05 fauxmo:191      DEBUG    Sending response to ('192.168.132.112', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Sat, 24 Dec 2016 17:20:05 GMT
EXT:
LOCATION: http://192.168.132.2:12342/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: b78f531d-9269-45a6-a89b-5fbf2ba0597f
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-cbc4bc63-e0e2-3a78-8a9f-f0ff7e419b79::urn:Belkin:device:**


2016-12-24 09:20:05 fauxmo:159      DEBUG    Received data below from ('192.168.132.112', 50000):
2016-12-24 09:20:05 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 15\r\nST: urn:schemas-upnp-org:device:basic:1\r\n\r\n'
2016-12-24 09:20:05 fauxmo:159      DEBUG    Received data below from ('192.168.132.112', 50000):
2016-12-24 09:20:05 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 15\r\nST: urn:schemas-upnp-org:device:basic:1\r\n\r\n'
^C2016-12-24 09:20:26 asyncio:1266     INFO     poll took 21246.405 ms: 1 events
2016-12-24 09:20:26 fauxmo:120      DEBUG    Shutdown starting...
2016-12-24 09:20:26 fauxmo:123      DEBUG    Shutting down server 0...
2016-12-24 09:20:26 fauxmo:123      DEBUG    Shutting down server 1...
2016-12-24 09:20:26 fauxmo:123      DEBUG    Shutting down server 2...

My config.json

{
    "FAUXMO": {
        "ip_address": "auto"
    },
    "DEVICES": [
        {
            "port": 12340,
            "handler": {
                "on_cmd": "http://192.168.1.104/myserver/fakeswitches/01/on",
                "off_cmd": "http://192.168.1.104/myserver/fakeswitches/01/off",
                "method": "GET"
            },
            "description": "fake switch one"
        },
        {
            "port": 12341,
            "handler": {
                "on_cmd": "http://localhost:54321/devices/garage%20light",
                "off_cmd": "http://localhost:54321/devices/garage%20light",
                "on_data": {"isOn": 1},
                "off_data": {"isOn": 0},
                "user": "this",
                "password": "that",
                "auth_type": "digest",
                "method": "PUT"
            },
            "description": "fake Indigo switch"
        },
        {
            "port": 12342,
            "handler": {
                "on_cmd": "http://192.168.1.104:8123/api/services/switch/turn_on",
                "off_cmd": "http://192.168.1.104:8123/api/services/switch/turn_off",
                "method": "POST",
                "headers": {"x-ha-access": "your_hass_password"},
                "on_json": {"entity_id": "switch.fake_hass_switch"},
                "off_json": {"entity_id": "switch.fake_hass_switch"}
            },
            "description": "fake hass switch by REST API"
        }
    ],
    "HOMEASSISTANT": {
        "enable": false,
        "host": "192.168.1.104",
        "port": 8123,
        "password": "your_hass_password",
        "DEVICES": [
            {
                "description": "hass switch by python API",
                "port": 12350,
                "entity_id": "switch.fake_hass_switch"
            }
        ]
    }
}
n8henrie commented

Huh. I assume you're continuing to run Fauxmo when you're checking for the discovered devices?

I have a Dot 2 now, I'll try to experiment this week to see if I can reproduce.

Thanks for the thorough and complete issue, including logs and config! Hopefully we'll get this sorted out.

n8henrie commented

@calikw -- did you see my Q above?

My Dot 2 finds my devices without issue. Have you tried re-discovery a few times? Occasionally it doesn't work the first time.

n8henrie commented

My Dot 2 is able to find the fake devices using the exact config-sample.json that you're using. I don't have any of the fake endpoints set up either, so that's not the problem. Let me know if you're still having this issue, otherwise I'll assume you figured it out and close the issue in about a week.

djseeds commented

Hi @n8henrie, I am having the same issue described above with my Echo Dot 2.

I am using the config-sample.json that you are using, and I can see the search request from my Echo and Fauxmo sends a response to the same address. I have tried discovering multiple times, and I leave Fauxmo running when I am checking for the devices.

Let me know if there is anything else I should try in order to get this working.

Python 3.5.2
Fauxmo 0.3.3?
Raspberry Pi running Raspbian Wheezy

n8henrie commented

Huh, I wonder what I'm missing here.

Are you guys sending me the debug output? -vvv?

Mine looks very similar, but after the last Sending response to DEBUG message, I get several in a row of:

2016-12-31 21:59:52 fauxmo:38       DEBUG    Connection made with: ('192.168.132.2', 12345)

then:

2016-12-31 21:59:52 fauxmo:48       DEBUG    setup.xml requested by Echo
2016-12-31 21:59:52 fauxmo:84       DEBUG    Fauxmo response to setup request:

and more stuff. Are you guys seeing that at all?

This is on Fauxmo 0.3.4, on Python 3.5.2, on my RPi 3 running Jessie, through my pretty new Dot 2.

djseeds commented

Here is my -vvv output. I have quite a few upnp devices, but it seems that the Echo is 10.0.0.38 and Fauxmo is running on 10.17.10.6.

2017-01-02 11:04:41 fauxmo:19       INFO     Fauxmo version 0.3.4
2017-01-02 11:04:43 fauxmo:39       DEBUG    3.5.2 (default, Dec 31 2016, 00:24:22) 
[GCC 4.6.3]
2017-01-02 11:04:43 fauxmo:46       INFO     Using config: ./config.json
2017-01-02 11:04:43 fauxmo:16       DEBUG    Attempting to get IP address automatically
2017-01-02 11:04:43 fauxmo:29       DEBUG    Using IP address: 10.17.10.6
2017-01-02 11:04:43 asyncio:979      INFO     <Server sockets=[<socket.socket fd=8, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('10.17.10.6', 12340)>]> is serving
2017-01-02 11:04:43 fauxmo:81       DEBUG    {'action_handler': <fauxmo.handlers.rest.RESTAPIHandler object at 0x75e05770>, 'name': 'fake switch one'}
2017-01-02 11:04:43 asyncio:979      INFO     <Server sockets=[<socket.socket fd=9, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('10.17.10.6', 12341)>]> is serving
2017-01-02 11:04:43 fauxmo:81       DEBUG    {'action_handler': <fauxmo.handlers.rest.RESTAPIHandler object at 0x75e05810>, 'name': 'fake Indigo switch'}
2017-01-02 11:04:43 asyncio:979      INFO     <Server sockets=[<socket.socket fd=10, family=AddressFamily.AF_INET, type=2049, proto=6, laddr=('10.17.10.6', 12342)>]> is serving
2017-01-02 11:04:43 fauxmo:81       DEBUG    {'action_handler': <fauxmo.handlers.rest.RESTAPIHandler object at 0x75e05790>, 'name': 'fake hass switch by REST API'}
2017-01-02 11:04:43 fauxmo:107      INFO     Starting UDP server
2017-01-02 11:04:43 asyncio:853      INFO     Datagram endpoint local_addr=('0.0.0.0', 1900) remote_addr=None created: (<_SelectorDatagramTransport fd=11 read=idle write=<idle, bufsize=0>>, <fauxmo.protocols.SSDPServer object at 0x75e05670>)
2017-01-02 11:04:45 asyncio:1266     INFO     poll took 1519.810 ms: 1 events
2017-01-02 11:04:45 fauxmo:159      DEBUG    Received data below from ('10.0.0.38', 50000):
2017-01-02 11:04:45 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 15\r\nST: urn:schemas-upnp-org:device:basic:1\r\n\r\n'
2017-01-02 11:04:45 fauxmo:159      DEBUG    Received data below from ('10.0.0.38', 50000):
2017-01-02 11:04:45 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 15\r\nST: urn:schemas-upnp-org:device:basic:1\r\n\r\n'
2017-01-02 11:04:45 fauxmo:159      DEBUG    Received data below from ('10.0.0.38', 50000):
2017-01-02 11:04:45 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 15\r\nST: urn:Belkin:device:**\r\n\r\n'
2017-01-02 11:04:45 fauxmo:191      DEBUG    Sending response to ('10.0.0.38', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Mon, 02 Jan 2017 16:04:45 GMT
EXT:
LOCATION: http://10.17.10.6:12340/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: f5cd5f5c-6bdf-4687-b1a5-f3dca4ebffc9
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-2d4ac336-8683-3660-992a-d056b5382a8d::urn:Belkin:device:**


2017-01-02 11:04:45 fauxmo:191      DEBUG    Sending response to ('10.0.0.38', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Mon, 02 Jan 2017 16:04:45 GMT
EXT:
LOCATION: http://10.17.10.6:12341/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: cd7308ae-7f86-4ee2-b829-d5db0524d6a7
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-85a333cd-bad0-370b-b7d0-0d63a93d3c5c::urn:Belkin:device:**


2017-01-02 11:04:45 fauxmo:191      DEBUG    Sending response to ('10.0.0.38', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Mon, 02 Jan 2017 16:04:45 GMT
EXT:
LOCATION: http://10.17.10.6:12342/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 47c02d76-94dc-4e71-ba79-2f402f6e84d5
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-cbc4bc63-e0e2-3a78-8a9f-f0ff7e419b79::urn:Belkin:device:**


2017-01-02 11:04:45 fauxmo:159      DEBUG    Received data below from ('10.0.0.38', 50000):
2017-01-02 11:04:45 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 15\r\nST: urn:Belkin:device:**\r\n\r\n'
2017-01-02 11:04:45 fauxmo:191      DEBUG    Sending response to ('10.0.0.38', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Mon, 02 Jan 2017 16:04:45 GMT
EXT:
LOCATION: http://10.17.10.6:12340/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 2f65045a-a280-4e02-8215-72291d41c1ad
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-2d4ac336-8683-3660-992a-d056b5382a8d::urn:Belkin:device:**


2017-01-02 11:04:45 fauxmo:191      DEBUG    Sending response to ('10.0.0.38', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Mon, 02 Jan 2017 16:04:45 GMT
EXT:
LOCATION: http://10.17.10.6:12341/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 4424a668-e46a-4517-bf95-ccc8e2c35f10
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-85a333cd-bad0-370b-b7d0-0d63a93d3c5c::urn:Belkin:device:**


2017-01-02 11:04:45 fauxmo:191      DEBUG    Sending response to ('10.0.0.38', 50000):
HTTP/1.1 200 OK
CACHE-CONTROL: max-age=86400
DATE: Mon, 02 Jan 2017 16:04:45 GMT
EXT:
LOCATION: http://10.17.10.6:12342/setup.xml
OPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01
01-NLS: 9c0dcb72-a67d-43c5-8979-d3148b5840c5
SERVER: Unspecified, UPnP/1.0, Unspecified
ST: urn:Belkin:device:**
USN: uuid:Socket-1_0-cbc4bc63-e0e2-3a78-8a9f-f0ff7e419b79::urn:Belkin:device:**


2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.37', 59004):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.37', 59004):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.205', 36873):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.205:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: upnp:rootdevice\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137EEE::upnp:rootdevice\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.205', 36873):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.205:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:InternetGatewayDevice:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137EEE::urn:schemas-upnp-org:device:InternetGatewayDevice:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.205', 36873):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.205:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:WANConnectionDevice:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137EEE::urn:schemas-upnp-org:device:WANConnectionDevice:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.205', 36873):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.205:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:WANDevice:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137EEE::urn:schemas-upnp-org:device:WANDevice:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.205', 36873):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.205:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137EEE::urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.205', 36873):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.205:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:service:WANIPConnection:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137EEE::urn:schemas-upnp-org:service:WANIPConnection:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.205', 36873):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.205:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:service:WANPPPConnection:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137EEE::urn:schemas-upnp-org:service:WANPPPConnection:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:46 fauxmo:159      DEBUG    Received data below from ('10.0.0.37', 59004):
2017-01-02 11:04:46 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:47 fauxmo:159      DEBUG    Received data below from ('10.0.0.229', 39474):
2017-01-02 11:04:47 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.229:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: upnp:rootdevice\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641138067::upnp:rootdevice\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:47 fauxmo:159      DEBUG    Received data below from ('10.0.0.229', 39474):
2017-01-02 11:04:47 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.229:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:InternetGatewayDevice:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641138067::urn:schemas-upnp-org:device:InternetGatewayDevice:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:47 fauxmo:159      DEBUG    Received data below from ('10.0.0.229', 39474):
2017-01-02 11:04:47 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.229:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:WANConnectionDevice:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641138067::urn:schemas-upnp-org:device:WANConnectionDevice:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:47 fauxmo:159      DEBUG    Received data below from ('10.0.0.229', 39474):
2017-01-02 11:04:47 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.229:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:WANDevice:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641138067::urn:schemas-upnp-org:device:WANDevice:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:47 fauxmo:159      DEBUG    Received data below from ('10.0.0.229', 39474):
2017-01-02 11:04:47 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.229:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641138067::urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:47 fauxmo:159      DEBUG    Received data below from ('10.0.0.229', 39474):
2017-01-02 11:04:47 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.229:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:service:WANIPConnection:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641138067::urn:schemas-upnp-org:service:WANIPConnection:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:47 fauxmo:159      DEBUG    Received data below from ('10.0.0.229', 39474):
2017-01-02 11:04:47 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.229:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:service:WANPPPConnection:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641138067::urn:schemas-upnp-org:service:WANPPPConnection:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:47 fauxmo:159      DEBUG    Received data below from ('10.0.0.243', 54280):
2017-01-02 11:04:47 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:47 fauxmo:159      DEBUG    Received data below from ('10.0.0.243', 54280):
2017-01-02 11:04:47 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:48 fauxmo:159      DEBUG    Received data below from ('10.0.0.243', 54280):
2017-01-02 11:04:48 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:48 fauxmo:159      DEBUG    Received data below from ('10.0.0.60', 35277):
2017-01-02 11:04:48 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nlOCATION: http://10.0.0.60:12040/rootDesc.xml\r\nSERVER:  UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:Basic:1\r\nNTS: ssdp:alive\r\nUSN: uuid:4b710320-451a-11e2-bcfd-0800200c9a66\r\n'
2017-01-02 11:04:48 fauxmo:159      DEBUG    Received data below from ('10.0.0.185', 56875):
2017-01-02 11:04:48 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHost: 239.255.255.250:1900\r\nST: upnp:rootdevice\r\nMan: "ssdp:discover"\r\nMX: 3\r\n\r\n'
2017-01-02 11:04:51 asyncio:1266     INFO     poll took 2994.735 ms: 1 events
2017-01-02 11:04:51 fauxmo:159      DEBUG    Received data below from ('10.0.0.185', 56875):
2017-01-02 11:04:51 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHost: 239.255.255.250:1900\r\nST: upnp:rootdevice\r\nMan: "ssdp:discover"\r\nMX: 3\r\n\r\n'
2017-01-02 11:04:52 fauxmo:159      DEBUG    Received data below from ('10.0.0.239', 52338):
2017-01-02 11:04:52 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.239:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: upnp:rootdevice\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137FC5::upnp:rootdevice\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:52 fauxmo:159      DEBUG    Received data below from ('10.0.0.239', 52338):
2017-01-02 11:04:52 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.239:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:InternetGatewayDevice:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137FC5::urn:schemas-upnp-org:device:InternetGatewayDevice:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:52 fauxmo:159      DEBUG    Received data below from ('10.0.0.239', 52338):
2017-01-02 11:04:52 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.239:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:WANConnectionDevice:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137FC5::urn:schemas-upnp-org:device:WANConnectionDevice:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:52 fauxmo:159      DEBUG    Received data below from ('10.0.0.239', 52338):
2017-01-02 11:04:52 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.239:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:WANDevice:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137FC5::urn:schemas-upnp-org:device:WANDevice:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:52 fauxmo:159      DEBUG    Received data below from ('10.0.0.239', 52338):
2017-01-02 11:04:52 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.239:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137FC5::urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:52 fauxmo:159      DEBUG    Received data below from ('10.0.0.239', 52338):
2017-01-02 11:04:52 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.239:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:service:WANIPConnection:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137FC5::urn:schemas-upnp-org:service:WANIPConnection:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:52 fauxmo:159      DEBUG    Received data below from ('10.0.0.239', 52338):
2017-01-02 11:04:52 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=60\r\nlOCATION: http://10.0.0.239:49152/rootDesc.xml\r\nSERVER: Linux 3.0.8 UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:service:WANPPPConnection:1\r\nUSN: uuid:Upnp-BasicDevice-1_0-BC6641137FC5::urn:schemas-upnp-org:service:WANPPPConnection:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n'
2017-01-02 11:04:54 asyncio:1266     INFO     poll took 2087.614 ms: 1 events
2017-01-02 11:04:54 fauxmo:159      DEBUG    Received data below from ('10.0.0.60', 51089):
2017-01-02 11:04:54 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nlOCATION: http://10.0.0.60:12040/rootDesc.xml\r\nSERVER:  UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:Basic:1\r\nNTS: ssdp:alive\r\nUSN: uuid:4b710320-451a-11e2-bcfd-0800200c9a66\r\n'
2017-01-02 11:04:54 fauxmo:159      DEBUG    Received data below from ('10.0.0.185', 56875):
2017-01-02 11:04:54 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHost: 239.255.255.250:1900\r\nST: upnp:rootdevice\r\nMan: "ssdp:discover"\r\nMX: 3\r\n\r\n'
2017-01-02 11:04:56 asyncio:1266     INFO     poll took 1910.604 ms: 1 events
2017-01-02 11:04:56 fauxmo:159      DEBUG    Received data below from ('10.0.0.37', 59004):
2017-01-02 11:04:56 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:56 fauxmo:159      DEBUG    Received data below from ('10.0.0.37', 59004):
2017-01-02 11:04:56 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:56 fauxmo:159      DEBUG    Received data below from ('10.0.0.37', 59004):
2017-01-02 11:04:56 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:57 asyncio:1266     INFO     poll took 1306.435 ms: 1 events
2017-01-02 11:04:57 fauxmo:159      DEBUG    Received data below from ('10.0.0.243', 54280):
2017-01-02 11:04:57 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:57 fauxmo:159      DEBUG    Received data below from ('10.0.0.243', 54280):
2017-01-02 11:04:57 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:58 fauxmo:159      DEBUG    Received data below from ('10.0.0.243', 54280):
2017-01-02 11:04:58 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nMAN: "ssdp:discover"\r\nMX: 1\r\nST: urn:dial-multiscreen-org:service:dial:1\r\n\r\n'
2017-01-02 11:04:58 fauxmo:159      DEBUG    Received data below from ('10.0.0.185', 56875):
2017-01-02 11:04:58 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHost: 239.255.255.250:1900\r\nST: upnp:rootdevice\r\nMan: "ssdp:discover"\r\nMX: 3\r\n\r\n'
2017-01-02 11:05:00 asyncio:1266     INFO     poll took 1857.111 ms: 1 events
2017-01-02 11:05:00 fauxmo:159      DEBUG    Received data below from ('10.0.0.60', 40886):
2017-01-02 11:05:00 fauxmo:160      DEBUG    b'NOTIFY * HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=1800\r\nlOCATION: http://10.0.0.60:12040/rootDesc.xml\r\nSERVER:  UPnP/1.1 MiniUPnPd/1.7\r\nNT: urn:schemas-upnp-org:device:Basic:1\r\nNTS: ssdp:alive\r\nUSN: uuid:4b710320-451a-11e2-bcfd-0800200c9a66\r\n'
2017-01-02 11:05:01 asyncio:1266     INFO     poll took 1125.369 ms: 1 events
2017-01-02 11:05:01 fauxmo:159      DEBUG    Received data below from ('10.0.0.185', 56875):
2017-01-02 11:05:01 fauxmo:160      DEBUG    b'M-SEARCH * HTTP/1.1\r\nHost: 239.255.255.250:1900\r\nST: upnp:rootdevice\r\nMan: "ssdp:discover"\r\nMX: 3\r\n\r\n'
^C2017-01-02 11:05:03 asyncio:1266     INFO     poll took 1899.734 ms: 1 events
2017-01-02 11:05:03 fauxmo:120      DEBUG    Shutdown starting...
2017-01-02 11:05:03 fauxmo:123      DEBUG    Shutting down server 0...
2017-01-02 11:05:03 fauxmo:123      DEBUG    Shutting down server 1...
2017-01-02 11:05:03 fauxmo:123      DEBUG    Shutting down server 2...
djseeds commented

Just to be sure, my Echo Dot 2 is running software version 564196920 (latest).

djseeds commented

I downloaded Fauxmo to my Mac and I was able to successfully pair. Might this be a Linux issue, then?

calikw commented

Whoa! I thought I had this thread sending me email updates... Sorry for missing all this wonderful follow-up @n8henrie & @djseeds! Will check on all the above today and post an update with answers to the above questions...

(happy new year!)

calikw commented

Okay. I just figured it out and I'll officially admit I'm an idiot here :) That would explain why the logging didn't show any real activity too, I suppose.

The default firewall UFW was blocking any access to/from the Amazon Echo. After I disabled the firewall, all of the dummy devices were discovered. I'll add a few exceptions to the FW, re-enable it, and then we should be back in business.

Thanks @n8henrie!

n8henrie commented

@calikw -- Ha, I should have thought of that. I'll add to the FAQ. If you have a chance, can you check and see if UDP traffic on 1900 is sufficient for device discovery? (Obviously various ports and protocols would be required for respective devices, but I think UDP 1900 should be enough for discovery).

@djseeds -- any chance you're facing a similar issue? Are you sure that at minimum UDP 1900 is open on your Ubuntu box? Shouldn't be a general Linux issue -- to be honest, I have it running on my RPi and couldn't get it to run on my MBA when tinkering on this issue yesterday (I think due to a port reuse issue).

djseeds commented

@n8henrie, it looks like my issue was that device discovery was being routed through my VPN. After disabling the VPN Fauxmo worked like a charm.

Do you know of any way to run Fauxmo with a VPN active?

n8henrie commented

@djseeds Have you tried this? #1

djseeds commented

@n8henrie - I'm not sure that #1 is my issue, as the script runs fine, but it just never reaches the "connection made" stage with the Echo

djseeds commented

@n8henrie I tried out the fix for #1 anyways, and it worked. Thanks for your help!

  • 👍1