[Qa-jenkins-scm] Build failed in Jenkins: reproducible_scheduler #22306

Wed Nov 7 22:52:16 GMT 2018

See <https://jenkins.debian.net/job/reproducible_scheduler/22306/display/redirect>

------------------------------------------
Started by timer
[EnvInject] - Loading node environment variables.
Building on master in workspace <https://jenkins.debian.net/job/reproducible_scheduler/ws/>
[workspace] $ /bin/sh -xe /tmp/jenkins2062135986966249269.sh
+ /srv/jenkins/bin/reproducible_scheduler.py
[2018-11-07 21:23:16] INFO: Starting at 2018-11-07 21:23:16.752117
[2018-11-07 21:23:20] INFO: Updating sources tables for all suites.
[2018-11-07 21:23:20] INFO: Downloading sources file for stretch: http://deb.debian.org/debian/dists/stretch/main/source/Sources.xz
[2018-11-07 21:23:55] INFO: Updating sources db for stretch/amd64...
[2018-11-07 21:27:31] INFO: Pushing 0 updated packages to the database...
[2018-11-07 21:28:19] INFO: Now deleting 0 removed packages: []
[2018-11-07 21:28:27] INFO: DB update done for stretch/amd64 done at 2018-11-07 21:28:26.
[2018-11-07 21:28:27] INFO: Updating sources db for stretch/i386...
[2018-11-07 21:30:56] INFO: Pushing 0 updated packages to the database...
[2018-11-07 21:31:53] INFO: Now deleting 0 removed packages: []
[2018-11-07 21:32:00] INFO: DB update done for stretch/i386 done at 2018-11-07 21:32:00.
[2018-11-07 21:32:00] INFO: Updating sources db for stretch/arm64...
[2018-11-07 21:34:15] INFO: Pushing 0 updated packages to the database...
[2018-11-07 21:35:02] INFO: Now deleting 0 removed packages: []
[2018-11-07 21:35:08] INFO: DB update done for stretch/arm64 done at 2018-11-07 21:35:08.
[2018-11-07 21:35:08] INFO: Updating sources db for stretch/armhf...
[2018-11-07 21:37:14] INFO: Pushing 0 updated packages to the database...
[2018-11-07 21:38:11] INFO: Now deleting 0 removed packages: []
[2018-11-07 21:38:16] INFO: DB update done for stretch/armhf done at 2018-11-07 21:38:16.
[2018-11-07 21:38:16] INFO: Sources for suite stretch done at 2018-11-07 21:38:16.
[2018-11-07 21:38:16] INFO: Downloading sources file for buster: http://deb.debian.org/debian/dists/buster/main/source/Sources.xz
[2018-11-07 21:38:37] INFO: Updating sources db for buster/amd64...
[2018-11-07 21:42:41] INFO: Pushing 1 updated packages to the database...
[2018-11-07 21:45:31] INFO: Now deleting 0 removed packages: []
[2018-11-07 21:45:35] INFO: DB update done for buster/amd64 done at 2018-11-07 21:45:35.
[2018-11-07 21:45:35] INFO: Updating sources db for buster/i386...
[2018-11-07 21:51:34] INFO: Pushing 1 updated packages to the database...
[2018-11-07 21:53:43] INFO: Now deleting 0 removed packages: []
[2018-11-07 21:53:50] INFO: DB update done for buster/i386 done at 2018-11-07 21:53:50.
[2018-11-07 21:53:50] INFO: Updating sources db for buster/arm64...
[2018-11-07 21:58:47] INFO: Pushing 1 updated packages to the database...
[2018-11-07 22:02:33] INFO: Now deleting 0 removed packages: []
[2018-11-07 22:02:40] INFO: DB update done for buster/arm64 done at 2018-11-07 22:02:40.
[2018-11-07 22:02:40] INFO: Updating sources db for buster/armhf...
[2018-11-07 22:08:21] INFO: Pushing 1 updated packages to the database...
[2018-11-07 22:11:13] INFO: Now deleting 0 removed packages: []
[2018-11-07 22:11:26] INFO: DB update done for buster/armhf done at 2018-11-07 22:11:26.
[2018-11-07 22:11:32] INFO: Sources for suite buster done at 2018-11-07 22:11:32.
[2018-11-07 22:11:32] INFO: Downloading sources file for unstable: http://deb.debian.org/debian/dists/unstable/main/source/Sources.xz
[2018-11-07 22:12:17] INFO: Updating sources db for unstable/amd64...
[2018-11-07 22:16:36] INFO: Pushing 116 updated packages to the database...
[2018-11-07 22:16:41] INFO: Now inserting 2 new sources in the database: [{'suite': 'unstable', 'architecture': 'amd64', 'version': '0.1-1', 'name': 'ionit'}, {'suite': 'unstable', 'architecture': 'amd64', 'version': '0.0~git20170407.fc9adea-1', 'name': 'golang-gopkg-freddierice-go-losetup.v1'}]
[2018-11-07 22:18:14] INFO: Now deleting 0 removed packages: []
[2018-11-07 22:18:17] INFO: Building pages for the new packages
[2018-11-07 22:18:17] INFO: Generating the pages of 2 package(s)
[2018-11-07 22:18:20] INFO: DB update done for unstable/amd64 done at 2018-11-07 22:18:20.
[2018-11-07 22:18:20] INFO: Updating sources db for unstable/i386...
[2018-11-07 22:23:54] INFO: Pushing 116 updated packages to the database...
[2018-11-07 22:23:54] INFO: Now inserting 2 new sources in the database: [{'suite': 'unstable', 'architecture': 'i386', 'version': '0.1-1', 'name': 'ionit'}, {'suite': 'unstable', 'architecture': 'i386', 'version': '0.0~git20170407.fc9adea-1', 'name': 'golang-gopkg-freddierice-go-losetup.v1'}]
[2018-11-07 22:26:02] INFO: Now deleting 0 removed packages: []
[2018-11-07 22:26:04] INFO: Building pages for the new packages
[2018-11-07 22:26:04] INFO: Generating the pages of 2 package(s)
[2018-11-07 22:26:08] INFO: DB update done for unstable/i386 done at 2018-11-07 22:26:08.
[2018-11-07 22:26:08] INFO: Updating sources db for unstable/arm64...
[2018-11-07 22:35:23] INFO: Pushing 116 updated packages to the database...
[2018-11-07 22:35:23] INFO: Now inserting 2 new sources in the database: [{'suite': 'unstable', 'architecture': 'arm64', 'version': '0.1-1', 'name': 'ionit'}, {'suite': 'unstable', 'architecture': 'arm64', 'version': '0.0~git20170407.fc9adea-1', 'name': 'golang-gopkg-freddierice-go-losetup.v1'}]
[2018-11-07 22:39:54] INFO: Now deleting 0 removed packages: []
[2018-11-07 22:40:01] INFO: Building pages for the new packages
[2018-11-07 22:40:01] INFO: Generating the pages of 2 package(s)
[2018-11-07 22:40:16] INFO: DB update done for unstable/arm64 done at 2018-11-07 22:40:16.
[2018-11-07 22:40:16] INFO: Updating sources db for unstable/armhf...
[2018-11-07 22:47:49] INFO: Pushing 116 updated packages to the database...
[2018-11-07 22:47:50] INFO: Now inserting 2 new sources in the database: [{'suite': 'unstable', 'architecture': 'armhf', 'version': '0.0~git20170407.fc9adea-1', 'name': 'golang-gopkg-freddierice-go-losetup.v1'}, {'suite': 'unstable', 'architecture': 'armhf', 'version': '0.1-1', 'name': 'ionit'}]
[2018-11-07 22:51:11] INFO: Now deleting 0 removed packages: []
[2018-11-07 22:51:20] INFO: Building pages for the new packages
[2018-11-07 22:51:20] INFO: Generating the pages of 2 package(s)
[2018-11-07 22:51:32] INFO: DB update done for unstable/armhf done at 2018-11-07 22:51:31.
[2018-11-07 22:51:36] INFO: Sources for suite unstable done at 2018-11-07 22:51:36.
[2018-11-07 22:51:36] INFO: Downloading sources file for experimental: http://deb.debian.org/debian/dists/experimental/main/source/Sources.xz
[2018-11-07 22:51:37] INFO: Updating sources db for experimental/amd64...
[2018-11-07 22:51:49] INFO: Pushing 1 updated packages to the database...
[2018-11-07 22:51:49] INFO: Now inserting 2 new sources in the database: [{'suite': 'experimental', 'architecture': 'amd64', 'version': '1.0.2-1', 'name': 'keras-preprocessing'}, {'suite': 'experimental', 'architecture': 'amd64', 'version': '1.0.4-1', 'name': 'keras-applications'}]
[2018-11-07 22:51:49] INFO: Now deleting 1 removed packages: ['llvm-toolchain-7']
Traceback (most recent call last):
  File "/usr/lib/python3/dist-packages/sqlalchemy/engine/base.py", line 1139, in _execute_context
    context)
  File "/usr/lib/python3/dist-packages/sqlalchemy/engine/default.py", line 450, in do_execute
    cursor.execute(statement, parameters)
