builder: release-birch_firefox_bncr_sub
slave: bld-linux64-spot-303
starttime: 1519148214.47
results: success (0)
revision: 5a0b8528690ff2f2520af7fed0f99ae520789ce2

========= Started set props: master (results: 0, elapsed: 0 secs) (at 2018-02-20 09:36:54.470370) =========
master: http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2018-02-20 09:36:54.470806) =========

========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2018-02-20 09:36:54.471092) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2018-02-20 09:36:54.530360) =========

========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2018-02-20 09:36:54.530649) =========
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-303.build.releng.usw2.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-02-20 09:36:54--  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% 57.8M=0s

2018-02-20 09:36:54 (57.8 MB/s) - ‘archiver_client.py’ saved [12179/12179]

program finished with exit code 0
elapsedTime=0.253902
========= master_lag: 0.01 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2018-02-20 09:36:54.795899) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2018-02-20 09:36:54.796192) =========
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-303.build.releng.usw2.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.003524
========= master_lag: 0.05 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2018-02-20 09:36:54.845880) =========

========= Started 'bash -c ...' (results: 0, elapsed: 4 mins, 49 secs) (at 2018-02-20 09:36:54.846200) =========
bash -c 'python archiver_client.py mozharness --repo projects/birch --rev 5a0b8528690ff2f2520af7fed0f99ae520789ce2 --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 5a0b8528690ff2f2520af7fed0f99ae520789ce2 --destination scripts --debug']
 environment:
  CCACHE_HASHDIR=
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-303.build.releng.usw2.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-02-20 09:36:54,895 truncating revision to first 12 chars
2018-02-20 09:36:54,895 Setting DEBUG logging.
2018-02-20 09:36:54,895 attempt 1/10
2018-02-20 09:36:54,895 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/birch/5a0b8528690f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2018-02-20 09:36:55,561 attempt 1/10
2018-02-20 09:36:56,199 current task status: no status available at this point. state: PENDING
2018-02-20 09:36:56,199 sleeping for 9.00s (attempt 1/10)
2018-02-20 09:37:05,209 attempt 2/10
2018-02-20 09:37:05,830 current task status: no status available at this point. state: PENDING
2018-02-20 09:37:05,830 sleeping for 12.50s (attempt 2/10)
2018-02-20 09:37:18,343 attempt 3/10
2018-02-20 09:37:18,991 current task status: no status available at this point. state: PENDING
2018-02-20 09:37:18,992 sleeping for 18.75s (attempt 3/10)
2018-02-20 09:37:37,761 attempt 4/10
2018-02-20 09:37:38,402 current task status: no status available at this point. state: PENDING
2018-02-20 09:37:38,402 sleeping for 29.12s (attempt 4/10)
2018-02-20 09:38:07,556 attempt 5/10
2018-02-20 09:38:08,529 current task status: Task has expired from pending for too long. Re-creating task. state: RETRY
2018-02-20 09:38:08,529 sleeping for 44.69s (attempt 5/10)
2018-02-20 09:38:53,262 attempt 6/10
2018-02-20 09:38:53,882 current task status: no status available at this point. state: PENDING
2018-02-20 09:38:53,882 sleeping for 68.03s (attempt 6/10)
2018-02-20 09:40:01,981 attempt 7/10
2018-02-20 09:40:03,032 current task status: Task has expired from pending for too long. Re-creating task. state: RETRY
2018-02-20 09:40:03,032 sleeping for 100.00s (attempt 7/10)
2018-02-20 09:41:43,133 attempt 8/10
2018-02-20 09:41:43,665 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS
2018-02-20 09:41:43,777 unpacking tar archive at: birch-5a0b8528690f/testing/mozharness/
program finished with exit code 0
elapsedTime=289.101000
========= master_lag: 0.01 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 4 mins, 49 secs) (at 2018-02-20 09:41:43.957019) =========

========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2018-02-20 09:41:43.957354) =========
script_repo_revision: 5a0b8528690ff2f2520af7fed0f99ae520789ce2
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2018-02-20 09:41:43.957756) =========

========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2018-02-20 09:41:43.958035) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2018-02-20 09:41:43.967540) =========

