Project

General

Profile

Bug #19599 » proxy.log

Dirk Mayer, 05/22/2017 05:30 AM

 
# Logfile created on 2017-05-22 11:19:04 +0200 by logger.rb/41954
D, [2017-05-22T11:19:04.145752 ] DEBUG -- : 'dns' settings: 'dns_ttl': 86400 (default), 'enabled': https, 'use_provider': dns_nsupdate (default)
D, [2017-05-22T11:19:04.147989 ] DEBUG -- : 'tftp' settings: 'enabled': https, 'tftp_servername': 172.17.31.210, 'tftproot': /var/lib/tftpboot (default)
D, [2017-05-22T11:19:04.152204 ] DEBUG -- : 'dhcp' settings: 'enabled': https, 'server': 127.0.0.1 (default), 'subnets': [] (default), 'use_provider': dhcp_isc (default)
D, [2017-05-22T11:19:04.154121 ] DEBUG -- : 'puppetca' settings: 'enabled': https, 'puppetdir': /etc/puppetlabs/puppet, 'ssldir': /etc/puppetlabs/puppet/ssl
D, [2017-05-22T11:19:04.157673 ] DEBUG -- : 'puppet' settings: 'enabled': https, 'puppet_version': 4.10.1, 'use_provider': ["puppet_proxy_ssh", :puppet_proxy_puppet_api]
D, [2017-05-22T11:19:04.159800 ] DEBUG -- : 'logs' settings: 'enabled': https
D, [2017-05-22T11:19:04.160155 ] DEBUG -- : Providers ['dns_nsupdate'] are going to be configured for 'dns'
D, [2017-05-22T11:19:04.160224 ] DEBUG -- : Providers ['dhcp_isc'] are going to be configured for 'dhcp'
D, [2017-05-22T11:19:04.160284 ] DEBUG -- : Providers ['puppet_proxy_ssh', 'puppet_proxy_puppet_api'] are going to be configured for 'puppet'
D, [2017-05-22T11:19:04.162575 ] DEBUG -- : 'dns_nsupdate' settings: 'dns_key': /etc/rndc.key, 'dns_server': 127.0.0.1, 'dns_ttl': 86400, 'use_provider': dns_nsupdate
D, [2017-05-22T11:19:04.174277 ] DEBUG -- : 'dhcp_isc' settings: 'config': /etc/dhcp/dhcpd.conf (default), 'leases': /var/lib/dhcpd/dhcpd.leases (default), 'leases_file_observer': inotify_leases_file_observer, 'omapi_port': 7911, 'server': 127.0.0.1, 'subnets': [], 'use_provider': dhcp_isc
D, [2017-05-22T11:19:04.246115 ] DEBUG -- : 'puppet_proxy_puppet_api' settings: 'api_timeout': 30 (default), 'classes_retriever': apiv3, 'environments_retriever': apiv3, 'puppet_ssl_ca': /etc/puppetlabs/puppet/ssl/certs/ca.pem, 'puppet_ssl_cert': /etc/puppetlabs/puppet/ssl/certs/m740-tk.localdomain.pem, 'puppet_ssl_key': /etc/puppetlabs/puppet/ssl/private_keys/m740-tk.localdomain.pem, 'puppet_url': https://m740-tk.localdomain:8140, 'puppet_version': 4.10.1, 'use_provider': ["puppet_proxy_ssh", :puppet_proxy_puppet_api]
D, [2017-05-22T11:19:04.249678 ] DEBUG -- : 'puppet_proxy_ssh' settings: 'command': puppet.bat agent --onetime --no-usecacheonfailure, 'keyfile': /usr/share/foreman-proxy/.ssh/id_rsa, 'puppet_version': 4.10.1, 'use_provider': ["puppet_proxy_ssh", :puppet_proxy_puppet_api], 'use_sudo': false (default), 'user': txpadmin, 'wait': false (default)
I, [2017-05-22T11:19:04.250045 ] INFO -- : Successfully initialized 'foreman_proxy'
I, [2017-05-22T11:19:04.250116 ] INFO -- : Successfully initialized 'dns_nsupdate'
I, [2017-05-22T11:19:04.250162 ] INFO -- : Successfully initialized 'dns'
I, [2017-05-22T11:19:04.250202 ] INFO -- : Successfully initialized 'tftp'
D, [2017-05-22T11:19:04.267178 ] DEBUG -- : Added a subnet: 172.17.16.0
D, [2017-05-22T11:19:04.268597 ] DEBUG -- : Added a reservation: 172.17.31.216:a0:36:9f:d5:51:6a:tc01-tk.localdomain
D, [2017-05-22T11:19:04.268672 ] DEBUG -- : Added a reservation: 172.17.31.211:a0:36:9f:a2:6b:da:node0.localdomain
D, [2017-05-22T11:19:04.268730 ] DEBUG -- : Added a reservation: 172.17.31.214:52:54:00:13:3d:ae:r80-tk.localdomain
D, [2017-05-22T11:19:04.268774 ] DEBUG -- : Added a reservation: 172.17.31.215:52:54:00:52:bb:16:tecsrv-tk.localdomain
D, [2017-05-22T11:19:04.268814 ] DEBUG -- : Added a reservation: 172.17.31.212:a0:36:9f:a2:6c:ce:node1.localdomain
D, [2017-05-22T11:19:04.268854 ] DEBUG -- : Added a reservation: 172.17.31.219:a0:36:9f:c0:7b:ae:kate-ference.localdomain
D, [2017-05-22T11:19:04.268903 ] DEBUG -- : Deleted a reservation: 172.17.31.219:a0:36:9f:c0:7b:ae:kate-ference.localdomain
I, [2017-05-22T11:19:04.269044 ] INFO -- : Successfully initialized 'dhcp_isc'
I, [2017-05-22T11:19:04.269245 ] INFO -- : Successfully initialized 'dhcp'
I, [2017-05-22T11:19:04.269292 ] INFO -- : Successfully initialized 'puppetca'
I, [2017-05-22T11:19:04.269431 ] INFO -- : Started puppet class cache initialization
I, [2017-05-22T11:19:04.278311 ] INFO -- : Successfully initialized 'puppet_proxy_puppet_api'
I, [2017-05-22T11:19:04.278968 ] INFO -- : Successfully initialized 'puppet_proxy_ssh'
I, [2017-05-22T11:19:04.279046 ] INFO -- : Successfully initialized 'puppet'
D, [2017-05-22T11:19:04.279118 ] DEBUG -- : Log buffer API initialized, available capacity: 2000/1000
I, [2017-05-22T11:19:04.279344 ] INFO -- : Successfully initialized 'logs'
W, [2017-05-22T11:19:04.282201 ] WARN -- : Failed to initialize puppet class cache, deferring initialization. Is puppetserver running?
I, [2017-05-22T11:19:04.288652 ] INFO -- : WEBrick 1.3.1
I, [2017-05-22T11:19:04.288752 ] INFO -- : ruby 2.0.0 (2015-12-16) [x86_64-linux]
D, [2017-05-22T11:19:04.289491 ] DEBUG -- : TCPServer.new(0.0.0.0, 8443)
D, [2017-05-22T11:19:04.289803 ] DEBUG -- : TCPServer.new(::, 8443)
W, [2017-05-22T11:19:04.289896 ] WARN -- : TCPServer Error: Address already in use - bind(2)
I, [2017-05-22T11:19:04.290737 ] INFO -- :
Certificate:
Data:
Version: 3 (0x2)
Serial Number: 2 (0x2)
Signature Algorithm: sha256WithRSAEncryption
Issuer: CN=Puppet CA: m740-tk.localdomain
Validity
Not Before: May 16 09:31:42 2017 GMT
Not After : May 16 09:31:42 2022 GMT
Subject: CN=m740-tk.localdomain
Subject Public Key Info:
Public Key Algorithm: rsaEncryption
Public-Key: (4096 bit)
Modulus:
00:c0:36:44:d5:92:d7:04:4f:b2:11:de:13:23:b4:
9a:70:f7:c9:8f:81:50:2a:1c:3c:38:75:6b:c8:94:
60:7e:d5:1b:94:59:a5:56:ef:65:ab:18:ba:4a:7c:
fc:59:b2:dd:a2:3f:c9:5e:01:61:57:5d:fa:82:9b:
7e:6a:23:0e:56:79:e0:1a:52:41:e3:ba:9a:f4:76:
0b:dd:dd:1c:fa:ff:ea:90:8d:eb:1d:c4:6f:2e:6d:
6b:df:6e:71:3f:43:2b:3a:3d:79:53:ce:36:12:3e:
96:1e:ce:26:41:1d:b3:92:40:bc:d8:41:c3:b5:24:
cf:4d:2d:40:61:2a:29:56:b3:91:1b:fc:26:41:5c:
9f:70:09:2d:f6:5f:e8:82:14:25:b4:a3:ef:07:d9:
9b:df:e5:e4:e6:86:4d:70:72:1f:d2:f8:98:70:47:
72:76:37:e9:87:a6:c2:f2:19:04:58:dd:63:11:d8:
32:f7:39:35:7e:4a:dc:81:15:24:4a:bd:c0:aa:55:
b5:95:35:f3:73:66:5c:91:96:8b:af:ba:96:4b:9b:
44:3e:95:b6:2e:aa:92:77:a1:b1:b4:f5:41:1f:90:
20:bb:54:11:f1:14:5c:e7:88:46:c4:ca:82:78:18:
73:ba:c2:ef:11:e7:12:9f:c9:17:d7:86:86:1c:86:
a1:45:14:4d:83:46:ea:ef:93:04:80:4b:8e:fa:20:
20:9c:b1:0c:11:50:be:d0:09:ea:05:5d:0c:1d:2c:
d9:5e:3a:17:3a:fb:1a:0c:ab:20:ef:de:ab:90:4f:
52:a8:39:2e:22:a5:91:33:9e:92:b8:6d:e3:e9:6a:
5d:a1:28:43:12:b2:f3:4e:9a:8e:02:46:17:26:1c:
f5:f4:19:c2:56:b7:2d:1d:9a:8f:37:a6:3d:e0:39:
a5:6b:fe:33:9b:da:50:3b:d1:14:8f:16:da:18:15:
7c:50:29:d2:5d:a4:3c:1c:2b:a8:bc:e6:a8:05:72:
33:97:7d:f3:93:6f:88:0c:6a:bf:2b:62:de:72:0c:
14:cd:a5:90:e1:a1:8f:1f:65:b1:80:97:43:98:83:
ed:c0:04:5b:5c:71:aa:a6:a8:d6:f0:a6:52:b6:e0:
a5:1d:b8:94:3e:05:86:4e:03:55:c2:d4:8c:3d:a3:
b5:db:f5:fe:c7:11:9b:3c:c7:ca:7f:94:87:88:f1:
15:15:bd:50:23:0e:2a:fc:4e:c4:bf:21:89:3d:d1:
6c:0e:8e:6c:bf:56:48:73:cd:ff:91:d5:e2:8b:ba:
35:75:f1:47:94:6a:d4:a9:3f:18:c8:8f:07:7c:fb:
bc:62:74:51:ed:a7:f8:4a:b2:85:4c:e3:31:ac:a1:
8e:21:47
Exponent: 65537 (0x10001)
X509v3 extensions:
Netscape Comment:
.(Puppet Ruby/OpenSSL Internal Certificate
X509v3 Subject Alternative Name:
DNS:m740-tk.localdomain, DNS:puppet, DNS:puppet.localdomain
X509v3 Key Usage: critical
Digital Signature, Key Encipherment
X509v3 Extended Key Usage: critical
TLS Web Server Authentication, TLS Web Client Authentication
X509v3 Basic Constraints: critical
CA:FALSE
X509v3 Subject Key Identifier:
30:6F:2F:0F:6B:35:03:E3:F2:4A:FB:09:0D:02:6F:C7:3A:41:50:D8
X509v3 Authority Key Identifier:
keyid:CD:75:04:5C:40:7D:B7:4B:27:AA:EE:76:D0:1B:43:79:2F:EA:0F:E4

Signature Algorithm: sha256WithRSAEncryption
76:95:0c:b5:f9:7d:06:f5:f7:cb:fc:3f:59:e6:a0:11:a9:48:
1f:67:65:11:39:57:74:7e:85:ef:fd:de:2f:61:f8:05:f8:fb:
5e:e0:46:7e:fc:04:69:d6:b4:a3:b9:f8:c5:5b:53:b2:2e:1e:
82:02:92:0e:2c:73:87:e6:33:6d:76:f8:76:2b:91:b1:b8:e7:
e0:61:69:71:c7:4a:78:77:70:18:28:46:44:c3:1a:2f:f0:3d:
c9:7b:92:25:05:59:33:bf:36:a6:6a:ca:0b:19:e5:7e:61:7c:
8f:16:0d:48:ff:05:77:12:01:3d:1f:3c:44:5e:12:78:76:af:
37:f6:88:e3:f3:a6:54:84:6f:ae:9a:0c:56:da:58:a8:f7:f2:
f3:7e:00:3d:46:cc:c4:1e:77:8c:ad:7a:dc:44:82:e2:54:5b:
1c:2c:b8:92:7a:e0:f4:07:00:1a:b4:71:a0:4b:5f:94:d2:0c:
94:50:b6:45:fc:1a:5a:b6:8a:c8:82:f5:09:d9:0a:b1:dc:0e:
41:87:d5:91:62:0f:c3:99:e2:e6:4a:55:3b:ce:0f:07:05:44:
6f:8b:e4:dc:cc:db:43:68:29:e8:2a:b3:93:e3:4d:cf:7e:cb:
45:7e:29:09:2d:3b:93:5b:62:34:84:e7:f4:22:1d:8e:a4:17:
17:38:8f:a0:0c:3f:31:3b:68:b5:86:8e:54:11:81:99:2c:1f:
50:d0:28:cc:60:a7:1e:b0:54:01:8f:92:59:d2:d7:a3:ed:78:
75:3a:14:a7:be:0b:84:de:b9:3c:36:88:40:9f:11:03:c6:95:
69:2d:9f:00:1c:f5:60:13:17:43:c2:af:ba:cc:dd:37:59:7f:
0a:69:e5:ea:75:26:ae:a7:cb:9c:a1:40:11:38:af:79:a6:6e:
49:4f:49:4d:8a:6b:33:84:e2:52:94:14:c2:71:5e:2b:bf:c1:
e1:c1:54:df:36:82:86:83:e9:ea:f9:95:39:e6:94:a3:99:d2:
9e:ec:eb:67:6d:60:f3:48:a6:61:66:7b:2c:b4:52:a1:3c:74:
fc:48:70:b4:69:38:5e:1a:b9:c7:80:8a:25:2e:04:fc:56:cc:
11:c2:53:01:01:16:71:e6:3b:36:7b:81:93:12:04:8b:11:20:
bb:94:94:4c:8b:bd:92:d0:5a:ee:42:b4:57:66:84:22:6e:7e:
3a:da:c6:9e:7c:01:94:dd:f4:26:55:0e:74:cc:c3:01:f3:57:
6e:80:06:f9:88:92:2d:3d:e4:4a:9b:a8:7a:be:cf:54:31:f8:
be:10:ec:c3:9e:fd:1e:73:05:e7:53:bc:1e:9c:d5:81:2b:54:
bf:4e:fb:a6:ad:7d:06:1f

D, [2017-05-22T11:19:04.292777 ] DEBUG -- : Rack::Handler::WEBrick is mounted on /.
I, [2017-05-22T11:19:04.292903 ] INFO -- : WEBrick::HTTPServer#start: pid=2295 port=8443
D, [2017-05-22T11:19:13.442280 ] DEBUG -- : caught :moved_to event on /var/lib/dhcpd/dhcpd.leases.
D, [2017-05-22T11:19:13.443371 ] DEBUG -- : Added a subnet: 172.17.16.0
D, [2017-05-22T11:19:13.444374 ] DEBUG -- : Added a reservation: 172.17.31.216:a0:36:9f:d5:51:6a:tc01-tk.localdomain
D, [2017-05-22T11:19:13.444450 ] DEBUG -- : Added a reservation: 172.17.31.211:a0:36:9f:a2:6b:da:node0.localdomain
D, [2017-05-22T11:19:13.444496 ] DEBUG -- : Added a reservation: 172.17.31.214:52:54:00:13:3d:ae:r80-tk.localdomain
D, [2017-05-22T11:19:13.444537 ] DEBUG -- : Added a reservation: 172.17.31.215:52:54:00:52:bb:16:tecsrv-tk.localdomain
D, [2017-05-22T11:19:13.444576 ] DEBUG -- : Added a reservation: 172.17.31.212:a0:36:9f:a2:6c:ce:node1.localdomain
D, [2017-05-22T11:20:30.111183 ] DEBUG -- : accept: 172.17.31.210:49920
D, [2017-05-22T11:20:30.113063 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:20:30.597228 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:20:30.608421 ] DEBUG -- : trying to find an ip address, we got {:from=>"172.17.31.211", :to=>"172.17.31.219"}
D, [2017-05-22T11:20:30.627031 ] DEBUG -- : Searching for free IP - pinging 172.17.31.213
D, [2017-05-22T11:20:30.632829 ] DEBUG -- : Found a pingable IP(172.17.31.213) address which does not have a Proxy::DHCP record
D, [2017-05-22T11:20:30.632917 ] DEBUG -- : Searching for free IP - pinging 172.17.31.217
D, [2017-05-22T11:20:30.633373 ] DEBUG -- : Found a pingable IP(172.17.31.217) address which does not have a Proxy::DHCP record
D, [2017-05-22T11:20:30.633456 ] DEBUG -- : Searching for free IP - pinging 172.17.31.218
D, [2017-05-22T11:20:30.633847 ] DEBUG -- : Found a pingable IP(172.17.31.218) address which does not have a Proxy::DHCP record
D, [2017-05-22T11:20:30.633923 ] DEBUG -- : Searching for free IP - pinging 172.17.31.219
D, [2017-05-22T11:20:32.704541 ] DEBUG -- : Found free IP 172.17.31.219 out of a total of 4 free IPs
I, [2017-05-22T11:20:32.759506 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:20:32 +0200] "GET /dhcp/172.17.16.0/unused_ip?from=172.17.31.211&to=172.17.31.219 HTTP/1.1" 200 22 2.1625

D, [2017-05-22T11:20:32.800417 ] DEBUG -- : close: 172.17.31.210:49920
D, [2017-05-22T11:21:12.622035 ] DEBUG -- : accept: 172.17.31.210:50086
D, [2017-05-22T11:21:12.623259 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:12.625577 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
I, [2017-05-22T11:21:12.626060 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:12 +0200] "GET /tftp/serverName HTTP/1.1" 200 30 0.0007

D, [2017-05-22T11:21:12.666183 ] DEBUG -- : close: 172.17.31.210:50086
D, [2017-05-22T11:21:12.703623 ] DEBUG -- : accept: 172.17.31.210:50088
D, [2017-05-22T11:21:12.704914 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:12.705529 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
E, [2017-05-22T11:21:12.706452 ] ERROR -- : No DHCP record for MAC 172.17.16.0/a0:36:9f:c0:7b:ae found
D, [2017-05-22T11:21:12.706516 ] DEBUG -- : No DHCP record for MAC 172.17.16.0/a0:36:9f:c0:7b:ae found
I, [2017-05-22T11:21:12.706867 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:12 +0200] "GET /dhcp/172.17.16.0/mac/a0:36:9f:c0:7b:ae HTTP/1.1" 404 58 0.0015

D, [2017-05-22T11:21:12.747223 ] DEBUG -- : close: 172.17.31.210:50088
D, [2017-05-22T11:21:12.785406 ] DEBUG -- : accept: 172.17.31.210:50090
D, [2017-05-22T11:21:12.786701 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:12.787305 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
E, [2017-05-22T11:21:12.787650 ] ERROR -- : No DHCP records for IP 172.17.16.0/172.17.31.219 found
D, [2017-05-22T11:21:12.787693 ] DEBUG -- : No DHCP records for IP 172.17.16.0/172.17.31.219 found
I, [2017-05-22T11:21:12.787920 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:12 +0200] "GET /dhcp/172.17.16.0/ip/172.17.31.219 HTTP/1.1" 404 54 0.0007

D, [2017-05-22T11:21:12.828217 ] DEBUG -- : close: 172.17.31.210:50090
D, [2017-05-22T11:21:13.024602 ] DEBUG -- : accept: 172.17.31.210:50092
D, [2017-05-22T11:21:13.026000 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:13.061300 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:21:13.064151 ] DEBUG -- : omshell: executed - set name = "renee-fawver.localdomain"
D, [2017-05-22T11:21:13.064248 ] DEBUG -- : nil
D, [2017-05-22T11:21:13.064306 ] DEBUG -- : omshell: executed - set ip-address = 172.17.31.219
D, [2017-05-22T11:21:13.064338 ] DEBUG -- : nil
D, [2017-05-22T11:21:13.064376 ] DEBUG -- : omshell: executed - set hardware-address = a0:36:9f:c0:7b:ae
D, [2017-05-22T11:21:13.064410 ] DEBUG -- : nil
D, [2017-05-22T11:21:13.064447 ] DEBUG -- : omshell: executed - set hardware-type = 1
D, [2017-05-22T11:21:13.064474 ] DEBUG -- : nil
D, [2017-05-22T11:21:13.064627 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = ac:11:1f:d2; option host-name = \"renee-fawver.localdomain\";"
D, [2017-05-22T11:21:13.064668 ] DEBUG -- : nil
D, [2017-05-22T11:21:13.064717 ] DEBUG -- : omshell: executed - create
D, [2017-05-22T11:21:13.064749 ] DEBUG -- : nil
D, [2017-05-22T11:21:13.262277 ] DEBUG -- : caught :modify event on /var/lib/dhcpd/dhcpd.leases.
D, [2017-05-22T11:21:13.262990 ] DEBUG -- : Added a reservation: 172.17.31.219:a0:36:9f:c0:7b:ae:renee-fawver.localdomain
D, [2017-05-22T11:21:13.326646 ] DEBUG -- : Added DHCP reservation for renee-fawver.localdomain (172.17.31.219 / a0:36:9f:c0:7b:ae)
I, [2017-05-22T11:21:13.327181 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:13 +0200] "POST /dhcp/172.17.16.0 HTTP/1.1" 200 - 0.3006

D, [2017-05-22T11:21:13.328094 ] DEBUG -- : close: 172.17.31.210:50092
D, [2017-05-22T11:21:13.360763 ] DEBUG -- : accept: 172.17.31.210:50096
D, [2017-05-22T11:21:13.362161 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:13.364644 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:21:13.366547 ] DEBUG -- : running /usr/bin/nsupdate -k /etc/rndc.key
D, [2017-05-22T11:21:13.444701 ] DEBUG -- : nsupdate: executed - server 127.0.0.1
D, [2017-05-22T11:21:13.444858 ] DEBUG -- : nsupdate: executed - update add 219.31.17.172.in-addr.arpa. 86400 PTR renee-fawver.localdomain
I, [2017-05-22T11:21:13.613507 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:13 +0200] "POST /dns/ HTTP/1.1" 200 - 0.2491

D, [2017-05-22T11:21:13.614394 ] DEBUG -- : close: 172.17.31.210:50096
D, [2017-05-22T11:21:14.436997 ] DEBUG -- : accept: 172.17.31.210:50106
D, [2017-05-22T11:21:14.438395 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:14.439261 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:21:14.440364 ] DEBUG -- : TFTP: /var/lib/tftpboot/pxelinux.cfg/01-a0-36-9f-c0-7b-ae created successfully
I, [2017-05-22T11:21:14.440627 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:14 +0200] "POST /tftp/PXELinux/a0:36:9f:c0:7b:ae HTTP/1.1" 200 - 0.0016

D, [2017-05-22T11:21:14.441216 ] DEBUG -- : close: 172.17.31.210:50106
D, [2017-05-22T11:21:14.742094 ] DEBUG -- : accept: 172.17.31.210:50108
D, [2017-05-22T11:21:14.743440 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:14.744228 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:21:14.864714 ] DEBUG -- : Starting task: /usr/bin/wget --timeout=10 --tries=3 --no-check-certificate -nv -c "http://m740-tk.localdomain:8080/windows-7-x64///wimboot" -O "/var/lib/tftpboot/boot/windows-x64/wimboot"
I, [2017-05-22T11:21:14.867618 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:14 +0200] "POST /tftp/fetch_boot_file HTTP/1.1" 200 - 0.1236

D, [2017-05-22T11:21:14.868546 ] DEBUG -- : close: 172.17.31.210:50108
D, [2017-05-22T11:21:14.898205 ] DEBUG -- : accept: 172.17.31.210:50110
D, [2017-05-22T11:21:14.899706 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:14.900827 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:21:14.942825 ] DEBUG -- : Starting task: /usr/bin/wget --timeout=10 --tries=3 --no-check-certificate -nv -c "http://m740-tk.localdomain:8080/windows-7-x64///bootmgr" -O "/var/lib/tftpboot/boot/windows-x64/bootmgr"
I, [2017-05-22T11:21:14.943234 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:14 +0200] "POST /tftp/fetch_boot_file HTTP/1.1" 200 - 0.0428

D, [2017-05-22T11:21:14.946088 ] DEBUG -- : close: 172.17.31.210:50110
D, [2017-05-22T11:21:14.975325 ] DEBUG -- : accept: 172.17.31.210:50114
D, [2017-05-22T11:21:14.976766 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:14.977781 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:21:15.073211 ] DEBUG -- : Starting task: /usr/bin/wget --timeout=10 --tries=3 --no-check-certificate -nv -c "http://m740-tk.localdomain:8080/windows-7-x64//boot/bcd" -O "/var/lib/tftpboot/boot/windows-x64/bcd"
I, [2017-05-22T11:21:15.076226 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:15 +0200] "POST /tftp/fetch_boot_file HTTP/1.1" 200 - 0.0987

D, [2017-05-22T11:21:15.077190 ] DEBUG -- : close: 172.17.31.210:50114
D, [2017-05-22T11:21:15.106326 ] DEBUG -- : accept: 172.17.31.210:50118
D, [2017-05-22T11:21:15.107782 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:15.108768 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:21:15.170676 ] DEBUG -- : Starting task: /usr/bin/wget --timeout=10 --tries=3 --no-check-certificate -nv -c "http://m740-tk.localdomain:8080/windows-7-x64//boot/boot.sdi" -O "/var/lib/tftpboot/boot/windows-x64/boot.sdi"
I, [2017-05-22T11:21:15.171091 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:15 +0200] "POST /tftp/fetch_boot_file HTTP/1.1" 200 - 0.0627

D, [2017-05-22T11:21:15.173981 ] DEBUG -- : close: 172.17.31.210:50118
D, [2017-05-22T11:21:15.211231 ] DEBUG -- : accept: 172.17.31.210:50122
D, [2017-05-22T11:21:15.212674 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:21:15.213710 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:21:15.225418 ] DEBUG -- : Starting task: /usr/bin/wget --timeout=10 --tries=3 --no-check-certificate -nv -c "http://m740-tk.localdomain:8080/windows-7-x64//sources/boot.wim" -O "/var/lib/tftpboot/boot/windows-x64/boot.wim"
I, [2017-05-22T11:21:15.225932 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:21:15 +0200] "POST /tftp/fetch_boot_file HTTP/1.1" 200 - 0.0125

D, [2017-05-22T11:21:15.228719 ] DEBUG -- : close: 172.17.31.210:50122
D, [2017-05-22T11:25:07.205535 ] DEBUG -- : accept: 172.17.31.210:50868
D, [2017-05-22T11:25:07.206959 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:07.207930 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:25:07.208599 ] DEBUG -- : running /usr/bin/nsupdate -k /etc/rndc.key
D, [2017-05-22T11:25:07.211042 ] DEBUG -- : nsupdate: executed - server 127.0.0.1
D, [2017-05-22T11:25:07.223750 ] DEBUG -- : nsupdate: executed - update delete 219.31.17.172.in-addr.arpa PTR
I, [2017-05-22T11:25:07.282530 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:07 +0200] "DELETE /dns/219.31.17.172.in-addr.arpa HTTP/1.1" 200 - 0.0748

D, [2017-05-22T11:25:07.284026 ] DEBUG -- : close: 172.17.31.210:50868
D, [2017-05-22T11:25:07.317090 ] DEBUG -- : accept: 172.17.31.210:50870
D, [2017-05-22T11:25:07.318349 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:07.319074 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
I, [2017-05-22T11:25:07.319488 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:07 +0200] "GET /tftp/serverName HTTP/1.1" 200 30 0.0006

D, [2017-05-22T11:25:07.359000 ] DEBUG -- : close: 172.17.31.210:50870
D, [2017-05-22T11:25:07.481211 ] DEBUG -- : accept: 172.17.31.210:50872
D, [2017-05-22T11:25:07.482530 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:07.483165 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
W, [2017-05-22T11:25:07.483398 ] WARN -- : DELETE dhcp/:network/:record endpoint has been deprecated and will be removed in future versions. Please use DELETE dhcp/:network/mac/:mac_address or DELETE dhcp/:network/ip/:ip_address instead.
D, [2017-05-22T11:25:07.486221 ] DEBUG -- : omshell: executed - set hardware-address = a0:36:9f:c0:7b:ae
D, [2017-05-22T11:25:07.486307 ] DEBUG -- : nil
D, [2017-05-22T11:25:07.486376 ] DEBUG -- : omshell: executed - open
D, [2017-05-22T11:25:07.486436 ] DEBUG -- : nil
D, [2017-05-22T11:25:07.486488 ] DEBUG -- : omshell: executed - remove
D, [2017-05-22T11:25:07.486520 ] DEBUG -- : nil
D, [2017-05-22T11:25:07.505915 ] DEBUG -- : caught :modify event on /var/lib/dhcpd/dhcpd.leases.
D, [2017-05-22T11:25:07.519441 ] DEBUG -- : Deleted a reservation: 172.17.31.219:a0:36:9f:c0:7b:ae:renee-fawver.localdomain
D, [2017-05-22T11:25:07.520733 ] DEBUG -- : Removed DHCP reservation for renee-fawver.localdomain => renee-fawver.localdomain (172.17.31.219 / a0:36:9f:c0:7b:ae)
I, [2017-05-22T11:25:07.521142 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:07 +0200] "DELETE /dhcp/172.17.16.0/a0:36:9f:c0:7b:ae HTTP/1.1" 200 4 0.0381

D, [2017-05-22T11:25:07.561421 ] DEBUG -- : close: 172.17.31.210:50872
D, [2017-05-22T11:25:07.593452 ] DEBUG -- : accept: 172.17.31.210:50876
D, [2017-05-22T11:25:07.594965 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:07.595930 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:25:07.597452 ] DEBUG -- : running /usr/bin/nsupdate -k /etc/rndc.key
D, [2017-05-22T11:25:07.599670 ] DEBUG -- : nsupdate: executed - server 127.0.0.1
D, [2017-05-22T11:25:07.599784 ] DEBUG -- : nsupdate: executed - update add 219.31.17.172.in-addr.arpa. 86400 PTR renee-fawver.localdomain
I, [2017-05-22T11:25:07.665417 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:07 +0200] "POST /dns/ HTTP/1.1" 200 - 0.0698

D, [2017-05-22T11:25:07.666206 ] DEBUG -- : close: 172.17.31.210:50876
D, [2017-05-22T11:25:16.545047 ] DEBUG -- : accept: 172.17.31.210:50914
D, [2017-05-22T11:25:16.546546 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:16.547358 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:25:16.547659 ] DEBUG -- : running /usr/bin/nsupdate -k /etc/rndc.key
D, [2017-05-22T11:25:16.550076 ] DEBUG -- : nsupdate: executed - server 127.0.0.1
D, [2017-05-22T11:25:16.550180 ] DEBUG -- : nsupdate: executed - update delete 219.31.17.172.in-addr.arpa PTR
I, [2017-05-22T11:25:16.684003 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:16 +0200] "DELETE /dns/219.31.17.172.in-addr.arpa HTTP/1.1" 200 - 0.1368

D, [2017-05-22T11:25:16.685135 ] DEBUG -- : close: 172.17.31.210:50914
D, [2017-05-22T11:25:16.718681 ] DEBUG -- : accept: 172.17.31.210:50916
D, [2017-05-22T11:25:16.720000 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:16.720670 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
I, [2017-05-22T11:25:16.721115 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:16 +0200] "GET /tftp/serverName HTTP/1.1" 200 30 0.0006

D, [2017-05-22T11:25:16.761055 ] DEBUG -- : close: 172.17.31.210:50916
D, [2017-05-22T11:25:16.930914 ] DEBUG -- : accept: 172.17.31.210:50918
D, [2017-05-22T11:25:16.932107 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:16.932734 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
W, [2017-05-22T11:25:16.932948 ] WARN -- : DELETE dhcp/:network/:record endpoint has been deprecated and will be removed in future versions. Please use DELETE dhcp/:network/mac/:mac_address or DELETE dhcp/:network/ip/:ip_address instead.
E, [2017-05-22T11:25:16.933201 ] ERROR -- : No DHCP record for 172.17.16.0/a0:36:9f:c0:7b:ae found
D, [2017-05-22T11:25:16.933235 ] DEBUG -- : No DHCP record for 172.17.16.0/a0:36:9f:c0:7b:ae found
I, [2017-05-22T11:25:16.933468 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:16 +0200] "DELETE /dhcp/172.17.16.0/a0:36:9f:c0:7b:ae HTTP/1.1" 404 54 0.0009

D, [2017-05-22T11:25:16.973130 ] DEBUG -- : close: 172.17.31.210:50918
D, [2017-05-22T11:25:17.002877 ] DEBUG -- : accept: 172.17.31.210:50920
D, [2017-05-22T11:25:17.004146 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:17.004698 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:25:17.005227 ] DEBUG -- : TFTP: Skipping a request to delete a file which doesn't exists
I, [2017-05-22T11:25:17.005453 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:17 +0200] "DELETE /tftp/PXEGrub2/a0:36:9f:c0:7b:ae HTTP/1.1" 200 - 0.0009

D, [2017-05-22T11:25:17.006036 ] DEBUG -- : close: 172.17.31.210:50920
D, [2017-05-22T11:25:17.035404 ] DEBUG -- : accept: 172.17.31.210:50922
D, [2017-05-22T11:25:17.036459 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:17.036963 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:25:17.037430 ] DEBUG -- : TFTP: /var/lib/tftpboot/pxelinux.cfg/01-a0-36-9f-c0-7b-ae removed successfully
I, [2017-05-22T11:25:17.037655 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:17 +0200] "DELETE /tftp/PXELinux/a0:36:9f:c0:7b:ae HTTP/1.1" 200 - 0.0008

D, [2017-05-22T11:25:17.038154 ] DEBUG -- : close: 172.17.31.210:50922
D, [2017-05-22T11:25:17.067401 ] DEBUG -- : accept: 172.17.31.210:50924
D, [2017-05-22T11:25:17.068471 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:17.068976 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:25:17.069286 ] DEBUG -- : TFTP: Skipping a request to delete a file which doesn't exists
D, [2017-05-22T11:25:17.069343 ] DEBUG -- : TFTP: Skipping a request to delete a file which doesn't exists
I, [2017-05-22T11:25:17.069564 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:17 +0200] "DELETE /tftp/PXEGrub/a0:36:9f:c0:7b:ae HTTP/1.1" 200 - 0.0007

D, [2017-05-22T11:25:17.070138 ] DEBUG -- : close: 172.17.31.210:50924
D, [2017-05-22T11:25:17.209960 ] DEBUG -- : accept: 172.17.31.210:50926
D, [2017-05-22T11:25:17.211157 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:17.213308 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:25:17.217814 ] DEBUG -- : Found puppetca at /opt/puppetlabs/bin/puppet
D, [2017-05-22T11:25:17.218085 ] DEBUG -- : Found sudo at /usr/bin/sudo
D, [2017-05-22T11:25:17.218135 ] DEBUG -- : Executing /usr/bin/sudo -S /opt/puppetlabs/bin/puppet cert --ssldir /etc/puppetlabs/puppet/ssl --clean e6067900-60b5-4533-a06c-5ac91013ab64
D, [2017-05-22T11:25:33.031316 ] DEBUG -- : Attempt to remove nonexistent client certificate for e6067900-60b5-4533-a06c-5ac91013ab64
E, [2017-05-22T11:25:33.031598 ] ERROR -- : Attempt to remove nonexistent client certificate for e6067900-60b5-4533-a06c-5ac91013ab64
D, [2017-05-22T11:25:33.031630 ] DEBUG -- : Attempt to remove nonexistent client certificate for e6067900-60b5-4533-a06c-5ac91013ab64
I, [2017-05-22T11:25:33.031977 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:33 +0200] "DELETE /puppet/ca/e6067900-60b5-4533-a06c-5ac91013ab64 HTTP/1.1" 404 89 15.8188

D, [2017-05-22T11:25:33.072121 ] DEBUG -- : close: 172.17.31.210:50926
D, [2017-05-22T11:25:33.101761 ] DEBUG -- : accept: 172.17.31.210:50976
D, [2017-05-22T11:25:33.103031 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-05-22T11:25:33.103659 ] DEBUG -- : verifying remote client 172.17.31.210 against trusted_hosts ["m740-tk.localdomain"]
D, [2017-05-22T11:25:33.113276 ] DEBUG -- : Attempt to remove nonexistent client autosign for e6067900-60b5-4533-a06c-5ac91013ab64
E, [2017-05-22T11:25:33.113437 ] ERROR -- : Attempt to remove nonexistent client autosign for e6067900-60b5-4533-a06c-5ac91013ab64
D, [2017-05-22T11:25:33.113483 ] DEBUG -- : Attempt to remove nonexistent client autosign for e6067900-60b5-4533-a06c-5ac91013ab64
I, [2017-05-22T11:25:33.113693 ] INFO -- : 172.17.31.210 - - [22/May/2017:11:25:33 +0200] "DELETE /puppet/ca/autosign/e6067900-60b5-4533-a06c-5ac91013ab64 HTTP/1.1" 404 86 0.0102

D, [2017-05-22T11:25:33.154005 ] DEBUG -- : close: 172.17.31.210:50976
(4-4/5)