Very high CPU load for 5 minutes after start

[H. Lekin]

0.2-1build1 on Xubuntu 12.04 amd64 + AVM Fritz!Box Fon WLAN 7270 v3

For the first 5 minutes after start, UPnP Router Control uses 100% of one virtual CPU. Afterwards, CPU load drops close to 0%.

~$ upnp-router-control --debug
* Initializing GUI...
* Showing GUI...
* Starting UPnP Resource discovery... done
==> Device Available: FRITZ!Box Fon WLAN 7270 v3
   Model description: FRITZ!Box Fon WLAN 7270 v3
          Model name: FRITZ!Box Fon WLAN 7270 v3
        Model number: 1und1
               Brand: AVM Berlin
    Presentation URL: http://fritz.box/
                 UPC: (null)
  Unique Device Name: uuid:xxx
            Icon URL: http://xxx:49000/ligd.gif
      Icon mime/type: image/gif
          Icon depth: 8
          Icon width: 118
         Icon height: 119
    Enum services...
      Service: urn:any-com:serviceId:any1
         Type: urn:schemas-any-com:service:Any:1
    Enum sub-devices...
      Sub-Device: WANDevice - FRITZ!Box Fon WLAN 7270 v3
        Enum services...
          Service: urn:upnp-org:serviceId:WANCommonIFC1
             Type: urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1
                   > GetCommonLinkProperties
                   > GetTotalBytesSent
                   > GetTotalBytesReceived
                   > GetTotalPacketsSent
                   > GetTotalPacketsReceived
                   > GetAddonInfos
        Enum sub-devices...
          Sub-Device: WANConnectionDevice - FRITZ!Box Fon WLAN 7270 v3
            Enum services...
              Service: urn:upnp-org:serviceId:WANIPConn1
                 Type: urn:schemas-upnp-org:service:WANIPConnection:1
                       > SetConnectionType
                       > GetConnectionTypeInfo
                       > GetStatusInfo
                       > GetNATRSIPStatus
                       > GetGenericPortMappingEntry
                       > GetSpecificPortMappingEntry
                       > AddPortMapping
                       > DeletePortMapping
                       > GetExternalIPAddress
              ** Subscribed to WANIPConn1 events
Request for external IP address... successful [xxx]
Request for connection status info... successful
Connection info: Status: Connected, Uptime: 49606 sec.
Request for NAT and RSIP availability... successful [RSIP=no, NAT=yes]
              Service: urn:upnp-org:serviceId:WANDSLLinkC1
                 Type: urn:schemas-upnp-org:service:WANDSLLinkConfig:1
                       > SetDSLLinkType
                       > GetDSLLinkInfo
                       > GetAutoConfig
                       > GetModulationType
                       > SetDestinationAddress
                       > GetDestinationAddress
                       > SetATMEncapsulation
                       > GetATMEncapsulation
                       > SetFCSPreserved
                       > GetFCSPreserved
