rpkid throws exception when generating Ghostbusters

[sraustein]

rpkid is throwing an exception. I thought maybe the vm was in a bad state so I rebooted, but even after a restart it is continuing.

{{{ 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.PollParentTask {IANA}>: Starting 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.PollParentTask {IANA}>: Polling parents 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.PollParentTask {IANA}>: Executing list query 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.PollParentTask {IANA}>: Parsing list response 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.PollParentTask {IANA}>: Checking updates for 2016-06-17 17:10:06 rpkid[999]: Sending issue request to from <bound method CADetail.update of <CADetail: IANA.IANA class IANA active rsync://rpkidev/rpki/IANA/wc4K32wE4J81GUij2PiDlm2-M3E.cer>> 2016-06-17 17:10:06 rpkid[999]: Parent.up_down_issue_query(): caRepository u'rsync://rpkidev/rpki/IANA/' rpkiManifest u'rsync://rpkidev/rpki/IANA/wc4K32wE4J81GUij2PiDlm2-M3E.mft' rpkiNotify u'https://rpkidev/rrdp/notify.xml' 2016-06-17 17:10:06 rpkid[999]: <CADetail: IANA.IANA class IANA active rsync://rpkidev/rpki/IANA/wc4K32wE4J81GUij2PiDlm2-M3E.cer> received certificate rsync://rpkidev/rpki/IANA/wc4K32wE4J81GUij2PiDlm2-M3E.cer 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.PollParentTask {IANA}>: Exiting 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateChildrenTask {IANA}>: Starting 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateChildrenTask {IANA}>: Updating children 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateChildrenTask {IANA}>: Exiting 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateROAsTask {IANA}>: Starting 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateROAsTask {IANA}>: Updating ROAs 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateROAsTask {IANA}>: Received response to query for ROA requests: <Element {http://www.hactrn.net/uris/rpki/left-right-spec/}msg at 0x7efde5719b90> 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateROAsTask {IANA}>: Exiting 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateGhostbustersTask {IANA}>: Starting 2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateGhostbustersTask {IANA}>: Updating Ghostbuster records 2016-06-17 17:10:06 rpkid[999]: Could not update Ghostbuster records for IANA, skipping Traceback (most recent call last): File "/usr/lib/python2.7/dist-packages/rpki/rpkid_tasks.py", line 476, in main r_msg = yield self.rpkid.irdb_query_ghostbuster_requests(self.tenant.tenant_handle, parent_handles) File "/usr/lib/python2.7/dist-packages/tornado/gen.py", line 870, in run value = future.result() File "/usr/lib/python2.7/dist-packages/tornado/concurrent.py", line 215, in result raise_exc_info(self._exc_info) File "/usr/lib/python2.7/dist-packages/tornado/gen.py", line 876, in run yielded = self.gen.throw(*exc_info) File "/usr/lib/python2.7/dist-packages/rpki/rpkid.py", line 418, in irdb_query_ghostbuster_requests r_msg = yield self.irdb_query(q_msg) File "/usr/lib/python2.7/dist-packages/tornado/gen.py", line 870, in run value = future.result() File "/usr/lib/python2.7/dist-packages/tornado/concurrent.py", line 215, in result raise_exc_info(self._exc_info) File "/usr/lib/python2.7/dist-packages/tornado/gen.py", line 879, in run yielded = self.gen.send(value) File "/usr/lib/python2.7/dist-packages/rpki/rpkid.py", line 361, in irdb_query raise rpki.exceptions.BadIRDBReply("Unexpected response to IRDB query: %s" % r_cms.pretty_print_content()) BadIRDBReply: Unexpected response to IRDB query: <?xml version='1.0' encoding='us-ascii'?>

column irdb_parent.id does not exist LINE 1: ...ent" ON ( "irdb_ghostbusterrequest"."parent_id" = "irdb_pare... ^

2016-06-17 17:10:06 rpkid[999]: <rpki.rpkid_tasks.UpdateGhostbustersTask {IANA}>: Exiting }}}

{{{ root@rpkidev:/var/log/rpki# dpkg -l rpki-ca Desired=Unknown/Install/Remove/Purge/Hold | Status=Not/Inst/Conf-files/Unpacked/halF-conf/Half-inst/trig-aWait/Trig-pend |/ Err?=(none)/Reinst-required (Status,Err: uppercase=bad) ||/ Name Version Architecture Description +++-================================================-=============================-=============================-===================================================================================================== ii rpki-ca 0.6442~trusty amd64 rpki.net certification authority tools root@rpkidev:/var/log/rpki# }}}

Trac ticket #829 component rpkid priority minor, owner None, created by melkins on 2016-06-17T17:12:57Z, last modified 2016-06-17T18:12:37Z

[sraustein]

Is this an installation of tk705 which predates the "Farewell To Turtles" commit?

The primary key of the Parent model changed as part of that commit, and this report looks like what I would expect if you ran the new code with an old database. If the database schema still contains an irdb_turtle table and colums like turtle_id, turtle_ptr, turtle_id_ptr, etc, that's probably what happened here.

Trac comment by sra on 2016-06-17T18:04:06Z

[sraustein]

Thanks, I forgot about that. Indeed, the db does contain the irdb.Turtle table. I do recall you warning that since this affected developers that there wasn't an automated upgrade path.

Trac comment by melkins on 2016-06-17T18:12:37Z

[sraustein]

Closed with resolution invalid