# Pastebin 7rPfM3lF
2018-05-22 21:33:23,902 DEBUG paramiko.transport: Sending global request "keepalive@lag.net"
2018-05-22 21:33:26,690 INFO zuul.GerritConnection: Updating <Change 0x7fe88834c630 20,18>
2018-05-22 21:33:26,690 DEBUG paramiko.transport: [chan 17] Max packet in: 32768 bytes
2018-05-22 21:33:26,691 DEBUG paramiko.transport: [chan 17] Max packet out: 32768 bytes
2018-05-22 21:33:26,691 DEBUG paramiko.transport: Secsh channel 17 opened.
2018-05-22 21:33:26,692 DEBUG paramiko.transport: [chan 17] Sesch channel 17 request ok
2018-05-22 21:33:26,736 DEBUG paramiko.transport: [chan 17] EOF received (17)
2018-05-22 21:33:26,737 DEBUG paramiko.transport: [chan 17] EOF sent (17)
2018-05-22 21:33:26,770 DEBUG paramiko.transport: [chan 18] Max packet in: 32768 bytes
2018-05-22 21:33:26,777 DEBUG paramiko.transport: [chan 18] Max packet out: 32768 bytes
2018-05-22 21:33:26,777 DEBUG paramiko.transport: Secsh channel 18 opened.
2018-05-22 21:33:26,778 DEBUG paramiko.transport: [chan 18] Sesch channel 18 request ok
2018-05-22 21:33:26,817 DEBUG paramiko.transport: [chan 18] EOF received (18)
2018-05-22 21:33:26,819 DEBUG paramiko.transport: [chan 18] EOF sent (18)
2018-05-22 21:33:26,819 INFO zuul.Pipeline.local.check: Adding change <Change 0x7fe88834c630 20,18> to queue <ChangeQueue check: demo-project> in <Pipeline check>
2018-05-22 21:33:27,225 INFO zuul.MergeClient: Merge <gear.Job 0x7fe8884d8550 handle: b'H:172.30.0.3:16' name: merger:merge unique: dbc12345b84047b595d64ff4f61aae4d> complete, merged: True, updated: False, commit: 72be445b0242c456d0db03ac37dc24b39250a720
2018-05-22 21:33:27,307 INFO zuul.Pipeline.local.check: Reporting start, action [<zuul.driver.gerrit.gerritreporter.GerritReporter object at 0x7fe888508e48>] item <QueueItem 0x7fe8884d82b0 for <Change 0x7fe88834c630 20,18> in check>
2018-05-22 21:33:27,307 DEBUG paramiko.transport: [chan 19] Max packet in: 32768 bytes
2018-05-22 21:33:27,308 DEBUG paramiko.transport: [chan 19] Max packet out: 32768 bytes
2018-05-22 21:33:27,308 DEBUG paramiko.transport: Secsh channel 19 opened.
2018-05-22 21:33:27,308 DEBUG paramiko.transport: [chan 19] Sesch channel 19 request ok
2018-05-22 21:33:27,349 DEBUG paramiko.transport: [chan 19] EOF received (19)
2018-05-22 21:33:27,349 DEBUG paramiko.transport: [chan 19] EOF sent (19)
2018-05-22 21:33:27,350 DEBUG paramiko.transport: [chan 20] Max packet in: 32768 bytes
2018-05-22 21:33:27,390 DEBUG paramiko.transport: [chan 20] Max packet out: 32768 bytes
2018-05-22 21:33:27,390 DEBUG paramiko.transport: Secsh channel 20 opened.
2018-05-22 21:33:27,391 DEBUG paramiko.transport: [chan 20] Sesch channel 20 request ok
2018-05-22 21:33:27,430 DEBUG paramiko.transport: [chan 20] EOF received (20)
2018-05-22 21:33:27,430 DEBUG paramiko.transport: [chan 20] EOF sent (20)
2018-05-22 21:33:27,440 INFO zuul.nodepool: Submitted node request <NodeRequest 200-0000000353 <NodeSet OrderedDict([(('container',), <Node None ['container']:centos-oci>)])OrderedDict()>>
2018-05-22 21:33:27,444 INFO zuul.nodepool: Submitted node request <NodeRequest 200-0000000354 <NodeSet OrderedDict([(('container',), <Node None ['container']:centos-oci>)])OrderedDict()>>
2018-05-22 21:33:27,452 INFO zuul.nodepool: Submitted node request <NodeRequest 200-0000000355 <NodeSet OrderedDict([(('main',), <Node None ['main']:dlrn-worker>)])OrderedDict()>>
2018-05-22 21:33:32,918 INFO zuul.nodepool: Node request <NodeRequest 200-0000000355 <NodeSet OrderedDict([(('main',), <Node 0000000188 ['main']:dlrn-worker>)])OrderedDict()>> fulfilled
2018-05-22 21:33:32,919 INFO zuul.nodepool: Accepting node request <NodeRequest 200-0000000355 <NodeSet OrderedDict([(('main',), <Node 0000000188 ['main']:dlrn-worker>)])OrderedDict()>>
2018-05-22 21:33:32,925 INFO zuul.Pipeline.local.check: Completed node request <NodeRequest 200-0000000355 <NodeSet OrderedDict([(('main',), <Node 0000000188 ['main']:dlrn-worker>)])OrderedDict()>> for job unit-tests of item <QueueItem 0x7fe8884d82b0 for <Change 0x7fe88834c630 20,18> in check> with nodes <NodeSet OrderedDict([(('main',), <Node 0000000188 ['main']:dlrn-worker>)])OrderedDict()>
2018-05-22 21:33:32,926 INFO zuul.nodepool: Setting nodeset <NodeSet OrderedDict([(('main',), <Node 0000000188 ['main']:dlrn-worker>)])OrderedDict()> in use
2018-05-22 21:33:32,929 INFO zuul.ExecutorClient: Execute job unit-tests (uuid: 050990a599f04aef8f26441265e0dcd7) on nodes <NodeSet OrderedDict([(('main',), <Node 0000000188 ['main']:dlrn-worker>)])OrderedDict()> for change <Change 0x7fe88834c630 20,18> with dependent changes [{'project': {'short_name': 'demo-project', 'canonical_name': 'managesf.sftests.com/demo-project', 'src_dir': 'src/managesf.sftests.com/demo-project', 'canonical_hostname': 'managesf.sftests.com', 'name': 'demo-project'}, 'patchset': '18', 'change_url': 'https://sftests.com/r/20', 'branch': 'master', 'change': '20'}]
2018-05-22 21:33:34,882 INFO zuul.ExecutorClient: Build <gear.Job 0x7fe8882df438 handle: b'H:172.30.0.3:17' name: executor:execute unique: 050990a599f04aef8f26441265e0dcd7> started
2018-05-22 21:33:35,779 INFO zuul.nodepool: Node request <NodeRequest 200-0000000353 <NodeSet OrderedDict([(('container',), <Node 0000000187 ['container']:centos-oci>)])OrderedDict()>> fulfilled
2018-05-22 21:33:35,779 INFO zuul.nodepool: Accepting node request <NodeRequest 200-0000000353 <NodeSet OrderedDict([(('container',), <Node 0000000187 ['container']:centos-oci>)])OrderedDict()>>
2018-05-22 21:33:35,788 INFO zuul.Pipeline.local.check: Completed node request <NodeRequest 200-0000000353 <NodeSet OrderedDict([(('container',), <Node 0000000187 ['container']:centos-oci>)])OrderedDict()>> for job tox-py27 of item <QueueItem 0x7fe8884d82b0 for <Change 0x7fe88834c630 20,18> in check> with nodes <NodeSet OrderedDict([(('container',), <Node 0000000187 ['container']:centos-oci>)])OrderedDict()>
2018-05-22 21:33:35,789 INFO zuul.nodepool: Setting nodeset <NodeSet OrderedDict([(('container',), <Node 0000000187 ['container']:centos-oci>)])OrderedDict()> in use
2018-05-22 21:33:35,792 INFO zuul.ExecutorClient: Execute job tox-py27 (uuid: 20e111b3183a4a769c6a1416d3cbe6da) on nodes <NodeSet OrderedDict([(('container',), <Node 0000000187 ['container']:centos-oci>)])OrderedDict()> for change <Change 0x7fe88834c630 20,18> with dependent changes [{'project': {'short_name': 'demo-project', 'canonical_name': 'managesf.sftests.com/demo-project', 'src_dir': 'src/managesf.sftests.com/demo-project', 'canonical_hostname': 'managesf.sftests.com', 'name': 'demo-project'}, 'patchset': '18', 'change_url': 'https://sftests.com/r/20', 'branch': 'master', 'change': '20'}]
2018-05-22 21:33:35,835 INFO zuul.nodepool: Node request <NodeRequest 200-0000000354 <NodeSet OrderedDict([(('container',), <Node 0000000186 ['container']:centos-oci>)])OrderedDict()>> fulfilled
2018-05-22 21:33:35,835 INFO zuul.nodepool: Accepting node request <NodeRequest 200-0000000354 <NodeSet OrderedDict([(('container',), <Node 0000000186 ['container']:centos-oci>)])OrderedDict()>>
2018-05-22 21:33:35,845 INFO zuul.Pipeline.local.check: Completed node request <NodeRequest 200-0000000354 <NodeSet OrderedDict([(('container',), <Node 0000000186 ['container']:centos-oci>)])OrderedDict()>> for job demo-rpmbuild of item <QueueItem 0x7fe8884d82b0 for <Change 0x7fe88834c630 20,18> in check> with nodes <NodeSet OrderedDict([(('container',), <Node 0000000186 ['container']:centos-oci>)])OrderedDict()>
2018-05-22 21:33:35,846 INFO zuul.nodepool: Setting nodeset <NodeSet OrderedDict([(('container',), <Node 0000000186 ['container']:centos-oci>)])OrderedDict()> in use
2018-05-22 21:33:35,851 INFO zuul.ExecutorClient: Execute job demo-rpmbuild (uuid: bd44a97ee8d6480f841535cb5845b629) on nodes <NodeSet OrderedDict([(('container',), <Node 0000000186 ['container']:centos-oci>)])OrderedDict()> for change <Change 0x7fe88834c630 20,18> with dependent changes [{'project': {'short_name': 'demo-project', 'canonical_name': 'managesf.sftests.com/demo-project', 'src_dir': 'src/managesf.sftests.com/demo-project', 'canonical_hostname': 'managesf.sftests.com', 'name': 'demo-project'}, 'patchset': '18', 'change_url': 'https://sftests.com/r/20', 'branch': 'master', 'change': '20'}]
2018-05-22 21:33:37,422 INFO zuul.GerritConnection: Updating <Change 0x7fe88834c630 20,18>
2018-05-22 21:33:37,423 DEBUG paramiko.transport: [chan 21] Max packet in: 32768 bytes
2018-05-22 21:33:37,424 DEBUG paramiko.transport: [chan 21] Max packet out: 32768 bytes
2018-05-22 21:33:37,423 DEBUG paramiko.transport: [chan 21] Max packet in: 32768 bytes
2018-05-22 21:33:37,424 DEBUG paramiko.transport: [chan 21] Max packet out: 32768 bytes
2018-05-22 21:33:37,424 DEBUG paramiko.transport: Secsh channel 21 opened.
2018-05-22 21:33:37,432 DEBUG paramiko.transport: [chan 21] Sesch channel 21 request ok
2018-05-22 21:33:37,475 DEBUG paramiko.transport: [chan 21] EOF received (21)
2018-05-22 21:33:37,476 DEBUG paramiko.transport: [chan 21] EOF sent (21)
2018-05-22 21:33:37,519 DEBUG paramiko.transport: [chan 22] Max packet in: 32768 bytes
2018-05-22 21:33:37,520 DEBUG paramiko.transport: [chan 22] Max packet out: 32768 bytes
2018-05-22 21:33:37,520 DEBUG paramiko.transport: Secsh channel 22 opened.
2018-05-22 21:33:37,521 DEBUG paramiko.transport: [chan 22] Sesch channel 22 request ok
2018-05-22 21:33:37,562 DEBUG paramiko.transport: [chan 22] EOF received (22)
2018-05-22 21:33:37,562 DEBUG paramiko.transport: [chan 22] EOF sent (22)
2018-05-22 21:33:37,797 INFO zuul.ExecutorClient: Build <gear.Job 0x7fe8882df780 handle: b'H:172.30.0.3:18' name: executor:execute unique: 20e111b3183a4a769c6a1416d3cbe6da> started
2018-05-22 21:33:39,415 INFO zuul.ExecutorClient: Build <gear.Job 0x7fe8882d7e10 handle: b'H:172.30.0.3:19' name: executor:execute unique: bd44a97ee8d6480f841535cb5845b629> started