builder: release-mozilla-release-fennec_bouncer_submitter
slave: bld-linux64-spot-534
starttime: 1462230911.96
results: success (0)
buildid: 20160502161457
builduid: 99416bc73a8f4241a8e13c0f4593520d

========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-02 16:15:11.965284) =========
master: http://buildbot-master91.bb.releng.usw2.mozilla.com:8001/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-02 16:15:11.965711) =========

========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-02 16:15:11.966020) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-02 16:15:12.177087) =========

========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-02 16:15:12.177353) =========
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-m-rel-bncr_sub-00000000000/. (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:
  BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom
  BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs
  CCACHE_HASHDIR=
  CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-534.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-m-rel-bncr_sub-00000000000
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
--2016-05-02 16:15:12--  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: 12141 (12K) [text/x-python]
Saving to: ‘archiver_client.py’

     0K .......... .                                          100% 88.0M=0s

2016-05-02 16:15:12 (88.0 MB/s) - ‘archiver_client.py’ saved [12141/12141]

program finished with exit code 0
elapsedTime=0.237613
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-02 16:15:12.436135) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-02 16:15:12.436456) =========
rm -rf scripts properties
 in dir /builds/slave/rel-m-rel-bncr_sub-00000000000/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-rf', 'scripts', 'properties']
 environment:
  BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom
  BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs
  CCACHE_HASHDIR=
  CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-534.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-m-rel-bncr_sub-00000000000
  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.002712
========= master_lag: 0.07 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-02 16:15:12.513248) =========

========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 24 secs) (at 2016-05-02 16:15:12.513578) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-release --tag FENNEC_46_0_1_RELEASE --destination scripts --debug'
 in dir /builds/slave/rel-m-rel-bncr_sub-00000000000/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-release --tag FENNEC_46_0_1_RELEASE --destination scripts --debug']
 environment:
  BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom
  BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs
  CCACHE_HASHDIR=
  CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-534.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-m-rel-bncr_sub-00000000000
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
2016-05-02 16:15:12,558 Setting DEBUG logging.
2016-05-02 16:15:12,558 "FENNEC_46_0_1_RELEASE" was passed as the tag. Querying remote repository for corresponding rev hash.
2016-05-02 16:15:13,198 revision being used: 57f513ab0330
2016-05-02 16:15:13,198 attempt 1/10
2016-05-02 16:15:13,198 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-release/57f513ab0330?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-05-02 16:15:13,977 attempt 1/10
2016-05-02 16:15:14,393 current task status: no status available at this point. state: PENDING
2016-05-02 16:15:14,393 sleeping for 11.00s (attempt 1/10)
2016-05-02 16:15:25,405 attempt 2/10
2016-05-02 16:15:25,734 current task status: no status available at this point. state: PENDING
2016-05-02 16:15:25,734 sleeping for 15.50s (attempt 2/10)
2016-05-02 16:15:41,250 attempt 3/10
2016-05-02 16:15:41,637 current task status: no status available at this point. state: PENDING
2016-05-02 16:15:41,637 sleeping for 22.25s (attempt 3/10)
2016-05-02 16:16:03,910 attempt 4/10
2016-05-02 16:16:04,289 current task status: no status available at this point. state: PENDING
2016-05-02 16:16:04,290 sleeping for 32.38s (attempt 4/10)
2016-05-02 16:16:36,697 attempt 5/10
2016-05-02 16:16:36,867 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS
2016-05-02 16:16:36,936 unpacking tar archive at: mozilla-release-57f513ab0330/testing/mozharness/
program finished with exit code 0
elapsedTime=84.540616
========= master_lag: 0.01 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 24 secs) (at 2016-05-02 16:16:37.065432) =========

========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-02 16:16:37.065750) =========
script_repo_revision: FENNEC_46_0_1_RELEASE
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-02 16:16:37.066133) =========

========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-02 16:16:37.066407) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-02 16:16:37.080688) =========

