Skip to content

Instantly share code, notes, and snippets.

@Andrei-Stepanov

Andrei-Stepanov/gist:075610442d375db1e3dcb4aa9780e3ae

Created January 17, 2019 12:04
Show Gist options
  • Save Andrei-Stepanov/075610442d375db1e3dcb4aa9780e3ae to your computer and use it in GitHub Desktop.
Save Andrei-Stepanov/075610442d375db1e3dcb4aa9780e3ae to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
# fedpkg module-build-local -s platform:f30 --file standard-test-roles.yaml
2019-01-17 06:53:06,861 - MainThread - urllib3.util.retry - DEBUG - Converted retries value: 3 -> Retry(total=3, connect=None, read=None, redirect=None, status=None)
2019-01-17 06:53:07,091 - MainThread - moksha.hub - WARNING - Cannot find qpid python module. Make sure you have python-qpid installed.
2019-01-17 06:53:07,244 - MainThread - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): mbs.fedoraproject.org:443
2019-01-17 06:53:07,884 - MainThread - urllib3.connectionpool - DEBUG - https://mbs.fedoraproject.org:443 "GET /module-build-service/1/module-builds/?name=platform&stream=f30&state=ready&verbose=True&order_desc_by=version&page=1&per_page=10 HTTP/1.1" 200 2319
2019-01-17 06:53:07,886 - MainThread - module_build_service - DEBUG - Adding module platform:f30:5:00000000 with requires: None
2019-01-17 06:53:07,887 - MainThread - module_build_service - INFO - Starting resolving with following input modules: ['platform:f30:5:00000000']
2019-01-17 06:53:07,887 - MainThread - module_build_service - DEBUG - Adding module standard-test-roles:f30:20190117115307:0 with requires: <Id #-2147483646 (module(platform) with module(platform:f30))>
2019-01-17 06:53:07,887 - MainThread - module_build_service - DEBUG - Testing standard-test-roles:f30:20190117115307:0-20190117115307.src with combination: (<Solvable #2 platform:f30:5:00000000-5.x86_64>,)
2019-01-17 06:53:07,887 - MainThread - module_build_service - DEBUG - Jobs:
2019-01-17 06:53:07,888 - MainThread - module_build_service - DEBUG - - favor platform:f30:5:00000000-5.x86_64
2019-01-17 06:53:07,888 - MainThread - module_build_service - DEBUG - - install standard-test-roles:f30:20190117115307:0-20190117115307.src
2019-01-17 06:53:07,888 - MainThread - module_build_service - DEBUG - Transaction:
2019-01-17 06:53:07,888 - MainThread - module_build_service - DEBUG - - standard-test-roles:f30:20190117115307:0-20190117115307.src
2019-01-17 06:53:07,888 - MainThread - module_build_service - DEBUG - - platform:f30:5:00000000-5.x86_64
2019-01-17 06:53:07,889 - MainThread - module_build_service - INFO - Resolving done, possible requires: {frozenset({'platform:f30:5:00000000:x86_64', 'standard-test-roles:f30:20190117115307:0:src'})}
2019-01-17 06:53:08,188 - MainThread - urllib3.connectionpool - DEBUG - https://mbs.fedoraproject.org:443 "GET /module-build-service/1/module-builds/?name=platform&stream=f30&state=ready&verbose=True&order_desc_by=version&version=5&context=00000000&page=1&per_page=10 HTTP/1.1" 200 2373
2019-01-17 06:53:08,195 - MainThread - module_build_service - INFO - Module platform:f30:5:00000000 imported
2019-01-17 06:53:08,198 - MainThread - module_build_service - DEBUG - Checking whether module build already exists: standard-test-roles:f30:3020190117115307:a5b0195c.
2019-01-17 06:53:08,200 - MainThread - module_build_service - DEBUG - Creating new module build
2019-01-17 06:53:08,219 - MainThread - module_build_service - WARNING - Hub not initialized. Queueing on the side.
2019-01-17 06:53:08,223 - MainThread - module_build_service - INFO - root submitted build of standard-test-roles, stream=f30, version=3020190117115307, context=a5b0195c
2019-01-17 06:53:08,225 - MainThread - moksha.hub - INFO - Loading the Moksha Hub
2019-01-17 06:53:08,226 - MainThread - moksha.hub - WARNING - No 'zmq_publish_endpoints' set. Are you sure?
2019-01-17 06:53:09,228 - MainThread - moksha.hub - INFO - Loading Consumers
2019-01-17 06:53:09,228 - MainThread - moksha.hub - DEBUG - Loading explicitly passed entry-points.
2019-01-17 06:53:09,228 - MainThread - module_build_service - DEBUG - Setting topics: *
2019-01-17 06:53:09,228 - MainThread - fedmsg.consumers - DEBUG - mbsconsumer is True
2019-01-17 06:53:09,229 - MainThread - fedmsg.consumers - INFO - enabled by config - module_build_service.scheduler.consumer:MBSConsumer
2019-01-17 06:53:09,229 - MainThread - moksha.hub - DEBUG - Subscribing to consumer topic *
2019-01-17 06:53:09,229 - MainThread - moksha.hub - DEBUG - Subscribing to on 'ZmqEndpoint(type='connect', address='ipc:///dev/null')'
2019-01-17 06:53:09,229 - MainThread - moksha.hub - DEBUG - Creating new txzmq factory.
2019-01-17 06:53:09,230 - MainThread - moksha.hub - INFO - Blocking mode false for <module_build_service.scheduler.consumer.MBSConsumer object at 0x7f72be035c88>. Messages to be queued and distributed to 1 threads.
2019-01-17 06:53:09,233 - MainThread - fedmsg.consumers - INFO - No backlog handling. status: None, url: None
2019-01-17 06:53:09,234 - MainThread - moksha.hub - INFO - Loading Producers
2019-01-17 06:53:09,234 - MainThread - moksha.hub - DEBUG - Loading explicitly passed entry-points.
2019-01-17 06:53:09,234 - MainThread - moksha.hub - INFO - Running the MokshaHub reactor
2019-01-17 06:53:09,234 - MainThread - moksha.hub - INFO - Suggesting threadpool size at 2
2019-01-17 06:53:09,235 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Worker thread picking a message.
2019-01-17 06:53:09,239 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Calling init: MBSModule, 1
2019-01-17 06:53:09,248 - Thread-1 - module_build_service - DEBUG - Getting/verifying commit hash for https://src.fedoraproject.org/rpms/standard-test-roles
2019-01-17 06:53:09,689 - Thread-1 - module_build_service - DEBUG - Getting/verifying commit hash for https://src.fedoraproject.org/rpms/standard-test-roles
2019-01-17 06:53:10,068 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Start to find out stream collision modules.
2019-01-17 06:53:10,070 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Connecting to koji 'https://koji.fedoraproject.org/kojihub'.
2019-01-17 06:53:10,071 - PoolThread-twisted.internet.reactor-0 - koji - DEBUG - Opening new requests session
2019-01-17 06:53:10,072 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): koji.fedoraproject.org:443
2019-01-17 06:53:10,419 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - https://koji.fedoraproject.org:443 "POST /kojihub HTTP/1.1" 200 705
2019-01-17 06:53:10,465 - PoolThread-twisted.internet.reactor-0 - koji - DEBUG - Connection Error: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))
2019-01-17 06:53:10,466 - PoolThread-twisted.internet.reactor-0 - koji - DEBUG - Opening new requests session
2019-01-17 06:53:10,467 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): koji.fedoraproject.org:443
2019-01-17 06:53:10,773 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - https://koji.fedoraproject.org:443 "POST /kojihub HTTP/1.1" 200 843
2019-01-17 06:53:10,845 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - https://koji.fedoraproject.org:443 "POST /kojihub HTTP/1.1" 200 2657
2019-01-17 06:53:10,846 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - No module metadata is found from ursine content.
2019-01-17 06:53:10,846 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - No stream collision module is found against base module platform.
2019-01-17 06:53:10,847 - PoolThread-twisted.internet.reactor-0 - urllib3.util.retry - DEBUG - Converted retries value: 3 -> Retry(total=3, connect=None, read=None, redirect=None, status=None)
2019-01-17 06:53:10,855 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'wait', batch 0, state_reason None>, state 0->1
2019-01-17 06:53:10,866 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Done with init: MBSModule, 1
2019-01-17 06:53:10,869 - PoolThread-twisted.internet.reactor-0 - module_build_service.scheduler - DEBUG - stop_condition checking <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'wait', batch 0, state_reason None>, got False
2019-01-17 06:53:10,869 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Going back to waiting on the incoming queue. Message handled: True
2019-01-17 06:53:10,869 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Worker thread picking a message.
2019-01-17 06:53:10,874 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Calling wait: MBSModule, 2
2019-01-17 06:53:10,876 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Found build=<ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'wait', batch 0, state_reason None> from message
2019-01-17 06:53:10,876 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - '---\ndocument: modulemd\nversion: 2\ndata:\n name: standard-test-roles\n stream: f30\n version: 3020190117115307\n context: a5b0195c\n summary: Standard Test Interface Ansible roles\n description: >-\n Shared Ansible roles to support the Standard Test Interface as described at https://fedoraproject.org/wiki/Changes/InvokingTestsAnsible.\n license:\n module:\n - MIT\n xmd:\n mbs:\n mse: TRUE\n buildrequires:\n platform:\n filtered_rpms: []\n ref: f30\n stream: f30\n context: 00000000\n version: 5\n koji_tag: module-f30-build\n stream_collision_modules: \n ursine_rpms: \n scmurl: \n commit: \n rpms:\n standard-test-roles:\n ref: 8ac521654e1a5f3ebae7cfe2494c9cbadcb1eb3f\n dependencies:\n - buildrequires:\n platform: [f30]\n requires:\n platform: [f30]\n profiles:\n default:\n description: A standard installation.\n rpms:\n - standard-test-roles\n provisioner-qemu:\n description: A standalone provisioner for qemu\n rpms:\n - standard-test-roles-inventory-qemu\n api:\n rpms:\n - standard-test-roles\n components:\n rpms:\n standard-test-roles:\n rationale: Package in api\n repository: https://src.fedoraproject.org/rpms/standard-test-roles\n cache: http://pkgs.fedoraproject.org/repo/pkgs/standard-test-roles\n ref: master\n buildorder: 10\n arches: [armv7hl, i686, x86_64]\n...\n'
2019-01-17 06:53:10,877 - PoolThread-twisted.internet.reactor-0 - root - DEBUG - get_module_build_dependencies(mmd=<Modulemd.Module object at 0x7f72bb77f480 (ModulemdModule at 0x7f72ac20b6d0)> strict=True)
2019-01-17 06:53:11,166 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - https://mbs.fedoraproject.org:443 "GET /module-build-service/1/module-builds/?name=platform&stream=f30&state=ready&verbose=True&order_desc_by=version&version=5&context=00000000&page=1&per_page=10 HTTP/1.1" 200 2373
2019-01-17 06:53:11,168 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Getting tag for standard-test-roles:f30:3020190117115307
2019-01-17 06:53:11,168 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Found tag=module-standard-test-roles-f30-3020190117115307 for module <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'wait', batch 0, state_reason None>
2019-01-17 06:53:11,168 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Assigning koji tag=module-standard-test-roles-f30-3020190117115307 to module build
2019-01-17 06:53:11,168 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Assigning Content Generator build koji tag=modular-updates-candidate to module build
2019-01-17 06:53:11,171 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Machine arch setting: x86_64
2019-01-17 06:53:11,172 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - MockModuleBuilder initialized, tag_name=module-standard-test-roles-f30-3020190117115307, tag_dir=/root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307
2019-01-17 06:53:11,172 - PoolThread-twisted.internet.reactor-0 - dogpile.lock - DEBUG - NeedRegenerationException
2019-01-17 06:53:11,173 - PoolThread-twisted.internet.reactor-0 - dogpile.lock - DEBUG - no value, waiting for create lock
2019-01-17 06:53:11,173 - PoolThread-twisted.internet.reactor-0 - dogpile.lock - DEBUG - value creation lock <dogpile.cache.region.CacheRegion._LockWrapper object at 0x7f72be01a5c0> acquired
2019-01-17 06:53:11,173 - PoolThread-twisted.internet.reactor-0 - dogpile.lock - DEBUG - Calling creation function for not-yet-present value
2019-01-17 06:53:11,457 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - https://mbs.fedoraproject.org:443 "GET /module-build-service/1/module-builds/?name=platform&stream=f30&state=ready&verbose=True&order_desc_by=version&version=5&context=00000000&page=1&per_page=10 HTTP/1.1" 200 2373
2019-01-17 06:53:11,459 - PoolThread-twisted.internet.reactor-0 - dogpile.lock - DEBUG - Released creation lock
2019-01-17 06:53:11,459 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Mock builder groups: ['findutils', 'grep', 'info', 'shadow-utils', 'xz', 'rpm-build', 'sed', 'patch', 'bzip2', 'bash', 'util-linux', 'coreutils', 'which', 'fedora-release', 'make', 'diffutils', 'cpio', 'tar', 'redhat-rpm-config', 'gzip', 'unzip', 'gawk']
2019-01-17 06:53:11,460 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Adding dependencies dict_keys(['module-f30-build']) into buildroot for module standard-test-roles:f30:3020190117115307
2019-01-17 06:53:11,461 - PoolThread-twisted.internet.reactor-0 - koji - DEBUG - Opening new requests session
2019-01-17 06:53:11,463 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): koji.fedoraproject.org:443
2019-01-17 06:53:12,264 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - https://koji.fedoraproject.org:443 "POST /kojihub HTTP/1.1" 200 114
2019-01-17 06:53:12,266 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Connecting to koji 'https://koji.fedoraproject.org/kojihub'
2019-01-17 06:53:12,266 - PoolThread-twisted.internet.reactor-0 - koji - DEBUG - Opening new requests session
2019-01-17 06:53:12,268 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): koji.fedoraproject.org:443
2019-01-17 06:53:13,768 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - https://koji.fedoraproject.org:443 "POST /kojihub HTTP/1.1" 200 228
2019-01-17 06:53:13,769 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Downloading 0 packages from Koji tag module-f30-build to /root/modulebuild/cache/koji_tags/module-f30-build
2019-01-17 06:53:14,250 - PoolThread-twisted.internet.reactor-0 - urllib3.connectionpool - DEBUG - https://koji.fedoraproject.org:443 "POST /kojihub HTTP/1.1" 200 705
2019-01-17 06:53:14,256 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Cannot re-use. <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'wait', batch 0, state_reason None> is the first module build.
2019-01-17 06:53:14,256 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Starting build batch 1
2019-01-17 06:53:14,270 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Building module-build-macros.spec
2019-01-17 06:53:14,270 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Executing command: ['rpmbuild', '-bs', 'module-build-macros.spec', '--define', '_topdir /tmp/module_build_service-build-macrosz5mp2ars', '--define', '_sourcedir /tmp/module_build_service-build-macrosz5mp2ars/SOURCES'], stdout log: /dev/null
2019-01-17 06:53:14,290 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Wrote srpm into /tmp/module_build_service-build-macrosz5mp2ars/SRPMS/module-build-macros-0.1-1.module+f30+2+00ee978f.src.rpm
2019-01-17 06:53:14,301 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Starting building artifact module-build-macros: /tmp/module_build_service-build-macrosz5mp2ars/SRPMS/module-build-macros-0.1-1.module+f30+2+00ee978f.src.rpm
2019-01-17 06:53:14,302 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Executing command: ['mock', '-v', '-r', '/root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/config/mock-PoolThread-twisted.internet.reactor-0.cfg', '--init'], stdout log: /root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/results/module-build-macros-mock-stdout.log, stderr log: /root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/results/module-build-macros-mock-stderr.log
2019-01-17 06:54:58,581 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Executing command: ['mock', '-v', '-r', '/root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/config/mock-PoolThread-twisted.internet.reactor-0.cfg', '--no-clean', '--resultdir=/root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/results/PoolThread-twisted.internet.reactor-0', '--rebuild', '/tmp/module_build_service-build-macrosz5mp2ars/SRPMS/module-build-macros-0.1-1.module+f30+2+00ee978f.src.rpm'], stdout log: /root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/results/module-build-macros-mock-stdout.log, stderr log: /root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/results/module-build-macros-mock-stderr.log
2019-01-17 06:55:07,476 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'build', batch 1, state_reason None>, state 1->2
2019-01-17 06:55:07,487 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Done with wait: MBSModule, 2
2019-01-17 06:55:07,487 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Scheduling faked event KojiRepoChange('fake msg', 'module-standard-test-roles-f30-3020190117115307')
2019-01-17 06:55:07,490 - PoolThread-twisted.internet.reactor-0 - module_build_service.scheduler - DEBUG - stop_condition checking <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'build', batch 1, state_reason None>, got False
2019-01-17 06:55:07,490 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Going back to waiting on the incoming queue. Message handled: True
2019-01-17 06:55:07,490 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Worker thread picking a message.
2019-01-17 06:55:07,497 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Calling complete: KojiBuildChange, a faked internal message
2019-01-17 06:55:07,499 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Saw relevant component build of 'module-build-macros-0.1-1.module+f30+2+00ee978f.src.rpm' from 'a faked internal message'.
2019-01-17 06:55:07,507 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Machine arch setting: x86_64
2019-01-17 06:55:07,508 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - MockModuleBuilder initialized, tag_name=module-standard-test-roles-f30-3020190117115307, tag_dir=/root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307
2019-01-17 06:55:07,508 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Mock builder groups: ['findutils', 'grep', 'info', 'shadow-utils', 'xz', 'rpm-build', 'sed', 'patch', 'bzip2', 'bash', 'util-linux', 'coreutils', 'which', 'fedora-release', 'make', 'diffutils', 'cpio', 'tar', 'redhat-rpm-config', 'gzip', 'unzip', 'gawk']
2019-01-17 06:55:07,509 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Batch done. Tagging 1 component(s) in the build tag.
2019-01-17 06:55:07,509 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - ['module-build-macros-0.1-1.module+f30+2+00ee978f.src.rpm']
2019-01-17 06:55:07,509 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Creating repository in /root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/results
2019-01-17 06:55:07,532 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Executing command: ['/usr/bin/createrepo_c', '--pkglist', '/root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/results/pkglist', '/root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/results']
Directory walk done - 2 packages
Temporary output repo path: /root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307/results/.repodata/
Preparing sqlite DBs
Pool started (with 5 workers)
Pool finished
2019-01-17 06:55:07,575 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Tagging 0 component(s) in the dest tag.
2019-01-17 06:55:07,576 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Done with complete: KojiBuildChange, a faked internal message
2019-01-17 06:55:07,580 - PoolThread-twisted.internet.reactor-0 - module_build_service.scheduler - DEBUG - stop_condition checking <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'build', batch 1, state_reason None>, got False
2019-01-17 06:55:07,580 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Going back to waiting on the incoming queue. Message handled: True
2019-01-17 06:55:07,580 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Worker thread picking a message.
2019-01-17 06:55:07,585 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Handler is NO_OP: <lambda>: MBSModule, 3
2019-01-17 06:55:07,587 - PoolThread-twisted.internet.reactor-0 - module_build_service.scheduler - DEBUG - stop_condition checking <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'build', batch 1, state_reason None>, got False
2019-01-17 06:55:07,587 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Going back to waiting on the incoming queue. Message handled: True
2019-01-17 06:55:07,587 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Worker thread picking a message.
2019-01-17 06:55:07,596 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Calling done: KojiRepoChange, fake msg
2019-01-17 06:55:07,604 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Machine arch setting: x86_64
2019-01-17 06:55:07,604 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - MockModuleBuilder initialized, tag_name=module-standard-test-roles-f30-3020190117115307, tag_dir=/root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307
2019-01-17 06:55:07,604 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Mock builder groups: ['findutils', 'grep', 'module-build-macros', 'info', 'shadow-utils', 'xz', 'rpm-build', 'sed', 'patch', 'bzip2', 'bash', 'util-linux', 'coreutils', 'which', 'fedora-release', 'make', 'diffutils', 'cpio', 'tar', 'redhat-rpm-config', 'gzip', 'unzip', 'gawk']
2019-01-17 06:55:07,605 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Builder <module_build_service.builder.MockModuleBuilder.MockModuleBuilder object at 0x7f72bb727390> doesn't provide information about active tasks.
2019-01-17 06:55:07,605 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Starting build of next batch 2, [<ComponentBuild standard-test-roles, 2, state: None, task_id: None, batch: 2, state_reason: None>]
2019-01-17 06:55:07,607 - ThreadPoolExecutor-0_0 - module_build_service - INFO - Starting building artifact standard-test-roles: https://src.fedoraproject.org/rpms/standard-test-roles?#8ac521654e1a5f3ebae7cfe2494c9cbadcb1eb3f
2019-01-17 06:55:07,608 - ThreadPoolExecutor-0_0 - module_build_service - ERROR - 'No defined commands for https://src.fedoraproject.org/rpms/standard-test-roles?#8ac521654e1a5f3ebae7cfe2494c9cbadcb1eb3f'
Traceback (most recent call last):
File "/usr/lib/python3.7/site-packages/module_build_service/utils/batches.py", line 76, in start_build_component
artifact_name=c.package, source=c.scmurl)
File "/usr/lib/python3.7/site-packages/module_build_service/builder/MockModuleBuilder.py", line 496, in build
builder = SCMBuilder(mock_config, resultsdir, source, artifact_name)
File "/usr/lib/python3.7/site-packages/module_build_service/builder/MockModuleBuilder.py", line 544, in __init__
distgit_cmds = self._get_distgit_commands(source)
File "/usr/lib/python3.7/site-packages/module_build_service/builder/MockModuleBuilder.py", line 578, in _get_distgit_commands
raise KeyError("No defined commands for {}".format(source))
KeyError: 'No defined commands for https://src.fedoraproject.org/rpms/standard-test-roles?#8ac521654e1a5f3ebae7cfe2494c9cbadcb1eb3f'
2019-01-17 06:55:07,611 - PoolThread-twisted.internet.reactor-0 - module_build_service - ERROR - Could not process message handler. See the traceback.
Traceback (most recent call last):
File "/usr/lib/python3.7/site-packages/module_build_service/utils/batches.py", line 76, in start_build_component
artifact_name=c.package, source=c.scmurl)
File "/usr/lib/python3.7/site-packages/module_build_service/builder/MockModuleBuilder.py", line 496, in build
builder = SCMBuilder(mock_config, resultsdir, source, artifact_name)
File "/usr/lib/python3.7/site-packages/module_build_service/builder/MockModuleBuilder.py", line 544, in __init__
distgit_cmds = self._get_distgit_commands(source)
File "/usr/lib/python3.7/site-packages/module_build_service/builder/MockModuleBuilder.py", line 578, in _get_distgit_commands
raise KeyError("No defined commands for {}".format(source))
KeyError: 'No defined commands for https://src.fedoraproject.org/rpms/standard-test-roles?#8ac521654e1a5f3ebae7cfe2494c9cbadcb1eb3f'
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 1127, in _execute_context
context = constructor(dialect, self, conn, *args)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/default.py", line 639, in _init_compiled
self.cursor = self.create_cursor()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/default.py", line 954, in create_cursor
return self._dbapi_connection.cursor()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/pool.py", line 977, in cursor
return self.connection.cursor(*args, **kwargs)
sqlite3.ProgrammingError: SQLite objects created in a thread can only be used in that same thread. The object was created in thread id 140130756843264 and this is thread id 140130714617600.
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/session.py", line 2344, in _flush
flush_context.execute()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/unitofwork.py", line 391, in execute
rec.execute(self)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/unitofwork.py", line 556, in execute
uow
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/persistence.py", line 177, in save_obj
mapper, table, update)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/persistence.py", line 768, in _emit_update_statements
execute(statement, multiparams)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 948, in execute
return meth(self, multiparams, params)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/sql/elements.py", line 269, in _execute_on_connection
return connection._execute_clauseelement(self, multiparams, params)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 1060, in _execute_clauseelement
compiled_sql, distilled_params
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 1132, in _execute_context
None, None)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 1413, in _handle_dbapi_exception
exc_info
File "/usr/lib64/python3.7/site-packages/sqlalchemy/util/compat.py", line 265, in raise_from_cause
reraise(type(exception), exception, tb=exc_tb, cause=cause)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/util/compat.py", line 248, in reraise
raise value.with_traceback(tb)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 1127, in _execute_context
context = constructor(dialect, self, conn, *args)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/default.py", line 639, in _init_compiled
self.cursor = self.create_cursor()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/default.py", line 954, in create_cursor
return self._dbapi_connection.cursor()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/pool.py", line 977, in cursor
return self.connection.cursor(*args, **kwargs)
sqlalchemy.exc.ProgrammingError: (sqlite3.ProgrammingError) SQLite objects created in a thread can only be used in that same thread. The object was created in thread id 140130756843264 and this is thread id 140130714617600. [SQL: 'UPDATE module_builds SET state=?, time_modified=?, time_completed=?, batch=? WHERE module_builds.id = ?'] [parameters: [{'state': 4, 'time_completed': datetime.datetime(2019, 1, 17, 11, 55, 7, 609584), 'batch': 2, 'time_modified': datetime.datetime(2019, 1, 17, 11, 55, 7, 609584), 'module_builds_id': 2}]] (Background on this error at: http://sqlalche.me/e/f405)
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 704, in _rollback_impl
self.engine.dialect.do_rollback(self.connection)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/default.py", line 459, in do_rollback
dbapi_connection.rollback()
sqlite3.ProgrammingError: SQLite objects created in a thread can only be used in that same thread. The object was created in thread id 140130756843264 and this is thread id 140130714617600.
The above exception was the direct cause of the following exception:
Traceback (most recent call last):
File "/usr/lib/python3.7/site-packages/module_build_service/scheduler/consumer.py", line 238, in process_message
further_work = handler(conf, session, msg) or []
File "/usr/lib/python3.7/site-packages/module_build_service/scheduler/handlers/repos.py", line 143, in done
config, module_build, session, builder)
File "/usr/lib/python3.7/site-packages/module_build_service/utils/batches.py", line 317, in start_next_batch_build
config, module, session, builder, unbuilt_components_after_reuse)
File "/usr/lib/python3.7/site-packages/module_build_service/utils/batches.py", line 162, in continue_batch_build
future.result()
File "/usr/lib64/python3.7/concurrent/futures/_base.py", line 425, in result
return self.__get_result()
File "/usr/lib64/python3.7/concurrent/futures/_base.py", line 384, in __get_result
raise self._exception
File "/usr/lib64/python3.7/concurrent/futures/thread.py", line 57, in run
result = self.fn(*self.args, **self.kwargs)
File "/usr/lib/python3.7/site-packages/module_build_service/utils/batches.py", line 81, in start_build_component
c.module_build.transition(conf, models.BUILD_STATES['failed'])
File "/usr/lib/python3.7/site-packages/module_build_service/models.py", line 536, in transition
self.module_builds_trace.append(mbt)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/attributes.py", line 242, in __get__
return self.impl.get(instance_state(instance), dict_)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/attributes.py", line 601, in get
value = self.callable_(state, passive)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/strategies.py", line 631, in _load_for_state
session, state, primary_key_identity, passive)
File "<string>", line 1, in <lambda>
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/strategies.py", line 771, in _emit_lazyload
with_post_criteria(set_default_params).all()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/ext/baked.py", line 457, in all
return list(self)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/ext/baked.py", line 359, in __iter__
self.session._autoflush()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/session.py", line 1444, in _autoflush
util.raise_from_cause(e)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/util/compat.py", line 265, in raise_from_cause
reraise(type(exception), exception, tb=exc_tb, cause=cause)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/util/compat.py", line 249, in reraise
raise value
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/session.py", line 1434, in _autoflush
self.flush()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/session.py", line 2254, in flush
self._flush(objects)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/session.py", line 2380, in _flush
transaction.rollback(_capture_exception=True)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/util/langhelpers.py", line 76, in __exit__
compat.reraise(type_, value, traceback)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/util/compat.py", line 249, in reraise
raise value
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/session.py", line 2380, in _flush
transaction.rollback(_capture_exception=True)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/session.py", line 532, in rollback
util.reraise(*rollback_err)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/util/compat.py", line 249, in reraise
raise value
File "/usr/lib64/python3.7/site-packages/sqlalchemy/orm/session.py", line 497, in rollback
t[1].rollback()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 1632, in rollback
self._do_rollback()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 1670, in _do_rollback
self.connection._rollback_impl()
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 706, in _rollback_impl
self._handle_dbapi_exception(e, None, None, None, None)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 1413, in _handle_dbapi_exception
exc_info
File "/usr/lib64/python3.7/site-packages/sqlalchemy/util/compat.py", line 265, in raise_from_cause
reraise(type(exception), exception, tb=exc_tb, cause=cause)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/util/compat.py", line 248, in reraise
raise value.with_traceback(tb)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/base.py", line 704, in _rollback_impl
self.engine.dialect.do_rollback(self.connection)
File "/usr/lib64/python3.7/site-packages/sqlalchemy/engine/default.py", line 459, in do_rollback
dbapi_connection.rollback()
sqlalchemy.exc.ProgrammingError: (raised as a result of Query-invoked autoflush; consider using a session.no_autoflush block if this flush is occurring prematurely) (sqlite3.ProgrammingError) SQLite objects created in a thread can only be used in that same thread. The object was created in thread id 140130756843264 and this is thread id 140130714617600. (Background on this error at: http://sqlalche.me/e/f405)
2019-01-17 06:55:07,635 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'failed', batch 1, state_reason '(raised as a result of Query-invoked autoflush; consider using a session.no_autoflush block if this flush is occurring prematurely) (sqlite3.ProgrammingError) SQLite objects created in a thread can only be used in that same thread. The object was created in thread id 140130756843264 and this is thread id 140130714617600. (Background on this error at: http://sqlalche.me/e/f405)'>, state 2->4
2019-01-17 06:55:07,642 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Done with done: KojiRepoChange, fake msg
2019-01-17 06:55:07,645 - PoolThread-twisted.internet.reactor-0 - module_build_service.scheduler - DEBUG - stop_condition checking <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'failed', batch 1, state_reason '(raised as a result of Query-invoked autoflush; consider using a session.no_autoflush block if this flush is occurring prematurely) (sqlite3.ProgrammingError) SQLite objects created in a thread can only be used in that same thread. The object was created in thread id 140130756843264 and this is thread id 140130714617600. (Background on this error at: http://sqlalche.me/e/f405)'>, got True
2019-01-17 06:55:07,645 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Scheduling shutdown.
2019-01-17 06:55:07,645 - MainThread - moksha.hub - DEBUG - Stopping the CentralMokshaHub
2019-01-17 06:55:07,646 - MainThread - moksha.hub - DEBUG - Stopping consumer <module_build_service.scheduler.consumer.MBSConsumer object at 0x7f72be035c88>
2019-01-17 06:55:07,646 - MainThread - moksha.hub - DEBUG - Stopping the CentralMokshaHub
2019-01-17 06:55:07,646 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Going back to waiting on the incoming queue. Message handled: True
2019-01-17 06:55:07,646 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Worker thread picking a message.
2019-01-17 06:55:07,654 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - No module associated with msg a faked internal message
2019-01-17 06:55:07,656 - PoolThread-twisted.internet.reactor-0 - module_build_service.scheduler - DEBUG - stop_condition checking <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'failed', batch 1, state_reason '(raised as a result of Query-invoked autoflush; consider using a session.no_autoflush block if this flush is occurring prematurely) (sqlite3.ProgrammingError) SQLite objects created in a thread can only be used in that same thread. The object was created in thread id 140130756843264 and this is thread id 140130714617600. (Background on this error at: http://sqlalche.me/e/f405)'>, got True
2019-01-17 06:55:07,656 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Scheduling shutdown.
2019-01-17 06:55:07,656 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Going back to waiting on the incoming queue. Message handled: True
2019-01-17 06:55:07,656 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Worker thread picking a message.
2019-01-17 06:55:07,660 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Calling failed: MBSModule, 4
2019-01-17 06:55:07,666 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Machine arch setting: x86_64
2019-01-17 06:55:07,666 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - MockModuleBuilder initialized, tag_name=module-standard-test-roles-f30-3020190117115307, tag_dir=/root/modulebuild/builds/module-standard-test-roles-f30-3020190117115307
2019-01-17 06:55:07,666 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Mock builder groups: ['findutils', 'grep', 'module-build-macros', 'info', 'shadow-utils', 'xz', 'rpm-build', 'sed', 'patch', 'bzip2', 'bash', 'util-linux', 'coreutils', 'which', 'fedora-release', 'make', 'diffutils', 'cpio', 'tar', 'redhat-rpm-config', 'gzip', 'unzip', 'gawk']
2019-01-17 06:55:07,673 - PoolThread-twisted.internet.reactor-0 - module_build_service - DEBUG - Done with failed: MBSModule, 4
2019-01-17 06:55:07,675 - PoolThread-twisted.internet.reactor-0 - module_build_service.scheduler - DEBUG - stop_condition checking <ModuleBuild standard-test-roles, id=2, stream=f30, version=3020190117115307, state 'failed', batch 1, state_reason '(raised as a result of Query-invoked autoflush; consider using a session.no_autoflush block if this flush is occurring prematurely) (sqlite3.ProgrammingError) SQLite objects created in a thread can only be used in that same thread. The object was created in thread id 140130756843264 and this is thread id 140130714617600. (Background on this error at: http://sqlalche.me/e/f405)'>, got True
2019-01-17 06:55:07,675 - PoolThread-twisted.internet.reactor-0 - module_build_service - INFO - Scheduling shutdown.
2019-01-17 06:55:07,675 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Going back to waiting on the incoming queue. Message handled: True
2019-01-17 06:55:07,675 - PoolThread-twisted.internet.reactor-0 - moksha.hub - DEBUG - 'MBSConsumer' thread 140130756843264 | Worker thread exiting.
2019-01-17 06:55:07,676 - MainThread - moksha.hub - INFO - MokshaHub reactor stopped
Traceback (most recent call last):
File "/usr/bin/mbs-manager", line 11, in <module>
load_entry_point('module-build-service==2.12.1', 'console_scripts', 'mbs-manager')()
File "/usr/lib/python3.7/site-packages/module_build_service/manage.py", line 189, in manager_wrapper
manager.run()
File "/usr/lib/python3.7/site-packages/flask_script/__init__.py", line 417, in run
result = self.handle(argv[0], argv[1:])
File "/usr/lib/python3.7/site-packages/flask_script/__init__.py", line 386, in handle
res = handle(*args, **config)
File "/usr/lib/python3.7/site-packages/flask_script/commands.py", line 216, in __call__
return self.run(*args, **kwargs)
File "/usr/lib/python3.7/site-packages/module_build_service/manage.py", line 167, in build_module_locally
raise RuntimeError('Module build failed')
RuntimeError: Module build failed
Could not execute module_build_local: Failed to execute command.
[root@host-8-254-147 standard-test-roles]# klist
Ticket cache: KCM:0
Default principal: astepano@FEDORAPROJECT.ORG
Valid starting Expires Service principal
01/17/2019 06:26:44 01/18/2019 06:26:26 krbtgt/FEDORAPROJECT.ORG@FEDORAPROJECT.ORG
renew until 01/24/2019 06:26:26
[root@host-8-254-147 standard-test-roles]#
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment