builder: release-birch_firefox_bncr_sub
slave: bld-linux64-spot-090
starttime: 1515052125.51
results: success (0)
revision: 371ed7b063bd4562eba2623308707f3fa3cf7b26
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:45.510801) =========
master: http://buildbot-master77.bb.releng.use1.mozilla.com:8001/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:45.522114) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:45.522543) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:45.689272) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:45.689620) =========
bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'
in dir /builds/slave/rel-bir_fx_bncr_sub-0000000000/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
environment:
CCACHE_HASHDIR=
G_BROKEN_FILENAMES=1
HISTCONTROL=ignoredups
HISTSIZE=1000
HOME=/home/cltbld
HOSTNAME=bld-linux64-spot-090.build.releng.use1.mozilla.com
LANG=en_US.UTF-8
LESSOPEN=|/usr/bin/lesspipe.sh %s
LOGNAME=cltbld
MAIL=/var/spool/mail/cltbld
PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
PWD=/builds/slave/rel-bir_fx_bncr_sub-0000000000
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
_=/tools/buildbot/bin/python
using PTY: False
--2018-01-03 23:48:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: ‘archiver_client.py’
0K .......... . 100% 48.8M=0s
2018-01-03 23:48:46 (48.8 MB/s) - ‘archiver_client.py’ saved [12179/12179]
program finished with exit code 0
elapsedTime=0.479233
========= master_lag: 0.10 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:46.269716) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:46.270014) =========
rm -rf scripts properties
in dir /builds/slave/rel-bir_fx_bncr_sub-0000000000/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
CCACHE_HASHDIR=
G_BROKEN_FILENAMES=1
HISTCONTROL=ignoredups
HISTSIZE=1000
HOME=/home/cltbld
HOSTNAME=bld-linux64-spot-090.build.releng.use1.mozilla.com
LANG=en_US.UTF-8
LESSOPEN=|/usr/bin/lesspipe.sh %s
LOGNAME=cltbld
MAIL=/var/spool/mail/cltbld
PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
PWD=/builds/slave/rel-bir_fx_bncr_sub-0000000000
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.003018
========= master_lag: 0.19 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:46.462036) =========
========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2018-01-03 23:48:46.462868) =========
bash -c 'python archiver_client.py mozharness --repo projects/birch --rev 371ed7b063bd4562eba2623308707f3fa3cf7b26 --destination scripts --debug'
in dir /builds/slave/rel-bir_fx_bncr_sub-0000000000/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/birch --rev 371ed7b063bd4562eba2623308707f3fa3cf7b26 --destination scripts --debug']
environment:
CCACHE_HASHDIR=
G_BROKEN_FILENAMES=1
HISTCONTROL=ignoredups
HISTSIZE=1000
HOME=/home/cltbld
HOSTNAME=bld-linux64-spot-090.build.releng.use1.mozilla.com
LANG=en_US.UTF-8
LESSOPEN=|/usr/bin/lesspipe.sh %s
LOGNAME=cltbld
MAIL=/var/spool/mail/cltbld
PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
PWD=/builds/slave/rel-bir_fx_bncr_sub-0000000000
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
_=/tools/buildbot/bin/python
using PTY: False
2018-01-03 23:48:46,511 truncating revision to first 12 chars
2018-01-03 23:48:46,511 Setting DEBUG logging.
2018-01-03 23:48:46,511 attempt 1/10
2018-01-03 23:48:46,511 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/birch/371ed7b063bd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2018-01-03 23:48:47,455 attempt 1/10
2018-01-03 23:48:48,396 current task status: no status available at this point. state: STARTED
2018-01-03 23:48:48,396 sleeping for 9.00s (attempt 1/10)
2018-01-03 23:48:57,406 attempt 2/10
2018-01-03 23:48:58,176 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS
2018-01-03 23:48:58,711 unpacking tar archive at: birch-371ed7b063bd/testing/mozharness/
program finished with exit code 0
elapsedTime=13.250105
========= master_lag: 0.01 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2018-01-03 23:48:59.723606) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:59.723939) =========
script_repo_revision: 371ed7b063bd4562eba2623308707f3fa3cf7b26
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:59.724355) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:59.724643) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2018-01-03 23:48:59.739245) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/bouncer_submitter.py ...' (results: 0, elapsed: 42 secs) (at 2018-01-03 23:48:59.739562) =========
/tools/buildbot/bin/python scripts/scripts/bouncer_submitter.py -c releases/bouncer_firefox_release.py --credentials-file oauth.txt --bouncer-api-prefix https://admin-bouncer-releng.stage.mozaws.net/api --repo projects/birch
in dir /builds/slave/rel-bir_fx_bncr_sub-0000000000/. (timeout 3600 secs) (maxTime 14400 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/bouncer_submitter.py', '-c', 'releases/bouncer_firefox_release.py', '--credentials-file', 'oauth.txt', '--bouncer-api-prefix', 'https://admin-bouncer-releng.stage.mozaws.net/api', '--repo', 'projects/birch']
environment:
CCACHE_COMPRESS=1
CCACHE_DIR=/builds/ccache
CCACHE_HASHDIR=
CCACHE_UMASK=002
DISPLAY=:2
G_BROKEN_FILENAMES=1
HG_SHARE_BASE_DIR=/builds/hg-shared
HISTCONTROL=ignoredups
HISTSIZE=1000
HOME=/home/cltbld
HOSTNAME=bld-linux64-spot-090.build.releng.use1.mozilla.com
LANG=en_US.UTF-8
LC_ALL=C
LESSOPEN=|/usr/bin/lesspipe.sh %s
LOGNAME=cltbld
MAIL=/var/spool/mail/cltbld
MOZ_AUTOMATION=1
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_OBJDIR=obj-firefox
PATH=/tools/buildbot/bin:/usr/local/bin:/usr/lib64/ccache:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/tools/git/bin:/tools/python27/bin:/tools/python27-mercurial/bin:/home/cltbld/bin
PROPERTIES_FILE=/builds/slave/rel-bir_fx_bncr_sub-0000000000/buildprops.json
PWD=/builds/slave/rel-bir_fx_bncr_sub-0000000000
SHELL=/bin/bash
SHLVL=1
TERM=linux
TINDERBOX_OUTPUT=1
TMOUT=86400
TOOLTOOL_CACHE=/builds/tooltool_cache
TOOLTOOL_HOME=/builds
USER=cltbld
_=/tools/buildbot/bin/python
using PTY: False
23:48:59 INFO - MultiFileLogger online at 20180103 23:48:59 in /builds/slave/rel-bir_fx_bncr_sub-0000000000
23:48:59 INFO - Using buildbot properties:
23:48:59 INFO - {
23:48:59 INFO - "channels": "unknown",
23:48:59 INFO - "partial_versions": "57.0.2build2",
23:48:59 INFO - "buildnumber": 0,
23:48:59 INFO - "build_number": 2,
23:48:59 INFO - "platform": null,
23:48:59 INFO - "balrog_api_root": "https://balrog-admin.stage.mozaws.net/api",
23:48:59 INFO - "basedir": "/builds/slave/rel-bir_fx_bncr_sub-0000000000",
23:48:59 INFO - "appVersion": "58.0",
23:48:59 INFO - "slavename": "bld-linux64-spot-090",
23:48:59 INFO - "version": "58.0",
23:48:59 INFO - "master": "http://buildbot-master77.bb.releng.use1.mozilla.com:8001/",
23:48:59 INFO - "branch": "birch",
23:48:59 INFO - "revision": "371ed7b063bd4562eba2623308707f3fa3cf7b26",
23:48:59 INFO - "product": "firefox",
23:48:59 INFO - "repository": "https://hg.mozilla.org/projects/birch",
23:48:59 INFO - "buildername": "release-birch_firefox_bncr_sub",
23:48:59 INFO - "taskId": "IrXm2v8WQ4iJZ4qeYX2-PQ",
23:48:59 INFO - "next_version": "58.0.1",
23:48:59 INFO - "script_repo_revision": "371ed7b063bd4562eba2623308707f3fa3cf7b26",
23:48:59 INFO - "project": "",
23:48:59 INFO - "release_promotion": true,
23:48:59 INFO - "repo_path": "projects/birch"
23:48:59 INFO - }
23:48:59 INFO - Overriding product with firefox
23:48:59 INFO - Overriding version with 58.0
23:48:59 INFO - Overriding build_number with 2
23:48:59 INFO - Overriding revision with 371ed7b063bd4562eba2623308707f3fa3cf7b26
23:48:59 INFO - Run as scripts/scripts/bouncer_submitter.py -c releases/bouncer_firefox_release.py --credentials-file oauth.txt --bouncer-api-prefix https://admin-bouncer-releng.stage.mozaws.net/api --repo projects/birch
23:48:59 INFO - Dumping config to /builds/slave/rel-bir_fx_bncr_sub-0000000000/logs/localconfig.json.
23:48:59 INFO - {'append_to_log': False,
23:48:59 INFO - 'base_work_dir': '/builds/slave/rel-bir_fx_bncr_sub-0000000000',
23:48:59 INFO - 'bouncer-api-prefix': 'https://admin-bouncer-releng.stage.mozaws.net/api',
23:48:59 INFO - 'build_number': 2,
23:48:59 INFO - 'buildbot_json_path': 'buildprops.json',
23:48:59 INFO - 'config_files': ('releases/bouncer_firefox_release.py',),
23:48:59 INFO - 'credentials_file': 'oauth.txt',
23:48:59 INFO - 'log_level': 'info',
23:48:59 INFO - 'log_to_console': True,
23:48:59 INFO - 'opt_config_files': (),
23:48:59 INFO - 'partials': {'candidates-dir': {'add-locales': True,
23:48:59 INFO - 'check_uptake': False,
23:48:59 INFO - 'paths': {'linux': {'bouncer-platform': 'linux',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-i686/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
23:48:59 INFO - 'linux64': {'bouncer-platform': 'linux64',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-x86_64/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
23:48:59 INFO - 'macosx64': {'bouncer-platform': 'osx',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/mac/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
23:48:59 INFO - 'win32': {'bouncer-platform': 'win',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win32/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
23:48:59 INFO - 'win64': {'bouncer-platform': 'win64',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win64/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'}},
23:48:59 INFO - 'product-name': 'Firefox-%(version)sbuild%(build_number)s-Partial-%(prev_version)sbuild%(prev_build_number)s',
23:48:59 INFO - 'ssl-only': False},
23:48:59 INFO - 'releases-dir': {'add-locales': True,
23:48:59 INFO - 'check_uptake': True,
23:48:59 INFO - 'paths': {'linux': {'bouncer-platform': 'linux',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/linux-i686/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
23:48:59 INFO - 'linux64': {'bouncer-platform': 'linux64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/linux-x86_64/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
23:48:59 INFO - 'macosx64': {'bouncer-platform': 'osx',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/mac/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
23:48:59 INFO - 'win32': {'bouncer-platform': 'win',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/win32/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
23:48:59 INFO - 'win64': {'bouncer-platform': 'win64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/win64/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'}},
23:48:59 INFO - 'product-name': 'Firefox-%(version)s-Partial-%(prev_version)s',
23:48:59 INFO - 'ssl-only': False}},
23:48:59 INFO - 'prev_versions': (u'57.0.2build2',),
23:48:59 INFO - 'product': u'firefox',
23:48:59 INFO - 'products': {'complete-mar': {'add-locales': True,
23:48:59 INFO - 'check_uptake': True,
23:48:59 INFO - 'paths': {'linux': {'bouncer-platform': 'linux',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/linux-i686/:lang/firefox-%(version)s.complete.mar'},
23:48:59 INFO - 'linux64': {'bouncer-platform': 'linux64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/linux-x86_64/:lang/firefox-%(version)s.complete.mar'},
23:48:59 INFO - 'macosx64': {'bouncer-platform': 'osx',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/mac/:lang/firefox-%(version)s.complete.mar'},
23:48:59 INFO - 'win32': {'bouncer-platform': 'win',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/win32/:lang/firefox-%(version)s.complete.mar'},
23:48:59 INFO - 'win64': {'bouncer-platform': 'win64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/win64/:lang/firefox-%(version)s.complete.mar'}},
23:48:59 INFO - 'product-name': 'Firefox-%(version)s-Complete',
23:48:59 INFO - 'ssl-only': False},
23:48:59 INFO - 'complete-mar-bz2': {'add-locales': True,
23:48:59 INFO - 'check_uptake': True,
23:48:59 INFO - 'paths': {'linux': {'bouncer-platform': 'linux',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/linux-i686/:lang/firefox-%(version)s.bz2.complete.mar'},
23:48:59 INFO - 'linux64': {'bouncer-platform': 'linux64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/linux-x86_64/:lang/firefox-%(version)s.bz2.complete.mar'},
23:48:59 INFO - 'macosx64': {'bouncer-platform': 'osx',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/mac/:lang/firefox-%(version)s.bz2.complete.mar'},
23:48:59 INFO - 'win32': {'bouncer-platform': 'win',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/win32/:lang/firefox-%(version)s.bz2.complete.mar'},
23:48:59 INFO - 'win64': {'bouncer-platform': 'win64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/update/win64/:lang/firefox-%(version)s.bz2.complete.mar'}},
23:48:59 INFO - 'product-name': 'Firefox-%(version)s-Complete-bz2',
23:48:59 INFO - 'ssl-only': False},
23:48:59 INFO - 'complete-mar-candidates': {'add-locales': True,
23:48:59 INFO - 'check_uptake': False,
23:48:59 INFO - 'paths': {'linux': {'bouncer-platform': 'linux',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-i686/:lang/firefox-%(version)s.complete.mar'},
23:48:59 INFO - 'linux64': {'bouncer-platform': 'linux64',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-x86_64/:lang/firefox-%(version)s.complete.mar'},
23:48:59 INFO - 'macosx64': {'bouncer-platform': 'osx',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/mac/:lang/firefox-%(version)s.complete.mar'},
23:48:59 INFO - 'win32': {'bouncer-platform': 'win',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win32/:lang/firefox-%(version)s.complete.mar'},
23:48:59 INFO - 'win64': {'bouncer-platform': 'win64',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win64/:lang/firefox-%(version)s.complete.mar'}},
23:48:59 INFO - 'product-name': 'Firefox-%(version)sbuild%(build_number)s-Complete',
23:48:59 INFO - 'ssl-only': False},
23:48:59 INFO - 'complete-mar-candidates-bz2': {'add-locales': True,
23:48:59 INFO - 'check_uptake': False,
23:48:59 INFO - 'paths': {'linux': {'bouncer-platform': 'linux',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-i686/:lang/firefox-%(version)s.bz2.complete.mar'},
23:48:59 INFO - 'linux64': {'bouncer-platform': 'linux64',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-x86_64/:lang/firefox-%(version)s.bz2.complete.mar'},
23:48:59 INFO - 'macosx64': {'bouncer-platform': 'osx',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/mac/:lang/firefox-%(version)s.bz2.complete.mar'},
23:48:59 INFO - 'win32': {'bouncer-platform': 'win',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win32/:lang/firefox-%(version)s.bz2.complete.mar'},
23:48:59 INFO - 'win64': {'bouncer-platform': 'win64',
23:48:59 INFO - 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win64/:lang/firefox-%(version)s.bz2.complete.mar'}},
23:48:59 INFO - 'product-name': 'Firefox-%(version)sbuild%(build_number)s-Complete-bz2',
23:48:59 INFO - 'ssl-only': False},
23:48:59 INFO - 'installer': {'add-locales': True,
23:48:59 INFO - 'alias': 'firefox-latest',
23:48:59 INFO - 'check_uptake': True,
23:48:59 INFO - 'paths': {'linux': {'bouncer-platform': 'linux',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/linux-i686/:lang/firefox-%(version)s.tar.bz2'},
23:48:59 INFO - 'linux64': {'bouncer-platform': 'linux64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/linux-x86_64/:lang/firefox-%(version)s.tar.bz2'},
23:48:59 INFO - 'macosx64': {'bouncer-platform': 'osx',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/mac/:lang/Firefox%%20%(version)s.dmg'},
23:48:59 INFO - 'win32': {'bouncer-platform': 'win',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/win32/:lang/Firefox%%20Setup%%20%(version)s.exe'},
23:48:59 INFO - 'win64': {'bouncer-platform': 'win64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/win64/:lang/Firefox%%20Setup%%20%(version)s.exe'}},
23:48:59 INFO - 'product-name': 'Firefox-%(version)s',
23:48:59 INFO - 'ssl-only': False},
23:48:59 INFO - 'installer-ssl': {'add-locales': True,
23:48:59 INFO - 'alias': 'firefox-latest-ssl',
23:48:59 INFO - 'check_uptake': True,
23:48:59 INFO - 'paths': {'linux': {'bouncer-platform': 'linux',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/linux-i686/:lang/firefox-%(version)s.tar.bz2'},
23:48:59 INFO - 'linux64': {'bouncer-platform': 'linux64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/linux-x86_64/:lang/firefox-%(version)s.tar.bz2'},
23:48:59 INFO - 'macosx64': {'bouncer-platform': 'osx',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/mac/:lang/Firefox%%20%(version)s.dmg'},
23:48:59 INFO - 'win32': {'bouncer-platform': 'win',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/win32/:lang/Firefox%%20Setup%%20%(version)s.exe'},
23:48:59 INFO - 'win64': {'bouncer-platform': 'win64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/win64/:lang/Firefox%%20Setup%%20%(version)s.exe'}},
23:48:59 INFO - 'product-name': 'Firefox-%(version)s-SSL',
23:48:59 INFO - 'ssl-only': True},
23:48:59 INFO - 'stub-installer': {'add-locales': True,
23:48:59 INFO - 'alias': 'firefox-stub',
23:48:59 INFO - 'check_uptake': True,
23:48:59 INFO - 'paths': {'win32': {'bouncer-platform': 'win',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/win32/:lang/Firefox%%20Installer.exe'},
23:48:59 INFO - 'win64': {'bouncer-platform': 'win64',
23:48:59 INFO - 'path': '/firefox/releases/%(version)s/win32/:lang/Firefox%%20Installer.exe'}},
23:48:59 INFO - 'product-name': 'Firefox-%(version)s-stub',
23:48:59 INFO - 'ssl-only': True}},
23:48:59 INFO - 'repo': 'projects/birch',
23:48:59 INFO - 'revision': u'371ed7b063bd4562eba2623308707f3fa3cf7b26',
23:48:59 INFO - 'shipped-locales-url': 'https://hg.mozilla.org/%(repo)s/raw-file/%(revision)s/browser/locales/shipped-locales',
23:48:59 INFO - 'version': u'58.0',
23:48:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
23:48:59 INFO - 'work_dir': 'build'}
23:48:59 INFO - [mozharness: 2018-01-04 07:48:59.998805Z] Running clobber step.
23:48:59 INFO - Running main action method: clobber
23:48:59 INFO - rmtree: /builds/slave/rel-bir_fx_bncr_sub-0000000000/build
23:49:00 INFO - [mozharness: 2018-01-04 07:49:00.002169Z] Finished clobber step (success)
23:49:00 INFO - [mozharness: 2018-01-04 07:49:00.002507Z] Running download-shipped-locales step.
23:49:00 INFO - Running main action method: download_shipped_locales
23:49:00 INFO - mkdir: /builds/slave/rel-bir_fx_bncr_sub-0000000000/build
23:49:00 INFO - Downloading https://hg.mozilla.org/projects/birch/raw-file/371ed7b063bd4562eba2623308707f3fa3cf7b26/browser/locales/shipped-locales to /builds/slave/rel-bir_fx_bncr_sub-0000000000/build/shipped-locales
23:49:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://hg.mozilla.org/projects/birch/raw-file/371ed7b063bd4562eba2623308707f3fa3cf7b26/browser/locales/shipped-locales', 'file_name': '/builds/slave/rel-bir_fx_bncr_sub-0000000000/build/shipped-locales'}, attempt #1
23:49:01 INFO - Downloaded 15 bytes.
23:49:01 INFO - Reading from file /builds/slave/rel-bir_fx_bncr_sub-0000000000/build/shipped-locales
23:49:01 INFO - Contents:
23:49:01 INFO - de
23:49:01 INFO - en-US
23:49:01 INFO - zh-TW
23:49:01 INFO - [mozharness: 2018-01-04 07:49:01.318012Z] Finished download-shipped-locales step (success)
23:49:01 INFO - [mozharness: 2018-01-04 07:49:01.318213Z] Running submit step.
23:49:01 INFO - Running main action method: submit
23:49:01 INFO - Checking if Firefox-58.0-Complete already exists
23:49:01 INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-58.0-Complete', None), kwargs: {}, attempt #1
23:49:01 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-58.0-Complete
23:49:02 INFO - Server response
23:49:02 INFO -
23:49:02 INFO - Products found: 0
23:49:02 INFO - Adding complete-mar...
23:49:02 INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'zh-TW'], 'product': u'Firefox-58.0-Complete'}), kwargs: {}, attempt #1
23:49:02 INFO - POST data: languages=de&languages=en-US&languages=zh-TW&product=Firefox-58.0-Complete
23:49:02 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
23:49:03 INFO - Server response
23:49:03 INFO -
23:49:03 INFO - Adding paths...
23:49:03 INFO - linux (linux): /firefox/releases/58.0/update/linux-i686/:lang/firefox-58.0.complete.mar
23:49:03 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/linux-i686/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0-Complete', 'os': 'linux'}), kwargs: {}, attempt #1
23:49:03 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Flinux-i686%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0-Complete&os=linux
23:49:03 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:04 INFO - Server response
23:49:04 INFO - /firefox/releases/58.0/update/linux-i686/:lang/firefox-58.0.complete.mar
23:49:04 INFO - linux64 (linux64): /firefox/releases/58.0/update/linux-x86_64/:lang/firefox-58.0.complete.mar
23:49:04 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/linux-x86_64/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0-Complete', 'os': 'linux64'}), kwargs: {}, attempt #1
23:49:04 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Flinux-x86_64%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0-Complete&os=linux64
23:49:04 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:04 INFO - Server response
23:49:04 INFO - /firefox/releases/58.0/update/linux-x86_64/:lang/firefox-58.0.complete.mar
23:49:04 INFO - macosx64 (osx): /firefox/releases/58.0/update/mac/:lang/firefox-58.0.complete.mar
23:49:04 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/mac/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0-Complete', 'os': 'osx'}), kwargs: {}, attempt #1
23:49:04 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Fmac%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0-Complete&os=osx
23:49:04 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:05 INFO - Server response
23:49:05 INFO - /firefox/releases/58.0/update/mac/:lang/firefox-58.0.complete.mar
23:49:05 INFO - win32 (win): /firefox/releases/58.0/update/win32/:lang/firefox-58.0.complete.mar
23:49:05 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/win32/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0-Complete', 'os': 'win'}), kwargs: {}, attempt #1
23:49:05 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Fwin32%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0-Complete&os=win
23:49:05 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:06 INFO - Server response
23:49:06 INFO - /firefox/releases/58.0/update/win32/:lang/firefox-58.0.complete.mar
23:49:06 INFO - win64 (win64): /firefox/releases/58.0/update/win64/:lang/firefox-58.0.complete.mar
23:49:06 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/win64/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0-Complete', 'os': 'win64'}), kwargs: {}, attempt #1
23:49:06 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Fwin64%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0-Complete&os=win64
23:49:06 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:07 INFO - Server response
23:49:07 INFO - /firefox/releases/58.0/update/win64/:lang/firefox-58.0.complete.mar
23:49:07 INFO - Checking if Firefox-58.0-Complete-bz2 already exists
23:49:07 INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-58.0-Complete-bz2', None), kwargs: {}, attempt #1
23:49:07 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-58.0-Complete-bz2
23:49:08 INFO - Server response
23:49:08 INFO -
23:49:08 INFO - Products found: 1
23:49:08 WARNING - Product Firefox-58.0-Complete-bz2 already exists. Skipping...
23:49:08 INFO - Checking if Firefox-58.0build2-Complete already exists
23:49:08 INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-58.0build2-Complete', None), kwargs: {}, attempt #1
23:49:08 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-58.0build2-Complete
23:49:09 INFO - Server response
23:49:09 INFO -
23:49:09 INFO - Products found: 0
23:49:09 INFO - Adding complete-mar-candidates...
23:49:09 INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'zh-TW'], 'product': u'Firefox-58.0build2-Complete'}), kwargs: {}, attempt #1
23:49:09 INFO - POST data: languages=de&languages=en-US&languages=zh-TW&product=Firefox-58.0build2-Complete
23:49:09 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
23:49:10 INFO - Server response
23:49:10 INFO -
23:49:10 INFO - Adding paths...
23:49:10 INFO - linux (linux): /firefox/candidates/58.0-candidates/build2/update/linux-i686/:lang/firefox-58.0.complete.mar
23:49:10 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/linux-i686/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0build2-Complete', 'os': 'linux'}), kwargs: {}, attempt #1
23:49:10 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Flinux-i686%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0build2-Complete&os=linux
23:49:10 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:11 INFO - Server response
23:49:11 INFO - /firefox/candidates/58.0-candidates/build2/update/linux-i686/:lang/firefox-58.0.complete.mar
23:49:11 INFO - linux64 (linux64): /firefox/candidates/58.0-candidates/build2/update/linux-x86_64/:lang/firefox-58.0.complete.mar
23:49:11 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/linux-x86_64/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0build2-Complete', 'os': 'linux64'}), kwargs: {}, attempt #1
23:49:11 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Flinux-x86_64%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0build2-Complete&os=linux64
23:49:11 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:11 INFO - Server response
23:49:11 INFO - /firefox/candidates/58.0-candidates/build2/update/linux-x86_64/:lang/firefox-58.0.complete.mar
23:49:11 INFO - macosx64 (osx): /firefox/candidates/58.0-candidates/build2/update/mac/:lang/firefox-58.0.complete.mar
23:49:11 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/mac/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0build2-Complete', 'os': 'osx'}), kwargs: {}, attempt #1
23:49:11 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Fmac%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0build2-Complete&os=osx
23:49:11 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:12 INFO - Server response
23:49:12 INFO - /firefox/candidates/58.0-candidates/build2/update/mac/:lang/firefox-58.0.complete.mar
23:49:12 INFO - win32 (win): /firefox/candidates/58.0-candidates/build2/update/win32/:lang/firefox-58.0.complete.mar
23:49:12 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/win32/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0build2-Complete', 'os': 'win'}), kwargs: {}, attempt #1
23:49:12 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Fwin32%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0build2-Complete&os=win
23:49:12 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:13 INFO - Server response
23:49:13 INFO - /firefox/candidates/58.0-candidates/build2/update/win32/:lang/firefox-58.0.complete.mar
23:49:13 INFO - win64 (win64): /firefox/candidates/58.0-candidates/build2/update/win64/:lang/firefox-58.0.complete.mar
23:49:13 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/win64/:lang/firefox-58.0.complete.mar', 'product': u'Firefox-58.0build2-Complete', 'os': 'win64'}), kwargs: {}, attempt #1
23:49:13 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Fwin64%2F%3Alang%2Ffirefox-58.0.complete.mar&product=Firefox-58.0build2-Complete&os=win64
23:49:13 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:14 INFO - Server response
23:49:14 INFO - /firefox/candidates/58.0-candidates/build2/update/win64/:lang/firefox-58.0.complete.mar
23:49:14 INFO - Checking if Firefox-58.0build2-Complete-bz2 already exists
23:49:14 INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-58.0build2-Complete-bz2', None), kwargs: {}, attempt #1
23:49:14 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-58.0build2-Complete-bz2
23:49:15 INFO - Server response
23:49:15 INFO -
23:49:15 INFO - Products found: 0
23:49:15 INFO - Adding complete-mar-candidates-bz2...
23:49:15 INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'zh-TW'], 'product': u'Firefox-58.0build2-Complete-bz2'}), kwargs: {}, attempt #1
23:49:15 INFO - POST data: languages=de&languages=en-US&languages=zh-TW&product=Firefox-58.0build2-Complete-bz2
23:49:15 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
23:49:16 INFO - Server response
23:49:16 INFO -
23:49:16 INFO - Adding paths...
23:49:16 INFO - linux (linux): /firefox/candidates/58.0-candidates/build2/update/linux-i686/:lang/firefox-58.0.bz2.complete.mar
23:49:16 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/linux-i686/:lang/firefox-58.0.bz2.complete.mar', 'product': u'Firefox-58.0build2-Complete-bz2', 'os': 'linux'}), kwargs: {}, attempt #1
23:49:16 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Flinux-i686%2F%3Alang%2Ffirefox-58.0.bz2.complete.mar&product=Firefox-58.0build2-Complete-bz2&os=linux
23:49:16 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:17 INFO - Server response
23:49:17 INFO - /firefox/candidates/58.0-candidates/build2/update/linux-i686/:lang/firefox-58.0.bz2.complete.mar
23:49:17 INFO - linux64 (linux64): /firefox/candidates/58.0-candidates/build2/update/linux-x86_64/:lang/firefox-58.0.bz2.complete.mar
23:49:17 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/linux-x86_64/:lang/firefox-58.0.bz2.complete.mar', 'product': u'Firefox-58.0build2-Complete-bz2', 'os': 'linux64'}), kwargs: {}, attempt #1
23:49:17 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Flinux-x86_64%2F%3Alang%2Ffirefox-58.0.bz2.complete.mar&product=Firefox-58.0build2-Complete-bz2&os=linux64
23:49:17 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:17 INFO - Server response
23:49:17 INFO - /firefox/candidates/58.0-candidates/build2/update/linux-x86_64/:lang/firefox-58.0.bz2.complete.mar
23:49:17 INFO - macosx64 (osx): /firefox/candidates/58.0-candidates/build2/update/mac/:lang/firefox-58.0.bz2.complete.mar
23:49:17 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/mac/:lang/firefox-58.0.bz2.complete.mar', 'product': u'Firefox-58.0build2-Complete-bz2', 'os': 'osx'}), kwargs: {}, attempt #1
23:49:17 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Fmac%2F%3Alang%2Ffirefox-58.0.bz2.complete.mar&product=Firefox-58.0build2-Complete-bz2&os=osx
23:49:17 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:18 INFO - Server response
23:49:18 INFO - /firefox/candidates/58.0-candidates/build2/update/mac/:lang/firefox-58.0.bz2.complete.mar
23:49:18 INFO - win32 (win): /firefox/candidates/58.0-candidates/build2/update/win32/:lang/firefox-58.0.bz2.complete.mar
23:49:18 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/win32/:lang/firefox-58.0.bz2.complete.mar', 'product': u'Firefox-58.0build2-Complete-bz2', 'os': 'win'}), kwargs: {}, attempt #1
23:49:18 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Fwin32%2F%3Alang%2Ffirefox-58.0.bz2.complete.mar&product=Firefox-58.0build2-Complete-bz2&os=win
23:49:18 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:19 INFO - Server response
23:49:19 INFO - /firefox/candidates/58.0-candidates/build2/update/win32/:lang/firefox-58.0.bz2.complete.mar
23:49:19 INFO - win64 (win64): /firefox/candidates/58.0-candidates/build2/update/win64/:lang/firefox-58.0.bz2.complete.mar
23:49:19 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/win64/:lang/firefox-58.0.bz2.complete.mar', 'product': u'Firefox-58.0build2-Complete-bz2', 'os': 'win64'}), kwargs: {}, attempt #1
23:49:19 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Fwin64%2F%3Alang%2Ffirefox-58.0.bz2.complete.mar&product=Firefox-58.0build2-Complete-bz2&os=win64
23:49:19 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:20 INFO - Server response
23:49:20 INFO - /firefox/candidates/58.0-candidates/build2/update/win64/:lang/firefox-58.0.bz2.complete.mar
23:49:20 INFO - Checking if Firefox-58.0 already exists
23:49:20 INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-58.0', None), kwargs: {}, attempt #1
23:49:20 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-58.0
23:49:21 INFO - Server response
23:49:21 INFO -
23:49:21 INFO - Products found: 0
23:49:21 INFO - Adding installer...
23:49:21 INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'zh-TW'], 'product': u'Firefox-58.0'}), kwargs: {}, attempt #1
23:49:21 INFO - POST data: languages=de&languages=en-US&languages=zh-TW&product=Firefox-58.0
23:49:21 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
23:49:22 INFO - Server response
23:49:22 INFO -
23:49:22 INFO - Adding paths...
23:49:22 INFO - linux (linux): /firefox/releases/58.0/linux-i686/:lang/firefox-58.0.tar.bz2
23:49:22 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/linux-i686/:lang/firefox-58.0.tar.bz2', 'product': u'Firefox-58.0', 'os': 'linux'}), kwargs: {}, attempt #1
23:49:22 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Flinux-i686%2F%3Alang%2Ffirefox-58.0.tar.bz2&product=Firefox-58.0&os=linux
23:49:22 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:23 INFO - Server response
23:49:23 INFO - /firefox/releases/58.0/linux-i686/:lang/firefox-58.0.tar.bz2
23:49:23 INFO - linux64 (linux64): /firefox/releases/58.0/linux-x86_64/:lang/firefox-58.0.tar.bz2
23:49:23 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/linux-x86_64/:lang/firefox-58.0.tar.bz2', 'product': u'Firefox-58.0', 'os': 'linux64'}), kwargs: {}, attempt #1
23:49:23 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Flinux-x86_64%2F%3Alang%2Ffirefox-58.0.tar.bz2&product=Firefox-58.0&os=linux64
23:49:23 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:24 INFO - Server response
23:49:24 INFO - /firefox/releases/58.0/linux-x86_64/:lang/firefox-58.0.tar.bz2
23:49:24 INFO - macosx64 (osx): /firefox/releases/58.0/mac/:lang/Firefox%2058.0.dmg
23:49:24 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/mac/:lang/Firefox%2058.0.dmg', 'product': u'Firefox-58.0', 'os': 'osx'}), kwargs: {}, attempt #1
23:49:24 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fmac%2F%3Alang%2FFirefox%252058.0.dmg&product=Firefox-58.0&os=osx
23:49:24 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:24 INFO - Server response
23:49:24 INFO - /firefox/releases/58.0/mac/:lang/Firefox%2058.0.dmg
23:49:24 INFO - win32 (win): /firefox/releases/58.0/win32/:lang/Firefox%20Setup%2058.0.exe
23:49:24 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/win32/:lang/Firefox%20Setup%2058.0.exe', 'product': u'Firefox-58.0', 'os': 'win'}), kwargs: {}, attempt #1
23:49:24 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fwin32%2F%3Alang%2FFirefox%2520Setup%252058.0.exe&product=Firefox-58.0&os=win
23:49:24 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:25 INFO - Server response
23:49:25 INFO - /firefox/releases/58.0/win32/:lang/Firefox%20Setup%2058.0.exe
23:49:25 INFO - win64 (win64): /firefox/releases/58.0/win64/:lang/Firefox%20Setup%2058.0.exe
23:49:25 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/win64/:lang/Firefox%20Setup%2058.0.exe', 'product': u'Firefox-58.0', 'os': 'win64'}), kwargs: {}, attempt #1
23:49:25 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fwin64%2F%3Alang%2FFirefox%2520Setup%252058.0.exe&product=Firefox-58.0&os=win64
23:49:25 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:26 INFO - Server response
23:49:26 INFO - /firefox/releases/58.0/win64/:lang/Firefox%20Setup%2058.0.exe
23:49:26 INFO - Checking if Firefox-58.0-SSL already exists
23:49:26 INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-58.0-SSL', None), kwargs: {}, attempt #1
23:49:26 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-58.0-SSL
23:49:27 INFO - Server response
23:49:27 INFO -
23:49:27 INFO - Products found: 1
23:49:27 WARNING - Product Firefox-58.0-SSL already exists. Skipping...
23:49:27 INFO - Checking if Firefox-58.0-stub already exists
23:49:27 INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-58.0-stub', None), kwargs: {}, attempt #1
23:49:27 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-58.0-stub
23:49:28 INFO - Server response
23:49:28 INFO -
23:49:28 INFO - Products found: 0
23:49:28 INFO - Adding stub-installer...
23:49:28 INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'zh-TW'], 'product': u'Firefox-58.0-stub', 'ssl_only': 'true'}), kwargs: {}, attempt #1
23:49:28 INFO - POST data: languages=de&languages=en-US&languages=zh-TW&product=Firefox-58.0-stub&ssl_only=true
23:49:28 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
23:49:29 INFO - Server response
23:49:29 INFO -
23:49:29 INFO - Adding paths...
23:49:29 INFO - win32 (win): /firefox/releases/58.0/win32/:lang/Firefox%20Installer.exe
23:49:29 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/win32/:lang/Firefox%20Installer.exe', 'product': u'Firefox-58.0-stub', 'os': 'win'}), kwargs: {}, attempt #1
23:49:29 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fwin32%2F%3Alang%2FFirefox%2520Installer.exe&product=Firefox-58.0-stub&os=win
23:49:29 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:29 INFO - Server response
23:49:29 INFO - /firefox/releases/58.0/win32/:lang/Firefox%20Installer.exe
23:49:29 INFO - win64 (win64): /firefox/releases/58.0/win32/:lang/Firefox%20Installer.exe
23:49:29 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/win32/:lang/Firefox%20Installer.exe', 'product': u'Firefox-58.0-stub', 'os': 'win64'}), kwargs: {}, attempt #1
23:49:29 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fwin32%2F%3Alang%2FFirefox%2520Installer.exe&product=Firefox-58.0-stub&os=win64
23:49:29 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:30 INFO - Server response
23:49:30 INFO - /firefox/releases/58.0/win32/:lang/Firefox%20Installer.exe
23:49:30 INFO - Checking if Firefox-58.0build2-Partial-57.0.2build2 already exists
23:49:30 INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-58.0build2-Partial-57.0.2build2', None), kwargs: {}, attempt #1
23:49:30 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-58.0build2-Partial-57.0.2build2
23:49:31 INFO - Server response
23:49:31 INFO -
23:49:31 INFO - Products found: 0
23:49:31 INFO - Adding partial updates for Firefox-58.0build2-Partial-57.0.2build2
23:49:31 INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'zh-TW'], 'product': u'Firefox-58.0build2-Partial-57.0.2build2'}), kwargs: {}, attempt #1
23:49:31 INFO - POST data: languages=de&languages=en-US&languages=zh-TW&product=Firefox-58.0build2-Partial-57.0.2build2
23:49:31 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
23:49:32 INFO - Server response
23:49:32 INFO -
23:49:32 INFO - linux (linux): /firefox/candidates/58.0-candidates/build2/update/linux-i686/:lang/firefox-57.0.2-58.0.partial.mar
23:49:32 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/linux-i686/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0build2-Partial-57.0.2build2', 'os': 'linux'}), kwargs: {}, attempt #1
23:49:32 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Flinux-i686%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0build2-Partial-57.0.2build2&os=linux
23:49:32 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:33 INFO - Server response
23:49:33 INFO - /firefox/candidates/58.0-candidates/build2/update/linux-i686/:lang/firefox-57.0.2-58.0.partial.mar
23:49:33 INFO - linux64 (linux64): /firefox/candidates/58.0-candidates/build2/update/linux-x86_64/:lang/firefox-57.0.2-58.0.partial.mar
23:49:33 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/linux-x86_64/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0build2-Partial-57.0.2build2', 'os': 'linux64'}), kwargs: {}, attempt #1
23:49:33 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Flinux-x86_64%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0build2-Partial-57.0.2build2&os=linux64
23:49:33 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:34 INFO - Server response
23:49:34 INFO - /firefox/candidates/58.0-candidates/build2/update/linux-x86_64/:lang/firefox-57.0.2-58.0.partial.mar
23:49:34 INFO - macosx64 (osx): /firefox/candidates/58.0-candidates/build2/update/mac/:lang/firefox-57.0.2-58.0.partial.mar
23:49:34 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/mac/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0build2-Partial-57.0.2build2', 'os': 'osx'}), kwargs: {}, attempt #1
23:49:34 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Fmac%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0build2-Partial-57.0.2build2&os=osx
23:49:34 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:35 INFO - Server response
23:49:35 INFO - /firefox/candidates/58.0-candidates/build2/update/mac/:lang/firefox-57.0.2-58.0.partial.mar
23:49:35 INFO - win32 (win): /firefox/candidates/58.0-candidates/build2/update/win32/:lang/firefox-57.0.2-58.0.partial.mar
23:49:35 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/win32/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0build2-Partial-57.0.2build2', 'os': 'win'}), kwargs: {}, attempt #1
23:49:35 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Fwin32%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0build2-Partial-57.0.2build2&os=win
23:49:35 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:35 INFO - Server response
23:49:35 INFO - /firefox/candidates/58.0-candidates/build2/update/win32/:lang/firefox-57.0.2-58.0.partial.mar
23:49:35 INFO - win64 (win64): /firefox/candidates/58.0-candidates/build2/update/win64/:lang/firefox-57.0.2-58.0.partial.mar
23:49:35 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/58.0-candidates/build2/update/win64/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0build2-Partial-57.0.2build2', 'os': 'win64'}), kwargs: {}, attempt #1
23:49:35 INFO - POST data: path=%2Ffirefox%2Fcandidates%2F58.0-candidates%2Fbuild2%2Fupdate%2Fwin64%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0build2-Partial-57.0.2build2&os=win64
23:49:35 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:36 INFO - Server response
23:49:36 INFO - /firefox/candidates/58.0-candidates/build2/update/win64/:lang/firefox-57.0.2-58.0.partial.mar
23:49:36 INFO - Checking if Firefox-58.0-Partial-57.0.2 already exists
23:49:36 INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-58.0-Partial-57.0.2', None), kwargs: {}, attempt #1
23:49:36 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-58.0-Partial-57.0.2
23:49:37 INFO - Server response
23:49:37 INFO -
23:49:37 INFO - Products found: 0
23:49:37 INFO - Adding partial updates for Firefox-58.0-Partial-57.0.2
23:49:37 INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'zh-TW'], 'product': u'Firefox-58.0-Partial-57.0.2'}), kwargs: {}, attempt #1
23:49:37 INFO - POST data: languages=de&languages=en-US&languages=zh-TW&product=Firefox-58.0-Partial-57.0.2
23:49:37 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
23:49:38 INFO - Server response
23:49:38 INFO -
23:49:38 INFO - linux (linux): /firefox/releases/58.0/update/linux-i686/:lang/firefox-57.0.2-58.0.partial.mar
23:49:38 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/linux-i686/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0-Partial-57.0.2', 'os': 'linux'}), kwargs: {}, attempt #1
23:49:38 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Flinux-i686%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0-Partial-57.0.2&os=linux
23:49:38 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:39 INFO - Server response
23:49:39 INFO - /firefox/releases/58.0/update/linux-i686/:lang/firefox-57.0.2-58.0.partial.mar
23:49:39 INFO - linux64 (linux64): /firefox/releases/58.0/update/linux-x86_64/:lang/firefox-57.0.2-58.0.partial.mar
23:49:39 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/linux-x86_64/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0-Partial-57.0.2', 'os': 'linux64'}), kwargs: {}, attempt #1
23:49:39 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Flinux-x86_64%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0-Partial-57.0.2&os=linux64
23:49:39 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:40 INFO - Server response
23:49:40 INFO - /firefox/releases/58.0/update/linux-x86_64/:lang/firefox-57.0.2-58.0.partial.mar
23:49:40 INFO - macosx64 (osx): /firefox/releases/58.0/update/mac/:lang/firefox-57.0.2-58.0.partial.mar
23:49:40 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/mac/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0-Partial-57.0.2', 'os': 'osx'}), kwargs: {}, attempt #1
23:49:40 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Fmac%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0-Partial-57.0.2&os=osx
23:49:40 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:40 INFO - Server response
23:49:40 INFO - /firefox/releases/58.0/update/mac/:lang/firefox-57.0.2-58.0.partial.mar
23:49:40 INFO - win32 (win): /firefox/releases/58.0/update/win32/:lang/firefox-57.0.2-58.0.partial.mar
23:49:40 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/win32/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0-Partial-57.0.2', 'os': 'win'}), kwargs: {}, attempt #1
23:49:40 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Fwin32%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0-Partial-57.0.2&os=win
23:49:40 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:41 INFO - Server response
23:49:41 INFO - /firefox/releases/58.0/update/win32/:lang/firefox-57.0.2-58.0.partial.mar
23:49:41 INFO - win64 (win64): /firefox/releases/58.0/update/win64/:lang/firefox-57.0.2-58.0.partial.mar
23:49:41 INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/releases/58.0/update/win64/:lang/firefox-57.0.2-58.0.partial.mar', 'product': u'Firefox-58.0-Partial-57.0.2', 'os': 'win64'}), kwargs: {}, attempt #1
23:49:41 INFO - POST data: path=%2Ffirefox%2Freleases%2F58.0%2Fupdate%2Fwin64%2F%3Alang%2Ffirefox-57.0.2-58.0.partial.mar&product=Firefox-58.0-Partial-57.0.2&os=win64
23:49:41 INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
23:49:42 INFO - Server response
23:49:42 INFO - /firefox/releases/58.0/update/win64/:lang/firefox-57.0.2-58.0.partial.mar
23:49:42 INFO - [mozharness: 2018-01-04 07:49:42.569179Z] Finished submit step (success)
23:49:42 INFO - Running post-run listener: copy_logs_to_upload_dir
23:49:42 INFO - Copying logs to upload dir...
23:49:42 INFO - mkdir: /builds/slave/rel-bir_fx_bncr_sub-0000000000/build/upload/logs
23:49:42 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=42.838651
========= master_lag: 0.02 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/bouncer_submitter.py ...' (results: 0, elapsed: 42 secs) (at 2018-01-03 23:49:42.601625) =========
========= Started no change (results: 0, elapsed: 0 secs) (at 2018-01-03 23:49:42.602905) =========
bash -c 'for file in `ls -1`; do cat $file; done'
in dir /builds/slave/rel-bir_fx_bncr_sub-0000000000/properties (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
environment:
CCACHE_HASHDIR=
G_BROKEN_FILENAMES=1
HISTCONTROL=ignoredups
HISTSIZE=1000
HOME=/home/cltbld
HOSTNAME=bld-linux64-spot-090.build.releng.use1.mozilla.com
LANG=en_US.UTF-8
LESSOPEN=|/usr/bin/lesspipe.sh %s
LOGNAME=cltbld
MAIL=/var/spool/mail/cltbld
PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
PWD=/builds/slave/rel-bir_fx_bncr_sub-0000000000/properties
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.005272
========= master_lag: 0.05 =========
========= Finished no change (results: 0, elapsed: 0 secs) (at 2018-01-03 23:49:42.658953) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2018-01-03 23:49:42.659321) =========
rm -f oauth.txt
in dir /builds/slave/rel-bir_fx_bncr_sub-0000000000/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
CCACHE_HASHDIR=
G_BROKEN_FILENAMES=1
HISTCONTROL=ignoredups
HISTSIZE=1000
HOME=/home/cltbld
HOSTNAME=bld-linux64-spot-090.build.releng.use1.mozilla.com
LANG=en_US.UTF-8
LESSOPEN=|/usr/bin/lesspipe.sh %s
LOGNAME=cltbld
MAIL=/var/spool/mail/cltbld
PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
PWD=/builds/slave/rel-bir_fx_bncr_sub-0000000000
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.002519
========= master_lag: 0.05 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2018-01-03 23:49:42.707634) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2018-01-03 23:49:42.707969) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2018-01-03 23:49:42.708311) =========
========= Total master_lag: 0.42 =========