SKS mock keyserver: Sometimes fails to start leading to failed specs / dirmngr fails to connect to SKS mock keyserver
I'm trying hard currently to fix the reproducible builds problems. Currently, it FTBFS, either in the first build, or more often, during the second build. I'm still debugging to find the root cause, and, it seems there are multiple problems. Besides others, I've hit the following (log of dirmngr):
2017-10-02 04:58:41 dirmngr[19218] listening on socket '/tmp/schleuder-test/example.org/list171/S.dirmngr'
2017-10-02 04:58:41 dirmngr[19219.0] permanently loaded certificates: 149
2017-10-02 04:58:41 dirmngr[19219.0] runtime cached certificates: 0
2017-10-02 04:58:41 dirmngr[19219.0] trusted certificates: 149 (148,0,0,1)
2017-10-02 04:58:41 dirmngr[19219.0] failed to open cache dir file '/tmp/schleuder-test/example.org/list171/crls.d/DIR.txt': No such file or directory
2017-10-02 04:58:41 dirmngr[19219.0] creating directory '/tmp/schleuder-test/example.org/list171/crls.d'
2017-10-02 04:58:41 dirmngr[19219.0] new cache dir file '/tmp/schleuder-test/example.org/list171/crls.d/DIR.txt' created
2017-10-02 04:58:42 dirmngr[19219.6] handler for fd 6 started
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> # Home: /tmp/schleuder-test/example.org/list171
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> # Config: /tmp/schleuder-test/example.org/list171/dirmngr.conf
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> OK Dirmngr 2.2.1 at your service
2017-10-02 04:58:42 dirmngr[19219.6] connection from process 19215 (0:0)
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 <- GETINFO version
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> D 2.2.1
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> OK
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 <- KEYSERVER --clear hkp://localhost:9999
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> OK
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 <- KEYSERVER
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> S KEYSERVER hkp://localhost:9999
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> OK
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 <- KS_GET -- 0x59C71FB38AEE22E091C78259D06350440F759BD3
2017-10-02 04:58:42 dirmngr[19219.6] DBG: dns: libdns initialized
2017-10-02 04:58:42 dirmngr[19219.6] DBG: dns: resolve_dns_name(localhost): Success
2017-10-02 04:58:42 dirmngr[19219.6] resolve_dns_addr for 'localhost': 'localhost' [already known]
2017-10-02 04:58:42 dirmngr[19219.6] resolve_dns_addr for 'localhost': 'localhost' [already known]
2017-10-02 04:58:42 dirmngr[19219.6] number of system provided CAs: 148
2017-10-02 04:58:42 dirmngr[19219.6] DBG: http.c:connect_server: trying name='localhost' port=9999
2017-10-02 04:58:42 dirmngr[19219.6] DBG: dns: resolve_dns_name(localhost): Success
2017-10-02 04:58:42 dirmngr[19219.6] can't connect to 'localhost': Connection refused
2017-10-02 04:58:42 dirmngr[19219.6] error connecting to 'http://localhost:9999': Connection refused
2017-10-02 04:58:42 dirmngr[19219.6] command 'KS_GET' failed: Connection refused
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> ERR 167804953 Connection refused <Dirmngr>
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 <- BYE
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> OK closing connection
2017-10-02 04:58:42 dirmngr[19219.6] handler for fd 6 terminated
2017-10-02 04:58:44 dirmngr[19219.6] handler for fd 6 started
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> # Home: /tmp/schleuder-test/example.org/list171
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> # Config: /tmp/schleuder-test/example.org/list171/dirmngr.conf
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> OK Dirmngr 2.2.1 at your service
2017-10-02 04:58:44 dirmngr[19219.6] connection from process 19221 (0:0)
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 <- GETINFO version
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> D 2.2.1
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> OK
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 <- KEYSERVER --clear hkp://localhost:9999
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> OK
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 <- KEYSERVER
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> S KEYSERVER hkp://localhost:9999
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> OK
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 <- KS_GET -- 0x59C71FB38AEE22E091C78259D06350440F759BD3
2017-10-02 04:58:42 dirmngr[19219.6] DBG: dns: libdns initialized
2017-10-02 04:58:42 dirmngr[19219.6] DBG: dns: resolve_dns_name(localhost): Success
2017-10-02 04:58:42 dirmngr[19219.6] resolve_dns_addr for 'localhost': 'localhost' [already known]
2017-10-02 04:58:42 dirmngr[19219.6] resolve_dns_addr for 'localhost': 'localhost' [already known]
2017-10-02 04:58:42 dirmngr[19219.6] number of system provided CAs: 148
2017-10-02 04:58:42 dirmngr[19219.6] DBG: http.c:connect_server: trying name='localhost' port=9999
2017-10-02 04:58:42 dirmngr[19219.6] DBG: dns: resolve_dns_name(localhost): Success
2017-10-02 04:58:42 dirmngr[19219.6] can't connect to 'localhost': Connection refused
2017-10-02 04:58:42 dirmngr[19219.6] error connecting to 'http://localhost:9999': Connection refused
2017-10-02 04:58:42 dirmngr[19219.6] command 'KS_GET' failed: Connection refused
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> ERR 167804953 Connection refused <Dirmngr>
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 <- BYE
2017-10-02 04:58:42 dirmngr[19219.6] DBG: chan_6 -> OK closing connection
2017-10-02 04:58:42 dirmngr[19219.6] handler for fd 6 terminated
2017-10-02 04:58:44 dirmngr[19219.6] handler for fd 6 started
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> # Home: /tmp/schleuder-test/example.org/list171
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> # Config: /tmp/schleuder-test/example.org/list171/dirmngr.conf
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> OK Dirmngr 2.2.1 at your service
2017-10-02 04:58:44 dirmngr[19219.6] connection from process 19221 (0:0)
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 <- GETINFO version
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> D 2.2.1
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> OK
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 <- KEYSERVER --clear hkp://localhost:9999
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> OK
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 <- KEYSERVER
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> S KEYSERVER hkp://localhost:9999
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> OK
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 <- KS_GET -- 0x98769E8A1091F36BD88403ECF71A3F8412D83889
2017-10-02 04:58:44 dirmngr[19219.6] DBG: http.c:connect_server: trying name='localhost' port=9999
2017-10-02 04:58:44 dirmngr[19219.6] DBG: dns: resolve_dns_name(localhost): Success
2017-10-02 04:58:44 dirmngr[19219.6] can't connect to 'localhost': Connection refused
2017-10-02 04:58:44 dirmngr[19219.6] error connecting to 'http://localhost:9999': Connection refused
2017-10-02 04:58:44 dirmngr[19219.6] command 'KS_GET' failed: Connection refused
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> ERR 167804953 Connection refused <Dirmngr>
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 <- BYE
2017-10-02 04:58:44 dirmngr[19219.6] DBG: chan_6 -> OK closing connection
2017-10-02 04:58:44 dirmngr[19219.6] handler for fd 6 terminated
(Note: I've patched the keyserver location, changing 127.0.0.1
to localhost
, during the above run, trying to check if this makes a difference.)
Right now, I'm not able to provide more information regarding the cause, so for now, this is just a pointer. More to follow, hopefully.
Edited by georg