========= Started 'scripts/scripts/bouncer_submitter.py -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-02 16:16:37.080992) =========
scripts/scripts/bouncer_submitter.py -c releases/bouncer_fennec.py --revision FENNEC_46_0_1_RELEASE --repo releases/mozilla-release --version 46.0.1 --credentials-file oauth.txt --bouncer-api-prefix https://bounceradmin.mozilla.com/api --build-number 1
 in dir /builds/slave/rel-m-rel-bncr_sub-00000000000/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['scripts/scripts/bouncer_submitter.py', '-c', 'releases/bouncer_fennec.py', '--revision', 'FENNEC_46_0_1_RELEASE', '--repo', 'releases/mozilla-release', '--version', '46.0.1', '--credentials-file', 'oauth.txt', '--bouncer-api-prefix', 'https://bounceradmin.mozilla.com/api', '--build-number', '1']
 environment:
  BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom
  BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs
  CCACHE_HASHDIR=
  CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-534.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
  PROPERTIES_FILE=/builds/slave/rel-m-rel-bncr_sub-00000000000/buildprops.json
  PWD=/builds/slave/rel-m-rel-bncr_sub-00000000000
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
16:16:37     INFO - MultiFileLogger online at 20160502 16:16:37 in /builds/slave/rel-m-rel-bncr_sub-00000000000
16:16:37     INFO - Using buildbot properties:
16:16:37     INFO - {
16:16:37     INFO -     "project": "", 
16:16:37     INFO -     "product": "Fennec", 
16:16:37     INFO -     "build_number": 1, 
16:16:37     INFO -     "scheduler": "release-mozilla-release-fennec_build", 
16:16:37     INFO -     "repository": "", 
16:16:37     INFO -     "buildername": "release-mozilla-release-fennec_bouncer_submitter", 
16:16:37     INFO -     "buildid": "20160502161457", 
16:16:37     INFO -     "basedir": "/builds/slave/rel-m-rel-bncr_sub-00000000000", 
16:16:37     INFO -     "release_config": "mozilla/release-fennec-mozilla-release.py", 
16:16:37     INFO -     "buildnumber": 6, 
16:16:37     INFO -     "platform": null, 
16:16:37     INFO -     "version": "46.0.1", 
16:16:37     INFO -     "master": "http://buildbot-master91.bb.releng.usw2.mozilla.com:8001/", 
16:16:37     INFO -     "slavebuilddir": "rel-m-rel-bncr_sub-00000000000", 
16:16:37     INFO -     "branch": "release-mozilla-release", 
16:16:37     INFO -     "script_repo_revision": "FENNEC_46_0_1_RELEASE", 
16:16:37     INFO -     "products": "fennec", 
16:16:37     INFO -     "slavename": "bld-linux64-spot-534", 
16:16:37     INFO -     "builduid": "99416bc73a8f4241a8e13c0f4593520d", 
16:16:37     INFO -     "revision": null
16:16:37     INFO - }
16:16:37     INFO - Run as scripts/scripts/bouncer_submitter.py -c releases/bouncer_fennec.py --revision FENNEC_46_0_1_RELEASE --repo releases/mozilla-release --version 46.0.1 --credentials-file oauth.txt --bouncer-api-prefix https://bounceradmin.mozilla.com/api --build-number 1
16:16:37     INFO - Dumping config to /builds/slave/rel-m-rel-bncr_sub-00000000000/logs/localconfig.json.
16:16:37     INFO - {'append_to_log': False,
16:16:37     INFO -  'base_work_dir': '/builds/slave/rel-m-rel-bncr_sub-00000000000',
16:16:37     INFO -  'bouncer-api-prefix': 'https://bounceradmin.mozilla.com/api',
16:16:37     INFO -  'build_number': '1',
16:16:37     INFO -  'buildbot_json_path': 'buildprops.json',
16:16:37     INFO -  'config_files': ('releases/bouncer_fennec.py',),
16:16:37     INFO -  'credentials_file': 'oauth.txt',
16:16:37     INFO -  'log_level': 'info',
16:16:37     INFO -  'log_to_console': True,
16:16:37     INFO -  'opt_config_files': (),
16:16:37     INFO -  'products': {'apk': {'add-locales': False,
16:16:37     INFO -                       'alias': 'fennec-latest',
16:16:37     INFO -                       'paths': {'android-api-15': {'bouncer-platform': 'android',
16:16:37     INFO -                                                    'path': '/mobile/releases/%(version)s/android-api-15/:lang/fennec-%(version)s.:lang.android-arm.apk'},
16:16:37     INFO -                                 'android-api-9': {'bouncer-platform': 'android-api-9',
16:16:37     INFO -                                                   'path': '/mobile/releases/%(version)s/android-api-9/:lang/fennec-%(version)s.:lang.android-arm.apk'},
16:16:37     INFO -                                 'android-x86': {'bouncer-platform': 'android-x86',
16:16:37     INFO -                                                 'path': '/mobile/releases/%(version)s/android-x86/:lang/fennec-%(version)s.:lang.android-i386.apk'}},
16:16:37     INFO -                       'product-name': 'Fennec-%(version)s',
16:16:37     INFO -                       'ssl-only': False}},
16:16:37     INFO -  'repo': 'releases/mozilla-release',
16:16:37     INFO -  'revision': 'FENNEC_46_0_1_RELEASE',
16:16:37     INFO -  'version': '46.0.1',
16:16:37     INFO -  'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
16:16:37     INFO -  'work_dir': 'build'}
16:16:37     INFO - #####
16:16:37     INFO - ##### Running clobber step.
16:16:37     INFO - #####
16:16:37     INFO - Running main action method: clobber
16:16:37     INFO - rmtree: /builds/slave/rel-m-rel-bncr_sub-00000000000/build
16:16:37     INFO - #####
16:16:37     INFO - ##### Running download-shipped-locales step.
16:16:37     INFO - #####
16:16:37     INFO - Running main action method: download_shipped_locales
16:16:37     INFO - No need to download shipped-locales
16:16:37     INFO - #####
16:16:37     INFO - ##### Running submit step.
16:16:37     INFO - #####
16:16:37     INFO - Running main action method: submit
16:16:37     INFO - Checking if Fennec-46.0.1 already exists
16:16:37     INFO - retry: Calling _api_call with args: ('product_show?product=Fennec-46.0.1', None), kwargs: {}, attempt #1
16:16:37     INFO - Submitting to https://bounceradmin.mozilla.com/api/product_show?product=Fennec-46.0.1
16:16:37     INFO - Server response
16:16:37     INFO - <?xml version="1.0" encoding="utf-8"?><products/>
16:16:37     INFO - Products found: 0
16:16:37     INFO - Adding apk...
16:16:37     INFO - retry: Calling _api_call with args: ('product_add/', {'product': 'Fennec-46.0.1'}), kwargs: {}, attempt #1
16:16:37     INFO - POST data: product=Fennec-46.0.1
16:16:37     INFO - Submitting to https://bounceradmin.mozilla.com/api/product_add/
16:16:38     INFO - Server response
16:16:38     INFO - <?xml version="1.0" encoding="utf-8"?><products><product id="5720" name="Fennec-46.0.1"/></products>
16:16:38     INFO - Adding paths...
16:16:38     INFO - android-api-15 (android): /mobile/releases/46.0.1/android-api-15/:lang/fennec-46.0.1.:lang.android-arm.apk
16:16:38     INFO - retry: Calling _api_call with args: ('location_add/', {'path': '/mobile/releases/46.0.1/android-api-15/:lang/fennec-46.0.1.:lang.android-arm.apk', 'product': 'Fennec-46.0.1', 'os': 'android'}), kwargs: {}, attempt #1
16:16:38     INFO - POST data: path=%2Fmobile%2Freleases%2F46.0.1%2Fandroid-api-15%2F%3Alang%2Ffennec-46.0.1.%3Alang.android-arm.apk&product=Fennec-46.0.1&os=android
16:16:38     INFO - Submitting to https://bounceradmin.mozilla.com/api/location_add/
16:16:38     INFO - Server response
16:16:38     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="5720" name="Fennec-46.0.1"><location id="29458" os="android">/mobile/releases/46.0.1/android-api-15/:lang/fennec-46.0.1.:lang.android-arm.apk</location></product></locations>
16:16:38     INFO - android-api-9 (android-api-9): /mobile/releases/46.0.1/android-api-9/:lang/fennec-46.0.1.:lang.android-arm.apk
16:16:38     INFO - retry: Calling _api_call with args: ('location_add/', {'path': '/mobile/releases/46.0.1/android-api-9/:lang/fennec-46.0.1.:lang.android-arm.apk', 'product': 'Fennec-46.0.1', 'os': 'android-api-9'}), kwargs: {}, attempt #1
16:16:38     INFO - POST data: path=%2Fmobile%2Freleases%2F46.0.1%2Fandroid-api-9%2F%3Alang%2Ffennec-46.0.1.%3Alang.android-arm.apk&product=Fennec-46.0.1&os=android-api-9
16:16:38     INFO - Submitting to https://bounceradmin.mozilla.com/api/location_add/
16:16:39     INFO - Server response
16:16:39     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="5720" name="Fennec-46.0.1"><location id="29459" os="android-api-9">/mobile/releases/46.0.1/android-api-9/:lang/fennec-46.0.1.:lang.android-arm.apk</location></product></locations>
16:16:39     INFO - android-x86 (android-x86): /mobile/releases/46.0.1/android-x86/:lang/fennec-46.0.1.:lang.android-i386.apk
16:16:39     INFO - retry: Calling _api_call with args: ('location_add/', {'path': '/mobile/releases/46.0.1/android-x86/:lang/fennec-46.0.1.:lang.android-i386.apk', 'product': 'Fennec-46.0.1', 'os': 'android-x86'}), kwargs: {}, attempt #1
16:16:39     INFO - POST data: path=%2Fmobile%2Freleases%2F46.0.1%2Fandroid-x86%2F%3Alang%2Ffennec-46.0.1.%3Alang.android-i386.apk&product=Fennec-46.0.1&os=android-x86
16:16:39     INFO - Submitting to https://bounceradmin.mozilla.com/api/location_add/
16:16:39     INFO - Server response
16:16:39     INFO - <?xml version="1.0" encoding="utf-8"?><locations><product id="5720" name="Fennec-46.0.1"><location id="29460" os="android-x86">/mobile/releases/46.0.1/android-x86/:lang/fennec-46.0.1.:lang.android-i386.apk</location></product></locations>
16:16:39     INFO - Running post-run listener: copy_logs_to_upload_dir
16:16:39     INFO - Copying logs to upload dir...
16:16:39     INFO - mkdir: /builds/slave/rel-m-rel-bncr_sub-00000000000/build/upload/logs
16:16:39     INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=2.510813
========= master_lag: 0.02 =========
========= Finished 'scripts/scripts/bouncer_submitter.py -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-02 16:16:39.608275) =========

========= Started no change (results: 0, elapsed: 0 secs) (at 2016-05-02 16:16:39.609279) =========
bash -c 'for file in `ls -1`; do cat $file; done'
 in dir /builds/slave/rel-m-rel-bncr_sub-00000000000/properties (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
 environment:
  BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom
  BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs
  CCACHE_HASHDIR=
  CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-534.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-m-rel-bncr_sub-00000000000/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.004874

========= master_lag: 0.04 =========
========= Finished no change (results: 0, elapsed: 0 secs) (at 2016-05-02 16:16:39.657965) =========

========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-02 16:16:39.658282) =========
rm -f oauth.txt
 in dir /builds/slave/rel-m-rel-bncr_sub-00000000000/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-f', 'oauth.txt']
 environment:
  BUILDBOTCUSTOM=https://hg.mozilla.org/build/buildbotcustom
  BUILDBOT_CONFIGS=https://hg.mozilla.org/build/buildbot-configs
  CCACHE_HASHDIR=
  CLOBBERER_URL=https://api.pub.build.mozilla.org/clobberer/forceclobber
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-534.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-m-rel-bncr_sub-00000000000
  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.002611
========= master_lag: 0.05 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-02 16:16:39.706500) =========

========= Total master_lag: 0.21 =========