GetTotalBytesReceived() duration: 1,016964s
GetTotalBytesSent() duration: 1,017130s
GetTotalBytesReceived() duration: 1,014638s
GetTotalBytesSent() duration: 1,015686s
GetTotalBytesReceived() duration: 1,015235s
GetTotalBytesSent() duration: 1,017103s
GetTotalBytesReceived() duration: 1,016590s
GetTotalBytesSent() duration: 1,018652s
==> Getting mapped ports list...
GetTotalBytesReceived() duration: 1,020326s
GetTotalBytesSent() duration: 1,014405s
Request for connection status info... successful
Connection info: Status: Connected, Uptime: 49612 sec.
GetTotalBytesReceived() duration: 1,019480s
GetTotalBytesSent() duration: 1,014462s
Request for external IP address... successful [xxx]
GetTotalBytesReceived() duration: 1,017104s
GetTotalBytesSent() duration: 1,026033s
GetTotalBytesReceived() duration: 1,036153s
GetTotalBytesSent() duration: 1,020291s
GetTotalBytesReceived() duration: 1,021105s
GetTotalBytesSent() duration: 1,017658s
GetTotalBytesReceived() duration: 1,018173s
GetTotalBytesSent() duration: 1,018203s
GetTotalBytesReceived() duration: 1,017611s
GetTotalBytesSent() duration: 1,022101s
GetTotalBytesReceived() duration: 1,042424s
GetTotalBytesSent() duration: 1,013692s
GetTotalBytesReceived() duration: 1,017943s
GetTotalBytesSent() duration: 1,015869s
GetTotalBytesReceived() duration: 1,027699s
GetTotalBytesSent() duration: 1,029156s
==> Getting mapped ports list...
GetTotalBytesReceived() duration: 1,077656s
GetTotalBytesSent() duration: 1,024144s
Request for connection status info... successful
Connection info: Status: Connected, Uptime: 49622 sec.
GetTotalBytesReceived() duration: 1,014619s
GetTotalBytesSent() duration: 1,020357s
Request for external IP address... successful [xxx]
GetTotalBytesReceived() duration: 1,017411s
GetTotalBytesSent() duration: 1,018090s
GetTotalBytesReceived() duration: 1,015346s
GetTotalBytesSent() duration: 1,015549s
GetTotalBytesReceived() duration: 1,019106s
GetTotalBytesSent() duration: 1,016202s
GetTotalBytesReceived() duration: 1,015310s
GetTotalBytesSent() duration: 1,026440s
GetTotalBytesReceived() duration: 1,016726s
GetTotalBytesSent() duration: 1,018026s
GetTotalBytesReceived() duration: 1,015359s
GetTotalBytesSent() duration: 1,015267s
GetTotalBytesReceived() duration: 1,015043s
GetTotalBytesSent() duration: 1,013830s
GetTotalBytesReceived() duration: 1,015483s
==> Getting mapped ports list...
GetTotalBytesSent() duration: 1,032371s
Request for connection status info... successful
Connection info: Status: Connected, Uptime: 49632 sec.
GetTotalBytesReceived() duration: 1,017600s
GetTotalBytesSent() duration: 1,018325s
Request for external IP address... successful [xxx]
GetTotalBytesReceived() duration: 1,018612s
GetTotalBytesSent() duration: 1,014891s
GetTotalBytesReceived() duration: 1,027499s
GetTotalBytesSent() duration: 1,018165s
GetTotalBytesReceived() duration: 1,015622s
GetTotalBytesSent() duration: 1,016540s
GetTotalBytesReceived() duration: 1,017580s
GetTotalBytesSent() duration: 1,018586s
GetTotalBytesReceived() duration: 1,019204s
GetTotalBytesSent() duration: 1,015107s
GetTotalBytesReceived() duration: 1,015634s
GetTotalBytesSent() duration: 1,017582s
GetTotalBytesReceived() duration: 1,031910s
GetTotalBytesSent() duration: 1,027248s
GetTotalBytesReceived() duration: 1,034252s
GetTotalBytesSent() duration: 1,018933s

[Daniele Napolitano (dnax88)]

Thank you for reporting this bug.

Why your router report icon at http://xxx:49000/ligd.gif (should be local gateway URL) and external IP xxx? Is a privacy feature of your router?

[H. Lekin (hael)]

Thank you for making upnp-router-control publicly available.

No privacy feature, I did this: I decided to remove the Unique Device Name from the public bug report as it contains the MAC address, and went on to remove the IPs as well...

The CPU load drops from 100% [of 1 virtual CPU] to nearly 0% after *exactly* 5 minutes, reproducibly.

I just remembered that - about two years ago - running one of either upnp-inspector or gupnp-tools on Ubuntu 10.04 led to a very high CPU load as well; the other package didn't show this behavior.

If you wish, I can send you something like strace output.

[Daniele Napolitano (dnax88)]

Possible the same of this bug https://gitlab.gnome.org/GNOME/gupnp/-/issues/8