psycopg2.IntegrityError: duplicate key value violates unique constraint "removed_packages_pkey"
DETAIL:  Key (name, suite, architecture)=(llvm-toolchain-7, experimental, amd64) already exists.

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/srv/jenkins/bin/reproducible_scheduler.py", line 760, in <module>
    update_sources(suite)
  File "/srv/jenkins/bin/reproducible_scheduler.py", line 266, in update_sources
    update_sources_db(suite, arch, sources)
  File "/srv/jenkins/bin/reproducible_scheduler.py", line 387, in update_sources_db
    conn_db.execute(removed_packages_table.insert(), pkgs_to_rm)
  File "/usr/lib/python3/dist-packages/sqlalchemy/engine/base.py", line 914, in execute
    return meth(self, multiparams, params)
  File "/usr/lib/python3/dist-packages/sqlalchemy/sql/elements.py", line 323, in _execute_on_connection
    return connection._execute_clauseelement(self, multiparams, params)
  File "/usr/lib/python3/dist-packages/sqlalchemy/engine/base.py", line 1010, in _execute_clauseelement
    compiled_sql, distilled_params
  File "/usr/lib/python3/dist-packages/sqlalchemy/engine/base.py", line 1146, in _execute_context
    context)
  File "/usr/lib/python3/dist-packages/sqlalchemy/engine/base.py", line 1341, in _handle_dbapi_exception
    exc_info
  File "/usr/lib/python3/dist-packages/sqlalchemy/util/compat.py", line 202, in raise_from_cause
    reraise(type(exception), exception, tb=exc_tb, cause=cause)
  File "/usr/lib/python3/dist-packages/sqlalchemy/util/compat.py", line 185, in reraise
    raise value.with_traceback(tb)
  File "/usr/lib/python3/dist-packages/sqlalchemy/engine/base.py", line 1139, in _execute_context
    context)
  File "/usr/lib/python3/dist-packages/sqlalchemy/engine/default.py", line 450, in do_execute
    cursor.execute(statement, parameters)
sqlalchemy.exc.IntegrityError: (psycopg2.IntegrityError) duplicate key value violates unique constraint "removed_packages_pkey"
DETAIL:  Key (name, suite, architecture)=(llvm-toolchain-7, experimental, amd64) already exists.
 [SQL: 'INSERT INTO removed_packages (name, suite, architecture) VALUES (%(name)s, %(suite)s, %(architecture)s)'] [parameters: {'suite': 'experimental', 'architecture': 'amd64', 'name': 'llvm-toolchain-7'}]
[2018-11-07 22:51:50] INFO: Finished at 2018-11-07 22:51:50.766762, took: 1:28:34.015025
Build step 'Execute shell' marked build as failure