[2.4, devel] INTERNAL_SERVER_ERROR While commissioning/testing

[Andres Rodriguez]

A lot of INTERNAL SERVER ERROR messages on regiond.log while commissioning/testing machines:

-> 5001).
2018-02-08 00:21:21 regiond: [info] 10.245.136.31 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 200 OK (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:21 regiond: [info] 10.245.136.31 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 200 OK (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:21 regiond: [info] 10.245.136.31 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 200 OK (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:21 regiond: [info] 10.245.136.31 GET /MAAS/metadata/2012-03-01/maas-scripts/ HTTP/1.1 --> 200 OK (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:22 regiond: [info] 10.245.136.31 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 200 OK (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:22 regiond: [info] 10.245.136.31 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 200 OK (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:22 regiond: [info] 10.245.136.25 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 500 INTERNAL_SERVER_ERROR (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:22 regiond: [info] 10.245.136.25 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 500 INTERNAL_SERVER_ERROR (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:24 regiond: [info] 10.245.136.25 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 200 OK (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:24 regiond: [info] 10.245.136.25 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 500 INTERNAL_SERVER_ERROR (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:24 regiond: [info] 10.245.136.25 POST /MAAS/metadata/2012-03-01/ HTTP/1.1 --> 500 INTERNAL_SERVER_ERROR (referrer: -; agent: Python-urllib/3.5)
2018-02-08 00:21:24 maasserver.dhcp: [info] Successfully configured DHCPv4 on rack controller 'mx4br7'.
2018-02-08 00:21:24 maasserver.dhcp: [info] Successfully configured DHCPv6 on rack controller 'mx4br7'.
2018-02-08 00:21:24 maasserver.region_controller: [info] Reloaded DNS configuration:
         * node warm-urchin renamed interface eth3 to eno4
         * node warm-urchin renamed interface eth2 to eno3
         * ip 10.245.136.41 connected to warm-urchin on eno2
         * ip 10.245.136.41 disconnected from warm-urchin on eno2
         * node warm-urchin renamed interface eth1 to eno2
         * ip 10.245.136.24 connected to warm-urchin on eno1
         * ip 10.245.136.24 disconnected from warm-urchin on eno1

The machines also show this logs:

Feb 8 00:21:52 wired-foal sudo: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 8 00:21:53 wired-foal sudo: pam_unix(sudo:session): session closed for user root
Feb 8 00:21:53 wired-foal cloud-init[2619]: Finished smartctl-validate (id: 241, script_version_id: 1): 0
Feb 8 00:21:53 wired-foal cloud-init[2619]: All scripts successfully ran
Feb 8 00:21:53 wired-foal cloud-init[2619]: request to http://10.245.136.6:5240/MAAS/metadata//2012-03-01/ failed. sleeping 1.: HTTP Error 500: Internal Server Error
Feb 8 00:21:53 wired-foal cloud-init[2619]: message repeated 3 times: [ request to http://10.245.136.6:5240/MAAS/metadata//2012-03-01/ failed. sleeping 1.: HTTP Error 500: Internal Server Error]

Feb 8 00:21:40 warm-gopher cloud-init[2805]: Finished smartctl-validate (id: 235, script_version_id: 1): 0
Feb 8 00:21:41 warm-gopher dhclient[4899]: DHCPDISCOVER on eno3 to 255.255.255.255 port 67 interval 9 (xid=0xbfeed239)
Feb 8 00:21:42 warm-gopher cloud-init[2805]: All scripts successfully ran
Feb 8 00:21:42 warm-gopher cloud-init[2805]: request to http://10.245.136.6:5240/MAAS/metadata//2012-03-01/ failed. sleeping 1.: HTTP Error 500: Internal Server Error
Feb 8 00:21:42 warm-gopher cloud-init[2805]: message repeated 2 times: [ request to http://10.245.136.6:5240/MAAS/metadata//2012-03-01/ failed. sleeping 1.: HTTP Error 500: Internal Server Error]