========= Started '/tools/buildbot/bin/python scripts/scripts/bouncer_submitter.py ...' (results: 0, elapsed: 18 secs) (at 2018-02-20 09:41:43.967831) =========
/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-303.build.releng.usw2.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
09:41:44     INFO - MultiFileLogger online at 20180220 09:41:44 in /builds/slave/rel-bir_fx_bncr_sub-0000000000
09:41:44     INFO - Using buildbot properties:
09:41:44     INFO - {
09:41:44     INFO -     "project": "", 
09:41:44     INFO -     "product": "firefox", 
09:41:44     INFO -     "build_number": 14, 
09:41:44     INFO -     "taskId": "KbR-XphqTfKgkaL9WxWxnw", 
09:41:44     INFO -     "repository": "https://hg.mozilla.org/projects/birch", 
09:41:44     INFO -     "buildername": "release-birch_firefox_bncr_sub", 
09:41:44     INFO -     "balrog_api_root": "https://balrog-admin.stage.mozaws.net/api", 
09:41:44     INFO -     "basedir": "/builds/slave/rel-bir_fx_bncr_sub-0000000000", 
09:41:44     INFO -     "appVersion": "61.0", 
09:41:44     INFO -     "buildnumber": 19, 
09:41:44     INFO -     "slavename": "bld-linux64-spot-303", 
09:41:44     INFO -     "version": "61.0", 
09:41:44     INFO -     "release_promotion": true, 
09:41:44     INFO -     "platform": null, 
09:41:44     INFO -     "branch": "birch", 
09:41:44     INFO -     "script_repo_revision": "5a0b8528690ff2f2520af7fed0f99ae520789ce2", 
09:41:44     INFO -     "master": "http://buildbot-master73.bb.releng.usw2.mozilla.com:8001/", 
09:41:44     INFO -     "revision": "5a0b8528690ff2f2520af7fed0f99ae520789ce2", 
09:41:44     INFO -     "partial_versions": "58.0b13build1, 57.0build4", 
09:41:44     INFO -     "next_version": "61.0.1", 
09:41:44     INFO -     "repo_path": "projects/birch"
09:41:44     INFO - }
09:41:44     INFO - Overriding product with firefox
09:41:44     INFO - Overriding version with 61.0
09:41:44     INFO - Overriding build_number with 14
09:41:44     INFO - Overriding revision with 5a0b8528690ff2f2520af7fed0f99ae520789ce2
09:41:44     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
09:41:44     INFO - Dumping config to /builds/slave/rel-bir_fx_bncr_sub-0000000000/logs/localconfig.json.
09:41:44     INFO - {'append_to_log': False,
09:41:44     INFO -  'base_work_dir': '/builds/slave/rel-bir_fx_bncr_sub-0000000000',
09:41:44     INFO -  'bouncer-api-prefix': 'https://admin-bouncer-releng.stage.mozaws.net/api',
09:41:44     INFO -  'bouncer_prefix': 'https://download.mozilla.org/',
09:41:44     INFO -  'build_number': 14,
09:41:44     INFO -  'buildbot_json_path': 'buildprops.json',
09:41:44     INFO -  'config_files': ('releases/bouncer_firefox_release.py',),
09:41:44     INFO -  'credentials_file': 'oauth.txt',
09:41:44     INFO -  'log_level': 'info',
09:41:44     INFO -  'log_to_console': True,
09:41:44     INFO -  'opt_config_files': (),
09:41:44     INFO -  'partials': {'candidates-dir': {'add-locales': True,
09:41:44     INFO -                                  'check_uptake': False,
09:41:44     INFO -                                  'paths': {'linux': {'bouncer-platform': 'linux',
09:41:44     INFO -                                                      'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-i686/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
09:41:44     INFO -                                            'linux64': {'bouncer-platform': 'linux64',
09:41:44     INFO -                                                        'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-x86_64/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
09:41:44     INFO -                                            'macosx64': {'bouncer-platform': 'osx',
09:41:44     INFO -                                                         'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/mac/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
09:41:44     INFO -                                            'win32': {'bouncer-platform': 'win',
09:41:44     INFO -                                                      'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win32/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
09:41:44     INFO -                                            'win64': {'bouncer-platform': 'win64',
09:41:44     INFO -                                                      'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win64/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'}},
09:41:44     INFO -                                  'product-name': 'Firefox-%(version)sbuild%(build_number)s-Partial-%(prev_version)sbuild%(prev_build_number)s',
09:41:44     INFO -                                  'ssl-only': False},
09:41:44     INFO -               'releases-dir': {'add-locales': True,
09:41:44     INFO -                                'check_uptake': True,
09:41:44     INFO -                                'paths': {'linux': {'bouncer-platform': 'linux',
09:41:44     INFO -                                                    'path': '/firefox/releases/%(version)s/update/linux-i686/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
09:41:44     INFO -                                          'linux64': {'bouncer-platform': 'linux64',
09:41:44     INFO -                                                      'path': '/firefox/releases/%(version)s/update/linux-x86_64/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
09:41:44     INFO -                                          'macosx64': {'bouncer-platform': 'osx',
09:41:44     INFO -                                                       'path': '/firefox/releases/%(version)s/update/mac/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
09:41:44     INFO -                                          'win32': {'bouncer-platform': 'win',
09:41:44     INFO -                                                    'path': '/firefox/releases/%(version)s/update/win32/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'},
09:41:44     INFO -                                          'win64': {'bouncer-platform': 'win64',
09:41:44     INFO -                                                    'path': '/firefox/releases/%(version)s/update/win64/:lang/firefox-%(prev_version)s-%(version)s.partial.mar'}},
09:41:44     INFO -                                'product-name': 'Firefox-%(version)s-Partial-%(prev_version)s',
09:41:44     INFO -                                'ssl-only': False}},
09:41:44     INFO -  'prev_versions': (u'58.0b13build1', u'57.0build4'),
09:41:44     INFO -  'product': u'firefox',
09:41:44     INFO -  'products': {'complete-mar': {'add-locales': True,
09:41:44     INFO -                                'check_uptake': True,
09:41:44     INFO -                                'paths': {'linux': {'bouncer-platform': 'linux',
09:41:44     INFO -                                                    'path': '/firefox/releases/%(version)s/update/linux-i686/:lang/firefox-%(version)s.complete.mar'},
09:41:44     INFO -                                          'linux64': {'bouncer-platform': 'linux64',
09:41:44     INFO -                                                      'path': '/firefox/releases/%(version)s/update/linux-x86_64/:lang/firefox-%(version)s.complete.mar'},
09:41:44     INFO -                                          'macosx64': {'bouncer-platform': 'osx',
09:41:44     INFO -                                                       'path': '/firefox/releases/%(version)s/update/mac/:lang/firefox-%(version)s.complete.mar'},
09:41:44     INFO -                                          'win32': {'bouncer-platform': 'win',
09:41:44     INFO -                                                    'path': '/firefox/releases/%(version)s/update/win32/:lang/firefox-%(version)s.complete.mar'},
09:41:44     INFO -                                          'win64': {'bouncer-platform': 'win64',
09:41:44     INFO -                                                    'path': '/firefox/releases/%(version)s/update/win64/:lang/firefox-%(version)s.complete.mar'}},
09:41:44     INFO -                                'product-name': 'Firefox-%(version)s-Complete',
09:41:44     INFO -                                'ssl-only': False},
09:41:44     INFO -               'complete-mar-candidates': {'add-locales': True,
09:41:44     INFO -                                           'check_uptake': False,
09:41:44     INFO -                                           'paths': {'linux': {'bouncer-platform': 'linux',
09:41:44     INFO -                                                               'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-i686/:lang/firefox-%(version)s.complete.mar'},
09:41:44     INFO -                                                     'linux64': {'bouncer-platform': 'linux64',
09:41:44     INFO -                                                                 'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/linux-x86_64/:lang/firefox-%(version)s.complete.mar'},
09:41:44     INFO -                                                     'macosx64': {'bouncer-platform': 'osx',
09:41:44     INFO -                                                                  'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/mac/:lang/firefox-%(version)s.complete.mar'},
09:41:44     INFO -                                                     'win32': {'bouncer-platform': 'win',
09:41:44     INFO -                                                               'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win32/:lang/firefox-%(version)s.complete.mar'},
09:41:44     INFO -                                                     'win64': {'bouncer-platform': 'win64',
09:41:44     INFO -                                                               'path': '/firefox/candidates/%(version)s-candidates/build%(build_number)s/update/win64/:lang/firefox-%(version)s.complete.mar'}},
09:41:44     INFO -                                           'product-name': 'Firefox-%(version)sbuild%(build_number)s-Complete',
09:41:44     INFO -                                           'ssl-only': False},
09:41:44     INFO -               'installer': {'add-locales': True,
09:41:44     INFO -                             'alias': 'firefox-latest',
09:41:44     INFO -                             'check_uptake': True,
09:41:44     INFO -                             'paths': {'linux': {'bouncer-platform': 'linux',
09:41:44     INFO -                                                 'path': '/firefox/releases/%(version)s/linux-i686/:lang/firefox-%(version)s.tar.bz2'},
09:41:44     INFO -                                       'linux64': {'bouncer-platform': 'linux64',
09:41:44     INFO -                                                   'path': '/firefox/releases/%(version)s/linux-x86_64/:lang/firefox-%(version)s.tar.bz2'},
09:41:44     INFO -                                       'macosx64': {'bouncer-platform': 'osx',
09:41:44     INFO -                                                    'path': '/firefox/releases/%(version)s/mac/:lang/Firefox%%20%(version)s.dmg'},
09:41:44     INFO -                                       'win32': {'bouncer-platform': 'win',
09:41:44     INFO -                                                 'path': '/firefox/releases/%(version)s/win32/:lang/Firefox%%20Setup%%20%(version)s.exe'},
09:41:44     INFO -                                       'win64': {'bouncer-platform': 'win64',
09:41:44     INFO -                                                 'path': '/firefox/releases/%(version)s/win64/:lang/Firefox%%20Setup%%20%(version)s.exe'}},
09:41:44     INFO -                             'product-name': 'Firefox-%(version)s',
09:41:44     INFO -                             'ssl-only': False},
09:41:44     INFO -               'installer-ssl': {'add-locales': True,
09:41:44     INFO -                                 'alias': 'firefox-latest-ssl',
09:41:44     INFO -                                 'check_uptake': True,
09:41:44     INFO -                                 'paths': {'linux': {'bouncer-platform': 'linux',
09:41:44     INFO -                                                     'path': '/firefox/releases/%(version)s/linux-i686/:lang/firefox-%(version)s.tar.bz2'},
09:41:44     INFO -                                           'linux64': {'bouncer-platform': 'linux64',
09:41:44     INFO -                                                       'path': '/firefox/releases/%(version)s/linux-x86_64/:lang/firefox-%(version)s.tar.bz2'},
09:41:44     INFO -                                           'macosx64': {'bouncer-platform': 'osx',
09:41:44     INFO -                                                        'path': '/firefox/releases/%(version)s/mac/:lang/Firefox%%20%(version)s.dmg'},
09:41:44     INFO -                                           'win32': {'bouncer-platform': 'win',
09:41:44     INFO -                                                     'path': '/firefox/releases/%(version)s/win32/:lang/Firefox%%20Setup%%20%(version)s.exe'},
09:41:44     INFO -                                           'win64': {'bouncer-platform': 'win64',
09:41:44     INFO -                                                     'path': '/firefox/releases/%(version)s/win64/:lang/Firefox%%20Setup%%20%(version)s.exe'}},
09:41:44     INFO -                                 'product-name': 'Firefox-%(version)s-SSL',
09:41:44     INFO -                                 'ssl-only': True},
09:41:44     INFO -               'stub-installer': {'add-locales': True,
09:41:44     INFO -                                  'alias': 'firefox-stub',
09:41:44     INFO -                                  'check_uptake': True,
09:41:44     INFO -                                  'paths': {'win32': {'bouncer-platform': 'win',
09:41:44     INFO -                                                      'path': '/firefox/releases/%(version)s/win32/:lang/Firefox%%20Installer.exe'},
09:41:44     INFO -                                            'win64': {'bouncer-platform': 'win64',
09:41:44     INFO -                                                      'path': '/firefox/releases/%(version)s/win32/:lang/Firefox%%20Installer.exe'}},
09:41:44     INFO -                                  'product-name': 'Firefox-%(version)s-stub',
09:41:44     INFO -                                  'ssl-only': True}},
09:41:44     INFO -  'repo': 'projects/birch',
09:41:44     INFO -  'revision': u'5a0b8528690ff2f2520af7fed0f99ae520789ce2',
09:41:44     INFO -  'shipped-locales-url': 'https://hg.mozilla.org/%(repo)s/raw-file/%(revision)s/browser/locales/shipped-locales',
09:41:44     INFO -  'version': u'61.0',
09:41:44     INFO -  'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
09:41:44     INFO -  'work_dir': 'build'}
09:41:44     INFO - [mozharness: 2018-02-20 17:41:44.138018Z] Running clobber step.
09:41:44     INFO - Running main action method: clobber
09:41:44     INFO - rmtree: /builds/slave/rel-bir_fx_bncr_sub-0000000000/build
09:41:44     INFO - [mozharness: 2018-02-20 17:41:44.141477Z] Finished clobber step (success)
09:41:44     INFO - [mozharness: 2018-02-20 17:41:44.141822Z] Running download-shipped-locales step.
09:41:44     INFO - Running main action method: download_shipped_locales
09:41:44     INFO - mkdir: /builds/slave/rel-bir_fx_bncr_sub-0000000000/build
09:41:44     INFO - Downloading https://hg.mozilla.org/projects/birch/raw-file/5a0b8528690ff2f2520af7fed0f99ae520789ce2/browser/locales/shipped-locales to /builds/slave/rel-bir_fx_bncr_sub-0000000000/build/shipped-locales
09:41:44     INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://hg.mozilla.org/projects/birch/raw-file/5a0b8528690ff2f2520af7fed0f99ae520789ce2/browser/locales/shipped-locales', 'file_name': '/builds/slave/rel-bir_fx_bncr_sub-0000000000/build/shipped-locales'}, attempt #1
09:41:45     INFO - Downloaded 18 bytes.
09:41:45     INFO - Reading from file /builds/slave/rel-bir_fx_bncr_sub-0000000000/build/shipped-locales
09:41:45     INFO - Contents:
09:41:45     INFO -  de
09:41:45     INFO -  en-US
09:41:45     INFO -  ru
09:41:45     INFO -  zh-TW
09:41:45     INFO - [mozharness: 2018-02-20 17:41:45.249010Z] Finished download-shipped-locales step (success)
09:41:45     INFO - [mozharness: 2018-02-20 17:41:45.249209Z] Running submit step.
09:41:45     INFO - Running main action method: submit
09:41:45     INFO - Checking if Firefox-61.0-Complete already exists
09:41:45     INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-61.0-Complete', None), kwargs: {}, attempt #1
09:41:45     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-61.0-Complete
09:41:45     INFO - Server response
09:41:45     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="8379" name="Firefox-61.0-Complete"><language locale="ach"/><language locale="af"/><language locale="an"/><language locale="ar"/><language locale="as"/><language locale="ast"/><language locale="az"/><language locale="be"/><language locale="bg"/><language locale="bn-BD"/><language locale="bn-IN"/><language locale="br"/><language locale="bs"/><language locale="ca"/><language locale="cak"/><language locale="cs"/><language locale="cy"/><language locale="da"/><language locale="de"/><language locale="dsb"/><language locale="el"/><language locale="en-GB"/><language locale="en-US"/><language locale="en-ZA"/><language locale="eo"/><language locale="es-AR"/><language locale="es-CL"/><language locale="es-ES"/><language locale="es-MX"/><language locale="et"/><language locale="eu"/><language locale="fa"/><language locale="ff"/><language locale="fi"/><language locale="fr"/><language locale="fy-NL"/><language locale="ga-IE"/><language locale="gd"/><language locale="gl"/><language locale="gn"/><language locale="gu-IN"/><language locale="he"/><language locale="hi-IN"/><language locale="hr"/><language locale="hsb"/><language locale="hu"/><language locale="hy-AM"/><language locale="ia"/><language locale="id"/><language locale="is"/><language locale="it"/><language locale="ja"/><language locale="ja-JP-mac"/><language locale="ka"/><language locale="kab"/><language locale="kk"/><language locale="km"/><language locale="kn"/><language locale="ko"/><language locale="lij"/><language locale="lt"/><language locale="lv"/><language locale="mai"/><language locale="mk"/><language locale="ml"/><language locale="mr"/><language locale="ms"/><language locale="my"/><language locale="nb-NO"/><language locale="ne-NP"/><language locale="nl"/><language locale="nn-NO"/><language locale="or"/><language locale="pa-IN"/><language locale="pl"/><language locale="pt-BR"/><language locale="pt-PT"/><language locale="rm"/><language locale="ro"/><language locale="ru"/><language locale="si"/><language locale="sk"/><language locale="sl"/><language locale="son"/><language locale="sq"/><language locale="sr"/><language locale="sv-SE"/><language locale="ta"/><language locale="te"/><language locale="th"/><language locale="tr"/><language locale="uk"/><language locale="ur"/><language locale="uz"/><language locale="vi"/><language locale="xh"/><language locale="zh-CN"/><language locale="zh-TW"/></product></products>
09:41:45     INFO - Products found: 1
09:41:45  WARNING - Product Firefox-61.0-Complete already exists. Skipping...
09:41:45     INFO - Checking if Firefox-61.0build14-Complete already exists
09:41:45     INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-61.0build14-Complete', None), kwargs: {}, attempt #1
09:41:45     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-61.0build14-Complete
09:41:46     INFO - Server response
09:41:46     INFO - <?xml version="1.0" encoding="utf-8"?><products/>
09:41:46     INFO - Products found: 0
09:41:46     INFO - Adding complete-mar-candidates...
09:41:46     INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'ru', 'zh-TW'], 'product': u'Firefox-61.0build14-Complete'}), kwargs: {}, attempt #1
09:41:46     INFO - POST data: languages=de&languages=en-US&languages=ru&languages=zh-TW&product=Firefox-61.0build14-Complete
09:41:46     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
09:41:47     INFO - Server response
09:41:47     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="8466" name="Firefox-61.0build14-Complete"><language locale="de"/><language locale="en-US"/><language locale="ru"/><language locale="zh-TW"/></product></products>
09:41:47     INFO - Adding paths...
09:41:47     INFO - linux (linux): /firefox/candidates/61.0-candidates/build14/update/linux-i686/:lang/firefox-61.0.complete.mar
09:41:47     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/linux-i686/:lang/firefox-61.0.complete.mar', 'product': u'Firefox-61.0build14-Complete', 'os': 'linux'}), kwargs: {}, attempt #1
09:41:47     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Flinux-i686%2F%3Alang%2Ffirefox-61.0.complete.mar&product=Firefox-61.0build14-Complete&os=linux
09:41:47     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:47     INFO - Server response
09:41:47     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8466" name="Firefox-61.0build14-Complete"><location id="42583" os="linux">/firefox/candidates/61.0-candidates/build14/update/linux-i686/:lang/firefox-61.0.complete.mar</location></product></locations>
09:41:47     INFO - linux64 (linux64): /firefox/candidates/61.0-candidates/build14/update/linux-x86_64/:lang/firefox-61.0.complete.mar
09:41:47     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/linux-x86_64/:lang/firefox-61.0.complete.mar', 'product': u'Firefox-61.0build14-Complete', 'os': 'linux64'}), kwargs: {}, attempt #1
09:41:47     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Flinux-x86_64%2F%3Alang%2Ffirefox-61.0.complete.mar&product=Firefox-61.0build14-Complete&os=linux64
09:41:47     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:48     INFO - Server response
09:41:48     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8466" name="Firefox-61.0build14-Complete"><location id="42584" os="linux64">/firefox/candidates/61.0-candidates/build14/update/linux-x86_64/:lang/firefox-61.0.complete.mar</location></product></locations>
09:41:48     INFO - macosx64 (osx): /firefox/candidates/61.0-candidates/build14/update/mac/:lang/firefox-61.0.complete.mar
09:41:48     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/mac/:lang/firefox-61.0.complete.mar', 'product': u'Firefox-61.0build14-Complete', 'os': 'osx'}), kwargs: {}, attempt #1
09:41:48     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Fmac%2F%3Alang%2Ffirefox-61.0.complete.mar&product=Firefox-61.0build14-Complete&os=osx
09:41:48     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:49     INFO - Server response
09:41:49     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8466" name="Firefox-61.0build14-Complete"><location id="42585" os="osx">/firefox/candidates/61.0-candidates/build14/update/mac/:lang/firefox-61.0.complete.mar</location></product></locations>
09:41:49     INFO - win32 (win): /firefox/candidates/61.0-candidates/build14/update/win32/:lang/firefox-61.0.complete.mar
09:41:49     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/win32/:lang/firefox-61.0.complete.mar', 'product': u'Firefox-61.0build14-Complete', 'os': 'win'}), kwargs: {}, attempt #1
09:41:49     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Fwin32%2F%3Alang%2Ffirefox-61.0.complete.mar&product=Firefox-61.0build14-Complete&os=win
09:41:49     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:49     INFO - Server response
09:41:49     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8466" name="Firefox-61.0build14-Complete"><location id="42586" os="win">/firefox/candidates/61.0-candidates/build14/update/win32/:lang/firefox-61.0.complete.mar</location></product></locations>
09:41:49     INFO - win64 (win64): /firefox/candidates/61.0-candidates/build14/update/win64/:lang/firefox-61.0.complete.mar
09:41:49     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/win64/:lang/firefox-61.0.complete.mar', 'product': u'Firefox-61.0build14-Complete', 'os': 'win64'}), kwargs: {}, attempt #1
09:41:49     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Fwin64%2F%3Alang%2Ffirefox-61.0.complete.mar&product=Firefox-61.0build14-Complete&os=win64
09:41:49     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:50     INFO - Server response
09:41:50     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8466" name="Firefox-61.0build14-Complete"><location id="42587" os="win64">/firefox/candidates/61.0-candidates/build14/update/win64/:lang/firefox-61.0.complete.mar</location></product></locations>
09:41:50     INFO - Checking if Firefox-61.0 already exists
09:41:50     INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-61.0', None), kwargs: {}, attempt #1
09:41:50     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-61.0
09:41:51     INFO - Server response
09:41:51     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="8381" name="Firefox-61.0"><language locale="ach"/><language locale="af"/><language locale="an"/><language locale="ar"/><language locale="as"/><language locale="ast"/><language locale="az"/><language locale="be"/><language locale="bg"/><language locale="bn-BD"/><language locale="bn-IN"/><language locale="br"/><language locale="bs"/><language locale="ca"/><language locale="cak"/><language locale="cs"/><language locale="cy"/><language locale="da"/><language locale="de"/><language locale="dsb"/><language locale="el"/><language locale="en-GB"/><language locale="en-US"/><language locale="en-ZA"/><language locale="eo"/><language locale="es-AR"/><language locale="es-CL"/><language locale="es-ES"/><language locale="es-MX"/><language locale="et"/><language locale="eu"/><language locale="fa"/><language locale="ff"/><language locale="fi"/><language locale="fr"/><language locale="fy-NL"/><language locale="ga-IE"/><language locale="gd"/><language locale="gl"/><language locale="gn"/><language locale="gu-IN"/><language locale="he"/><language locale="hi-IN"/><language locale="hr"/><language locale="hsb"/><language locale="hu"/><language locale="hy-AM"/><language locale="ia"/><language locale="id"/><language locale="is"/><language locale="it"/><language locale="ja"/><language locale="ja-JP-mac"/><language locale="ka"/><language locale="kab"/><language locale="kk"/><language locale="km"/><language locale="kn"/><language locale="ko"/><language locale="lij"/><language locale="lt"/><language locale="lv"/><language locale="mai"/><language locale="mk"/><language locale="ml"/><language locale="mr"/><language locale="ms"/><language locale="my"/><language locale="nb-NO"/><language locale="ne-NP"/><language locale="nl"/><language locale="nn-NO"/><language locale="or"/><language locale="pa-IN"/><language locale="pl"/><language locale="pt-BR"/><language locale="pt-PT"/><language locale="rm"/><language locale="ro"/><language locale="ru"/><language locale="si"/><language locale="sk"/><language locale="sl"/><language locale="son"/><language locale="sq"/><language locale="sr"/><language locale="sv-SE"/><language locale="ta"/><language locale="te"/><language locale="th"/><language locale="tr"/><language locale="uk"/><language locale="ur"/><language locale="uz"/><language locale="vi"/><language locale="xh"/><language locale="zh-CN"/><language locale="zh-TW"/></product></products>
09:41:51     INFO - Products found: 1
09:41:51  WARNING - Product Firefox-61.0 already exists. Skipping...
09:41:51     INFO - Checking if Firefox-61.0-SSL already exists
09:41:51     INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-61.0-SSL', None), kwargs: {}, attempt #1
09:41:51     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-61.0-SSL
09:41:51     INFO - Server response
09:41:51     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="8382" name="Firefox-61.0-SSL"><language locale="ach"/><language locale="af"/><language locale="an"/><language locale="ar"/><language locale="as"/><language locale="ast"/><language locale="az"/><language locale="be"/><language locale="bg"/><language locale="bn-BD"/><language locale="bn-IN"/><language locale="br"/><language locale="bs"/><language locale="ca"/><language locale="cak"/><language locale="cs"/><language locale="cy"/><language locale="da"/><language locale="de"/><language locale="dsb"/><language locale="el"/><language locale="en-GB"/><language locale="en-US"/><language locale="en-ZA"/><language locale="eo"/><language locale="es-AR"/><language locale="es-CL"/><language locale="es-ES"/><language locale="es-MX"/><language locale="et"/><language locale="eu"/><language locale="fa"/><language locale="ff"/><language locale="fi"/><language locale="fr"/><language locale="fy-NL"/><language locale="ga-IE"/><language locale="gd"/><language locale="gl"/><language locale="gn"/><language locale="gu-IN"/><language locale="he"/><language locale="hi-IN"/><language locale="hr"/><language locale="hsb"/><language locale="hu"/><language locale="hy-AM"/><language locale="ia"/><language locale="id"/><language locale="is"/><language locale="it"/><language locale="ja"/><language locale="ja-JP-mac"/><language locale="ka"/><language locale="kab"/><language locale="kk"/><language locale="km"/><language locale="kn"/><language locale="ko"/><language locale="lij"/><language locale="lt"/><language locale="lv"/><language locale="mai"/><language locale="mk"/><language locale="ml"/><language locale="mr"/><language locale="ms"/><language locale="my"/><language locale="nb-NO"/><language locale="ne-NP"/><language locale="nl"/><language locale="nn-NO"/><language locale="or"/><language locale="pa-IN"/><language locale="pl"/><language locale="pt-BR"/><language locale="pt-PT"/><language locale="rm"/><language locale="ro"/><language locale="ru"/><language locale="si"/><language locale="sk"/><language locale="sl"/><language locale="son"/><language locale="sq"/><language locale="sr"/><language locale="sv-SE"/><language locale="ta"/><language locale="te"/><language locale="th"/><language locale="tr"/><language locale="uk"/><language locale="ur"/><language locale="uz"/><language locale="vi"/><language locale="xh"/><language locale="zh-CN"/><language locale="zh-TW"/></product></products>
09:41:51     INFO - Products found: 1
09:41:51  WARNING - Product Firefox-61.0-SSL already exists. Skipping...
09:41:51     INFO - Checking if Firefox-61.0-stub already exists
09:41:51     INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-61.0-stub', None), kwargs: {}, attempt #1
09:41:51     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-61.0-stub
09:41:52     INFO - Server response
09:41:52     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="8383" name="Firefox-61.0-stub"><language locale="ach"/><language locale="af"/><language locale="an"/><language locale="ar"/><language locale="as"/><language locale="ast"/><language locale="az"/><language locale="be"/><language locale="bg"/><language locale="bn-BD"/><language locale="bn-IN"/><language locale="br"/><language locale="bs"/><language locale="ca"/><language locale="cak"/><language locale="cs"/><language locale="cy"/><language locale="da"/><language locale="de"/><language locale="dsb"/><language locale="el"/><language locale="en-GB"/><language locale="en-US"/><language locale="en-ZA"/><language locale="eo"/><language locale="es-AR"/><language locale="es-CL"/><language locale="es-ES"/><language locale="es-MX"/><language locale="et"/><language locale="eu"/><language locale="fa"/><language locale="ff"/><language locale="fi"/><language locale="fr"/><language locale="fy-NL"/><language locale="ga-IE"/><language locale="gd"/><language locale="gl"/><language locale="gn"/><language locale="gu-IN"/><language locale="he"/><language locale="hi-IN"/><language locale="hr"/><language locale="hsb"/><language locale="hu"/><language locale="hy-AM"/><language locale="ia"/><language locale="id"/><language locale="is"/><language locale="it"/><language locale="ja"/><language locale="ja-JP-mac"/><language locale="ka"/><language locale="kab"/><language locale="kk"/><language locale="km"/><language locale="kn"/><language locale="ko"/><language locale="lij"/><language locale="lt"/><language locale="lv"/><language locale="mai"/><language locale="mk"/><language locale="ml"/><language locale="mr"/><language locale="ms"/><language locale="my"/><language locale="nb-NO"/><language locale="ne-NP"/><language locale="nl"/><language locale="nn-NO"/><language locale="or"/><language locale="pa-IN"/><language locale="pl"/><language locale="pt-BR"/><language locale="pt-PT"/><language locale="rm"/><language locale="ro"/><language locale="ru"/><language locale="si"/><language locale="sk"/><language locale="sl"/><language locale="son"/><language locale="sq"/><language locale="sr"/><language locale="sv-SE"/><language locale="ta"/><language locale="te"/><language locale="th"/><language locale="tr"/><language locale="uk"/><language locale="ur"/><language locale="uz"/><language locale="vi"/><language locale="xh"/><language locale="zh-CN"/><language locale="zh-TW"/></product></products>
09:41:52     INFO - Products found: 1
09:41:52  WARNING - Product Firefox-61.0-stub already exists. Skipping...
09:41:52     INFO - Checking if Firefox-61.0build14-Partial-58.0b13build1 already exists
09:41:52     INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-61.0build14-Partial-58.0b13build1', None), kwargs: {}, attempt #1
09:41:52     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-61.0build14-Partial-58.0b13build1
09:41:52     INFO - Server response
09:41:52     INFO - <?xml version="1.0" encoding="utf-8"?><products/>
09:41:52     INFO - Products found: 0
09:41:52     INFO - Adding partial updates for Firefox-61.0build14-Partial-58.0b13build1
09:41:52     INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'ru', 'zh-TW'], 'product': u'Firefox-61.0build14-Partial-58.0b13build1'}), kwargs: {}, attempt #1
09:41:52     INFO - POST data: languages=de&languages=en-US&languages=ru&languages=zh-TW&product=Firefox-61.0build14-Partial-58.0b13build1
09:41:52     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
09:41:53     INFO - Server response
09:41:53     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="8467" name="Firefox-61.0build14-Partial-58.0b13build1"><language locale="de"/><language locale="en-US"/><language locale="ru"/><language locale="zh-TW"/></product></products>
09:41:53     INFO - linux (linux): /firefox/candidates/61.0-candidates/build14/update/linux-i686/:lang/firefox-58.0b13-61.0.partial.mar
09:41:53     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/linux-i686/:lang/firefox-58.0b13-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-58.0b13build1', 'os': 'linux'}), kwargs: {}, attempt #1
09:41:53     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Flinux-i686%2F%3Alang%2Ffirefox-58.0b13-61.0.partial.mar&product=Firefox-61.0build14-Partial-58.0b13build1&os=linux
09:41:53     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:54     INFO - Server response
09:41:54     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8467" name="Firefox-61.0build14-Partial-58.0b13build1"><location id="42588" os="linux">/firefox/candidates/61.0-candidates/build14/update/linux-i686/:lang/firefox-58.0b13-61.0.partial.mar</location></product></locations>
09:41:54     INFO - linux64 (linux64): /firefox/candidates/61.0-candidates/build14/update/linux-x86_64/:lang/firefox-58.0b13-61.0.partial.mar
09:41:54     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/linux-x86_64/:lang/firefox-58.0b13-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-58.0b13build1', 'os': 'linux64'}), kwargs: {}, attempt #1
09:41:54     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Flinux-x86_64%2F%3Alang%2Ffirefox-58.0b13-61.0.partial.mar&product=Firefox-61.0build14-Partial-58.0b13build1&os=linux64
09:41:54     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:54     INFO - Server response
09:41:54     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8467" name="Firefox-61.0build14-Partial-58.0b13build1"><location id="42589" os="linux64">/firefox/candidates/61.0-candidates/build14/update/linux-x86_64/:lang/firefox-58.0b13-61.0.partial.mar</location></product></locations>
09:41:54     INFO - macosx64 (osx): /firefox/candidates/61.0-candidates/build14/update/mac/:lang/firefox-58.0b13-61.0.partial.mar
09:41:54     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/mac/:lang/firefox-58.0b13-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-58.0b13build1', 'os': 'osx'}), kwargs: {}, attempt #1
09:41:54     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Fmac%2F%3Alang%2Ffirefox-58.0b13-61.0.partial.mar&product=Firefox-61.0build14-Partial-58.0b13build1&os=osx
09:41:54     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:55     INFO - Server response
09:41:55     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8467" name="Firefox-61.0build14-Partial-58.0b13build1"><location id="42590" os="osx">/firefox/candidates/61.0-candidates/build14/update/mac/:lang/firefox-58.0b13-61.0.partial.mar</location></product></locations>
09:41:55     INFO - win32 (win): /firefox/candidates/61.0-candidates/build14/update/win32/:lang/firefox-58.0b13-61.0.partial.mar
09:41:55     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/win32/:lang/firefox-58.0b13-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-58.0b13build1', 'os': 'win'}), kwargs: {}, attempt #1
09:41:55     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Fwin32%2F%3Alang%2Ffirefox-58.0b13-61.0.partial.mar&product=Firefox-61.0build14-Partial-58.0b13build1&os=win
09:41:55     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:55     INFO - Server response
09:41:55     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8467" name="Firefox-61.0build14-Partial-58.0b13build1"><location id="42591" os="win">/firefox/candidates/61.0-candidates/build14/update/win32/:lang/firefox-58.0b13-61.0.partial.mar</location></product></locations>
09:41:55     INFO - win64 (win64): /firefox/candidates/61.0-candidates/build14/update/win64/:lang/firefox-58.0b13-61.0.partial.mar
09:41:55     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/win64/:lang/firefox-58.0b13-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-58.0b13build1', 'os': 'win64'}), kwargs: {}, attempt #1
09:41:55     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Fwin64%2F%3Alang%2Ffirefox-58.0b13-61.0.partial.mar&product=Firefox-61.0build14-Partial-58.0b13build1&os=win64
09:41:55     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:56     INFO - Server response
09:41:56     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8467" name="Firefox-61.0build14-Partial-58.0b13build1"><location id="42592" os="win64">/firefox/candidates/61.0-candidates/build14/update/win64/:lang/firefox-58.0b13-61.0.partial.mar</location></product></locations>
09:41:56     INFO - Checking if Firefox-61.0build14-Partial-57.0build4 already exists
09:41:56     INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-61.0build14-Partial-57.0build4', None), kwargs: {}, attempt #1
09:41:56     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-61.0build14-Partial-57.0build4
09:41:57     INFO - Server response
09:41:57     INFO - <?xml version="1.0" encoding="utf-8"?><products/>
09:41:57     INFO - Products found: 0
09:41:57     INFO - Adding partial updates for Firefox-61.0build14-Partial-57.0build4
09:41:57     INFO - retry: Calling _api_call with args: ('product_add/', {'languages': ['de', 'en-US', 'ru', 'zh-TW'], 'product': u'Firefox-61.0build14-Partial-57.0build4'}), kwargs: {}, attempt #1
09:41:57     INFO - POST data: languages=de&languages=en-US&languages=ru&languages=zh-TW&product=Firefox-61.0build14-Partial-57.0build4
09:41:57     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_add/
09:41:57     INFO - Server response
09:41:57     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="8468" name="Firefox-61.0build14-Partial-57.0build4"><language locale="de"/><language locale="en-US"/><language locale="ru"/><language locale="zh-TW"/></product></products>
09:41:57     INFO - linux (linux): /firefox/candidates/61.0-candidates/build14/update/linux-i686/:lang/firefox-57.0-61.0.partial.mar
09:41:57     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/linux-i686/:lang/firefox-57.0-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-57.0build4', 'os': 'linux'}), kwargs: {}, attempt #1
09:41:57     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Flinux-i686%2F%3Alang%2Ffirefox-57.0-61.0.partial.mar&product=Firefox-61.0build14-Partial-57.0build4&os=linux
09:41:57     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:58     INFO - Server response
09:41:58     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8468" name="Firefox-61.0build14-Partial-57.0build4"><location id="42593" os="linux">/firefox/candidates/61.0-candidates/build14/update/linux-i686/:lang/firefox-57.0-61.0.partial.mar</location></product></locations>
09:41:58     INFO - linux64 (linux64): /firefox/candidates/61.0-candidates/build14/update/linux-x86_64/:lang/firefox-57.0-61.0.partial.mar
09:41:58     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/linux-x86_64/:lang/firefox-57.0-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-57.0build4', 'os': 'linux64'}), kwargs: {}, attempt #1
09:41:58     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Flinux-x86_64%2F%3Alang%2Ffirefox-57.0-61.0.partial.mar&product=Firefox-61.0build14-Partial-57.0build4&os=linux64
09:41:58     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:59     INFO - Server response
09:41:59     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8468" name="Firefox-61.0build14-Partial-57.0build4"><location id="42594" os="linux64">/firefox/candidates/61.0-candidates/build14/update/linux-x86_64/:lang/firefox-57.0-61.0.partial.mar</location></product></locations>
09:41:59     INFO - macosx64 (osx): /firefox/candidates/61.0-candidates/build14/update/mac/:lang/firefox-57.0-61.0.partial.mar
09:41:59     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/mac/:lang/firefox-57.0-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-57.0build4', 'os': 'osx'}), kwargs: {}, attempt #1
09:41:59     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Fmac%2F%3Alang%2Ffirefox-57.0-61.0.partial.mar&product=Firefox-61.0build14-Partial-57.0build4&os=osx
09:41:59     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:41:59     INFO - Server response
09:41:59     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8468" name="Firefox-61.0build14-Partial-57.0build4"><location id="42595" os="osx">/firefox/candidates/61.0-candidates/build14/update/mac/:lang/firefox-57.0-61.0.partial.mar</location></product></locations>
09:41:59     INFO - win32 (win): /firefox/candidates/61.0-candidates/build14/update/win32/:lang/firefox-57.0-61.0.partial.mar
09:41:59     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/win32/:lang/firefox-57.0-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-57.0build4', 'os': 'win'}), kwargs: {}, attempt #1
09:41:59     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Fwin32%2F%3Alang%2Ffirefox-57.0-61.0.partial.mar&product=Firefox-61.0build14-Partial-57.0build4&os=win
09:41:59     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:42:00     INFO - Server response
09:42:00     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8468" name="Firefox-61.0build14-Partial-57.0build4"><location id="42596" os="win">/firefox/candidates/61.0-candidates/build14/update/win32/:lang/firefox-57.0-61.0.partial.mar</location></product></locations>
09:42:00     INFO - win64 (win64): /firefox/candidates/61.0-candidates/build14/update/win64/:lang/firefox-57.0-61.0.partial.mar
09:42:00     INFO - retry: Calling _api_call with args: ('location_add/', {'path': u'/firefox/candidates/61.0-candidates/build14/update/win64/:lang/firefox-57.0-61.0.partial.mar', 'product': u'Firefox-61.0build14-Partial-57.0build4', 'os': 'win64'}), kwargs: {}, attempt #1
09:42:00     INFO - POST data: path=%2Ffirefox%2Fcandidates%2F61.0-candidates%2Fbuild14%2Fupdate%2Fwin64%2F%3Alang%2Ffirefox-57.0-61.0.partial.mar&product=Firefox-61.0build14-Partial-57.0build4&os=win64
09:42:00     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/location_add/
09:42:00     INFO - Server response
09:42:00     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="8468" name="Firefox-61.0build14-Partial-57.0build4"><location id="42597" os="win64">/firefox/candidates/61.0-candidates/build14/update/win64/:lang/firefox-57.0-61.0.partial.mar</location></product></locations>
09:42:00     INFO - Checking if Firefox-61.0-Partial-58.0b13 already exists
09:42:00     INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-61.0-Partial-58.0b13', None), kwargs: {}, attempt #1
09:42:00     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-61.0-Partial-58.0b13
09:42:01     INFO - Server response
09:42:01     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="8420" name="Firefox-61.0-Partial-58.0b13"><language locale="ach"/><language locale="af"/><language locale="an"/><language locale="ar"/><language locale="as"/><language locale="ast"/><language locale="az"/><language locale="be"/><language locale="bg"/><language locale="bn-BD"/><language locale="bn-IN"/><language locale="br"/><language locale="bs"/><language locale="ca"/><language locale="cak"/><language locale="cs"/><language locale="cy"/><language locale="da"/><language locale="de"/><language locale="dsb"/><language locale="el"/><language locale="en-GB"/><language locale="en-US"/><language locale="en-ZA"/><language locale="eo"/><language locale="es-AR"/><language locale="es-CL"/><language locale="es-ES"/><language locale="es-MX"/><language locale="et"/><language locale="eu"/><language locale="fa"/><language locale="ff"/><language locale="fi"/><language locale="fr"/><language locale="fy-NL"/><language locale="ga-IE"/><language locale="gd"/><language locale="gl"/><language locale="gn"/><language locale="gu-IN"/><language locale="he"/><language locale="hi-IN"/><language locale="hr"/><language locale="hsb"/><language locale="hu"/><language locale="hy-AM"/><language locale="ia"/><language locale="id"/><language locale="is"/><language locale="it"/><language locale="ja"/><language locale="ja-JP-mac"/><language locale="ka"/><language locale="kab"/><language locale="kk"/><language locale="km"/><language locale="kn"/><language locale="ko"/><language locale="lij"/><language locale="lt"/><language locale="lv"/><language locale="mai"/><language locale="mk"/><language locale="ml"/><language locale="mr"/><language locale="ms"/><language locale="my"/><language locale="nb-NO"/><language locale="ne-NP"/><language locale="nl"/><language locale="nn-NO"/><language locale="or"/><language locale="pa-IN"/><language locale="pl"/><language locale="pt-BR"/><language locale="pt-PT"/><language locale="rm"/><language locale="ro"/><language locale="ru"/><language locale="si"/><language locale="sk"/><language locale="sl"/><language locale="son"/><language locale="sq"/><language locale="sr"/><language locale="sv-SE"/><language locale="ta"/><language locale="te"/><language locale="th"/><language locale="tr"/><language locale="uk"/><language locale="ur"/><language locale="uz"/><language locale="vi"/><language locale="xh"/><language locale="zh-CN"/><language locale="zh-TW"/></product></products>
09:42:01     INFO - Products found: 1
09:42:01  WARNING - Product Firefox-61.0-Partial-58.0b13 already exists. Skipping...
09:42:01     INFO - Checking if Firefox-61.0-Partial-57.0 already exists
09:42:01     INFO - retry: Calling _api_call with args: (u'product_show?product=Firefox-61.0-Partial-57.0', None), kwargs: {}, attempt #1
09:42:01     INFO - Submitting to https://admin-bouncer-releng.stage.mozaws.net/api/product_show?product=Firefox-61.0-Partial-57.0
09:42:02     INFO - Server response
09:42:02     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="8385" name="Firefox-61.0-Partial-57.0"><language locale="ach"/><language locale="af"/><language locale="an"/><language locale="ar"/><language locale="as"/><language locale="ast"/><language locale="az"/><language locale="be"/><language locale="bg"/><language locale="bn-BD"/><language locale="bn-IN"/><language locale="br"/><language locale="bs"/><language locale="ca"/><language locale="cak"/><language locale="cs"/><language locale="cy"/><language locale="da"/><language locale="de"/><language locale="dsb"/><language locale="el"/><language locale="en-GB"/><language locale="en-US"/><language locale="en-ZA"/><language locale="eo"/><language locale="es-AR"/><language locale="es-CL"/><language locale="es-ES"/><language locale="es-MX"/><language locale="et"/><language locale="eu"/><language locale="fa"/><language locale="ff"/><language locale="fi"/><language locale="fr"/><language locale="fy-NL"/><language locale="ga-IE"/><language locale="gd"/><language locale="gl"/><language locale="gn"/><language locale="gu-IN"/><language locale="he"/><language locale="hi-IN"/><language locale="hr"/><language locale="hsb"/><language locale="hu"/><language locale="hy-AM"/><language locale="ia"/><language locale="id"/><language locale="is"/><language locale="it"/><language locale="ja"/><language locale="ja-JP-mac"/><language locale="ka"/><language locale="kab"/><language locale="kk"/><language locale="km"/><language locale="kn"/><language locale="ko"/><language locale="lij"/><language locale="lt"/><language locale="lv"/><language locale="mai"/><language locale="mk"/><language locale="ml"/><language locale="mr"/><language locale="ms"/><language locale="my"/><language locale="nb-NO"/><language locale="ne-NP"/><language locale="nl"/><language locale="nn-NO"/><language locale="or"/><language locale="pa-IN"/><language locale="pl"/><language locale="pt-BR"/><language locale="pt-PT"/><language locale="rm"/><language locale="ro"/><language locale="ru"/><language locale="si"/><language locale="sk"/><language locale="sl"/><language locale="son"/><language locale="sq"/><language locale="sr"/><language locale="sv-SE"/><language locale="ta"/><language locale="te"/><language locale="th"/><language locale="tr"/><language locale="uk"/><language locale="ur"/><language locale="uz"/><language locale="vi"/><language locale="xh"/><language locale="zh-CN"/><language locale="zh-TW"/></product></products>
09:42:02     INFO - Products found: 1
09:42:02  WARNING - Product Firefox-61.0-Partial-57.0 already exists. Skipping...
09:42:02     INFO - [mozharness: 2018-02-20 17:42:02.023827Z] Finished submit step (success)
09:42:02     INFO - Running post-run listener: copy_logs_to_upload_dir
09:42:02     INFO - Copying logs to upload dir...
09:42:02     INFO - mkdir: /builds/slave/rel-bir_fx_bncr_sub-0000000000/build/upload/logs
09:42:02     INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=18.065849
========= master_lag: 0.01 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/bouncer_submitter.py ...' (results: 0, elapsed: 18 secs) (at 2018-02-20 09:42:02.048216) =========

========= Started no change (results: 0, elapsed: 0 secs) (at 2018-02-20 09:42:02.050725) =========
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-303.build.releng.usw2.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.005640

========= master_lag: 0.05 =========
========= Finished no change (results: 0, elapsed: 0 secs) (at 2018-02-20 09:42:02.108868) =========

========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2018-02-20 09:42:02.109198) =========
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-303.build.releng.usw2.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.003519
========= master_lag: 0.05 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2018-02-20 09:42:02.159411) =========

========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2018-02-20 09:42:02.159743) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2018-02-20 09:42:02.160005) =========

========= Total master_lag: 0.18 =========