builder: mozilla-aurora_ubuntu64_vm_test_pgo-luciddream
slave: tst-linux64-spot-1319
starttime: 1452253073.03
results: exception (4)
buildid: 20160108010850
builduid: 0b64916438d64cd887309ee1f01b69fc
revision: 687d3ecf6c87410410028e63659944bbdf9fa026

========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.029106) =========
master: http://buildbot-master116.bb.releng.usw2.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.029754) =========

========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.030195) =========
bash -c pwd
 in dir /builds/slave/test/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'pwd']
 environment:
  HOME=/home/cltbld
  LANG=en_US.UTF-8
  LOGNAME=cltbld
  MAIL=/var/mail/cltbld
  NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
  PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PWD=/builds/slave/test
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1452253050.152668-831117805
  _=/tools/buildbot/bin/python
 using PTY: False
/builds/slave/test
program finished with exit code 0
elapsedTime=0.022793
basedir: '/builds/slave/test'
========= master_lag: 0.09 =========
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.138041) =========

========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.138525) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.177762) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.178169) =========
rm -rf properties
 in dir /builds/slave/test/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-rf', 'properties']
 environment:
  HOME=/home/cltbld
  LANG=en_US.UTF-8
  LOGNAME=cltbld
  MAIL=/var/mail/cltbld
  NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
  PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PWD=/builds/slave/test
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1452253050.152668-831117805
  _=/tools/buildbot/bin/python
 using PTY: False
program finished with exit code 0
elapsedTime=0.019085
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.238548) =========

========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.238979) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.239528) =========

========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.239945) =========
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/test/. (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:
  HOME=/home/cltbld
  LANG=en_US.UTF-8
  LOGNAME=cltbld
  MAIL=/var/mail/cltbld
  NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
  PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PWD=/builds/slave/test
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1452253050.152668-831117805
  _=/tools/buildbot/bin/python
 using PTY: False
--2016-01-08 03:37:53--  https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12141 (12K) [text/x-python]
Saving to: `archiver_client.py'

     0K .......... .                                          100% 12.7M=0.001s

2016-01-08 03:37:53 (12.7 MB/s) - `archiver_client.py' saved [12141/12141]

program finished with exit code 0
elapsedTime=0.601930
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.884369) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.884868) =========
rm -rf scripts
 in dir /builds/slave/test/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-rf', 'scripts']
 environment:
  HOME=/home/cltbld
  LANG=en_US.UTF-8
  LOGNAME=cltbld
  MAIL=/var/mail/cltbld
  NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
  PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PWD=/builds/slave/test
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1452253050.152668-831117805
  _=/tools/buildbot/bin/python
 using PTY: False
program finished with exit code 0
elapsedTime=0.033310
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.953967) =========

========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:53.954469) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 687d3ecf6c87410410028e63659944bbdf9fa026 --destination scripts --debug'
 in dir /builds/slave/test/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 687d3ecf6c87410410028e63659944bbdf9fa026 --destination scripts --debug']
 environment:
  HOME=/home/cltbld
  LANG=en_US.UTF-8
  LOGNAME=cltbld
  MAIL=/var/mail/cltbld
  NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
  PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PWD=/builds/slave/test
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1452253050.152668-831117805
  _=/tools/buildbot/bin/python
 using PTY: False
2016-01-08 03:37:54,054 truncating revision to first 12 chars
2016-01-08 03:37:54,055 Setting DEBUG logging.
2016-01-08 03:37:54,055 attempt 1/10
2016-01-08 03:37:54,055 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/687d3ecf6c87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-01-08 03:37:54,687 unpacking tar archive at: mozilla-aurora-687d3ecf6c87/testing/mozharness/
program finished with exit code 0
elapsedTime=0.905961
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:54.898480) =========

========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:54.898978) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:54.931581) =========

========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:54.931967) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-08 03:37:54.932544) =========

========= Started '/tools/buildbot/bin/python scripts/scripts/luciddream_unittest.py ...' exception (results: 4, elapsed: 29 mins, 34 secs) (at 2016-01-08 03:37:54.932967) =========
/tools/buildbot/bin/python scripts/scripts/luciddream_unittest.py --cfg luciddream/linux_config.py --blob-upload-branch mozilla-aurora --download-symbols ondemand
 in dir /builds/slave/test/. (timeout 1800 secs) (maxTime 7200 secs)
 watching logfiles {}
 argv: ['/tools/buildbot/bin/python', 'scripts/scripts/luciddream_unittest.py', '--cfg', 'luciddream/linux_config.py', '--blob-upload-branch', 'mozilla-aurora', '--download-symbols', 'ondemand']
 environment:
  CCACHE_DIR=/builds/ccache
  CCACHE_UMASK=002
  DISPLAY=:0
  HOME=/home/cltbld
  LANG=en_US.UTF-8
  LOGNAME=cltbld
  MAIL=/var/mail/cltbld
  MOZ_HIDE_RESULTS_TABLE=1
  MOZ_NODE_PATH=/usr/bin/node
  MOZ_NO_REMOTE=1
  NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
  NO_FAIL_ON_TEST_ERRORS=1
  PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PROPERTIES_FILE=/builds/slave/test/buildprops.json
  PWD=/builds/slave/test
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1452253050.152668-831117805
  _=/tools/buildbot/bin/python
 using PTY: False
03:37:55     INFO - MultiFileLogger online at 20160108 03:37:55 in /builds/slave/test
03:37:55     INFO - Run as scripts/scripts/luciddream_unittest.py --cfg luciddream/linux_config.py --blob-upload-branch mozilla-aurora --download-symbols ondemand
03:37:55     INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
03:37:55     INFO - {'append_to_log': False,
03:37:55     INFO -  'b2gdesktop_url': 'http://ftp.mozilla.org/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2',
03:37:55     INFO -  'base_work_dir': '/builds/slave/test',
03:37:55     INFO -  'blob_upload_branch': 'mozilla-aurora',
03:37:55     INFO -  'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
03:37:55     INFO -  'buildbot_json_path': 'buildprops.json',
03:37:55     INFO -  'config_files': ('luciddream/linux_config.py',),
03:37:55     INFO -  'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:37:55     INFO -  'download_minidump_stackwalk': True,
03:37:55     INFO -  'download_symbols': 'ondemand',
03:37:55     INFO -  'emulator': 'arm',
03:37:55     INFO -  'exes': {'gittool.py': '%(abs_tools_dir)s/buildfarm/utils/gittool.py',
03:37:55     INFO -           'python': '/tools/buildbot/bin/python',
03:37:55     INFO -           'tooltool.py': '/tools/tooltool.py',
03:37:55     INFO -           'virtualenv': ('/tools/buildbot/bin/python',
03:37:55     INFO -                          '/tools/misc-python/virtualenv.py')},
03:37:55     INFO -  'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:37:55     INFO -                 'http://pypi.pub.build.mozilla.org/pub'),
03:37:55     INFO -  'gaia_branch': 'default',
03:37:55     INFO -  'gaia_repo': 'https://hg.mozilla.org/integration/gaia-central',
03:37:55     INFO -  'log_level': 'info',
03:37:55     INFO -  'log_to_console': True,
03:37:55     INFO -  'opt_config_files': (),
03:37:55     INFO -  'pip_index': False,
03:37:55     INFO -  'require_test_zip': True,
03:37:55     INFO -  'suite_definitions': {'luciddream-b2gdt': {'options': ('--startup-timeout=300',
03:37:55     INFO -                                                         '--log-raw=%(raw_log_file)s',
03:37:55     INFO -                                                         '--log-errorsummary=%(error_summary_file)s',
03:37:55     INFO -                                                         '--browser-path=%(browser_path)s',
03:37:55     INFO -                                                         '--b2g-desktop-path=%(fxos_desktop_path)s',
03:37:55     INFO -                                                         '--gaia-profile=%(gaia_profile)s',
03:37:55     INFO -                                                         '%(test_manifest)s')},
03:37:55     INFO -                        'luciddream-emulator': {'options': ('--startup-timeout=300',
03:37:55     INFO -                                                            '--log-raw=%(raw_log_file)s',
03:37:55     INFO -                                                            '--log-errorsummary=%(error_summary_file)s',
03:37:55     INFO -                                                            '--browser-path=%(browser_path)s',
03:37:55     INFO -                                                            '--b2gpath=%(emulator_path)s',
03:37:55     INFO -                                                            '%(test_manifest)s')}},
03:37:55     INFO -  'tooltool_cache': '/builds/tooltool_cache',
03:37:55     INFO -  'vcs_share_base': '/builds/hg-shared',
03:37:55     INFO -  'virtualenv_modules': ('mozinstall',),
03:37:55     INFO -  'virtualenv_path': 'venv',
03:37:55     INFO -  'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:37:55     INFO -  'work_dir': 'build',
03:37:55     INFO -  'xre_path': 'xulrunner-sdk',
03:37:55     INFO -  'xre_url': 'https://api.pub.build.mozilla.org/tooltool/sha512/dc9503b21c87b5a469118746f99e4f41d73888972ce735fa10a80f6d218086da0e3da525d9a4cd8e4ea497ec199fef720e4a525873d77a1af304ac505e076462'}
03:37:55     INFO - #####
03:37:55     INFO - ##### Running clobber step.
03:37:55     INFO - #####
03:37:55     INFO - Running pre-action listener: _resource_record_pre_action
03:37:55     INFO - Running main action method: clobber
03:37:55     INFO - rmtree: /builds/slave/test/build
03:37:55     INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
03:37:56     INFO - Running post-action listener: _resource_record_post_action
03:37:56     INFO - #####
03:37:56     INFO - ##### Running read-buildbot-config step.
03:37:56     INFO - #####
03:37:56     INFO - Running pre-action listener: _resource_record_pre_action
03:37:56     INFO - Running main action method: read_buildbot_config
03:37:56     INFO - Using buildbot properties:
03:37:56     INFO - {
03:37:56     INFO -     "properties": {
03:37:56     INFO -         "buildnumber": 4, 
03:37:56     INFO -         "product": "firefox", 
03:37:56     INFO -         "script_repo_revision": "production", 
03:37:56     INFO -         "branch": "mozilla-aurora", 
03:37:56     INFO -         "repository": "", 
03:37:56     INFO -         "buildername": "Ubuntu VM 12.04 x64 mozilla-aurora pgo test luciddream", 
03:37:56     INFO -         "buildid": "20160108010850", 
03:37:56     INFO -         "slavename": "tst-linux64-spot-1319", 
03:37:56     INFO -         "stage_platform": "linux64-pgo", 
03:37:56     INFO -         "basedir": "/builds/slave/test", 
03:37:56     INFO -         "nightly_build": "True", 
03:37:56     INFO -         "project": "", 
03:37:56     INFO -         "platform": "linux64", 
03:37:56     INFO -         "master": "http://buildbot-master116.bb.releng.usw2.mozilla.com:8201/", 
03:37:56     INFO -         "slavebuilddir": "test", 
03:37:56     INFO -         "scheduler": "tests-mozilla-aurora-ubuntu64_vm-pgo-unittest", 
03:37:56     INFO -         "repo_path": "releases/mozilla-aurora", 
03:37:56     INFO -         "moz_repo_path": "", 
03:37:56     INFO -         "pgo_build": "True", 
03:37:56     INFO -         "builduid": "0b64916438d64cd887309ee1f01b69fc", 
03:37:56     INFO -         "revision": "687d3ecf6c87410410028e63659944bbdf9fa026"
03:37:56     INFO -     }, 
03:37:56     INFO -     "sourcestamp": {
03:37:56     INFO -         "repository": "", 
03:37:56     INFO -         "hasPatch": false, 
03:37:56     INFO -         "project": "", 
03:37:56     INFO -         "branch": "mozilla-aurora-linux64-pgo-unittest", 
03:37:56     INFO -         "changes": [
03:37:56     INFO -             {
03:37:56     INFO -                 "category": null, 
03:37:56     INFO -                 "files": [
03:37:56     INFO -                     {
03:37:56     INFO -                         "url": null, 
03:37:56     INFO -                         "name": "https://queue.taskcluster.net/v1/task/_BQOyTfwRm67hlQHQ5PosQ/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2"
03:37:56     INFO -                     }, 
03:37:56     INFO -                     {
03:37:56     INFO -                         "url": null, 
03:37:56     INFO -                         "name": "https://queue.taskcluster.net/v1/task/_BQOyTfwRm67hlQHQ5PosQ/artifacts/public/build/test_packages.json"
03:37:56     INFO -                     }
03:37:56     INFO -                 ], 
03:37:56     INFO -                 "repository": "", 
03:37:56     INFO -                 "rev": "9cd8b59d1a9d3ed922f05ec2d2950264f7a69671", 
03:37:56     INFO -                 "who": "sendchange-unittest", 
03:37:56     INFO -                 "when": 1452251914, 
03:37:56     INFO -                 "number": 7434901, 
03:37:56     INFO -                 "comments": "", 
03:37:56     INFO -                 "project": "", 
03:37:56     INFO -                 "at": "Fri 08 Jan 2016 03:18:34", 
03:37:56     INFO -                 "branch": "mozilla-aurora-linux64-pgo-unittest", 
03:37:56     INFO -                 "revlink": "", 
03:37:56     INFO -                 "properties": [
03:37:56     INFO -                     [
03:37:56     INFO -                         "buildid", 
03:37:56     INFO -                         "20160108004007", 
03:37:56     INFO -                         "Change"
03:37:56     INFO -                     ], 
03:37:56     INFO -                     [
03:37:56     INFO -                         "builduid", 
03:37:56     INFO -                         "cd16aca7d51948cba244323ca2e6ebe8", 
03:37:56     INFO -                         "Change"
03:37:56     INFO -                     ], 
03:37:56     INFO -                     [
03:37:56     INFO -                         "nightly_build", 
03:37:56     INFO -                         "True", 
03:37:56     INFO -                         "Change"
03:37:56     INFO -                     ], 
03:37:56     INFO -                     [
03:37:56     INFO -                         "pgo_build", 
03:37:56     INFO -                         "True", 
03:37:56     INFO -                         "Change"
03:37:56     INFO -                     ]
03:37:56     INFO -                 ], 
03:37:56     INFO -                 "revision": "9cd8b59d1a9d3ed922f05ec2d2950264f7a69671"
03:37:56     INFO -             }, 
03:37:56     INFO -             {
03:37:56     INFO -                 "category": null, 
03:37:56     INFO -                 "files": [
03:37:56     INFO -                     {
03:37:56     INFO -                         "url": null, 
03:37:56     INFO -                         "name": "https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2"
03:37:56     INFO -                     }, 
03:37:56     INFO -                     {
03:37:56     INFO -                         "url": null, 
03:37:56     INFO -                         "name": "https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/test_packages.json"
03:37:56     INFO -                     }
03:37:56     INFO -                 ], 
03:37:56     INFO -                 "repository": "", 
03:37:56     INFO -                 "rev": "687d3ecf6c87410410028e63659944bbdf9fa026", 
03:37:56     INFO -                 "who": "cbook@mozilla.com", 
03:37:56     INFO -                 "when": 1452252940, 
03:37:56     INFO -                 "number": 7434940, 
03:37:56     INFO -                 "comments": "Bug 1235821 - Don't track mouseleave when capturing mousei. r=jimm, a=ritu\nSince, in Windows, the tabbar overlays the \"nonclient\" area, TrackMouseEvent\nwill report a WM_MOUSELEAVE when you move your mouse when dragging a tab.\n\nSo, suspend mouseleave tracking when capturing and resume when we end capture.\n\nA nice effect of TrackMouseEvent is that it'll immediately post a WM_MOUSELEAVE\nif called when the mouse is outside the client area, so when capture ends, we\nare either over the client area and tracking resumes or we aren't and we get\nan immediate WM_MOUSELEAVE.", 
03:37:56     INFO -                 "project": "", 
03:37:56     INFO -                 "at": "Fri 08 Jan 2016 03:35:40", 
03:37:56     INFO -                 "branch": "mozilla-aurora-linux64-pgo-unittest", 
03:37:56     INFO -                 "revlink": "", 
03:37:56     INFO -                 "properties": [
03:37:56     INFO -                     [
03:37:56     INFO -                         "buildid", 
03:37:56     INFO -                         "20160108010850", 
03:37:56     INFO -                         "Change"
03:37:56     INFO -                     ], 
03:37:56     INFO -                     [
03:37:56     INFO -                         "builduid", 
03:37:56     INFO -                         "0b64916438d64cd887309ee1f01b69fc", 
03:37:56     INFO -                         "Change"
03:37:56     INFO -                     ], 
03:37:56     INFO -                     [
03:37:56     INFO -                         "pgo_build", 
03:37:56     INFO -                         "True", 
03:37:56     INFO -                         "Change"
03:37:56     INFO -                     ]
03:37:56     INFO -                 ], 
03:37:56     INFO -                 "revision": "687d3ecf6c87410410028e63659944bbdf9fa026"
03:37:56     INFO -             }
03:37:56     INFO -         ], 
03:37:56     INFO -         "revision": "687d3ecf6c87410410028e63659944bbdf9fa026"
03:37:56     INFO -     }
03:37:56     INFO - }
03:37:56     INFO - Found installer url https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2.
03:37:56     INFO - Found a test packages url https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/test_packages.json.
03:37:56     INFO - Running post-action listener: _resource_record_post_action
03:37:56     INFO - #####
03:37:56     INFO - ##### Running download-and-extract step.
03:37:56     INFO - #####
03:37:56     INFO - Running pre-action listener: _resource_record_pre_action
03:37:56     INFO - Running main action method: download_and_extract
03:37:56     INFO - mkdir: /builds/slave/test/build/tests
03:37:56     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
03:37:56     INFO - https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
03:37:56     INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/test_packages.json
03:37:56     INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/test_packages.json
03:37:56     INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json
03:37:56     INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1
03:37:58     INFO - Downloaded 1302 bytes.
03:37:58     INFO - Reading from file /builds/slave/test/build/test_packages.json
03:37:58     INFO - Using the following test package requirements:
03:37:58     INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'],
03:37:58     INFO -  u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip',
03:37:58     INFO -                   u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'],
03:37:58     INFO -  u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip',
03:37:58     INFO -               u'jsshell-linux-x86_64.zip'],
03:37:58     INFO -  u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip',
03:37:58     INFO -                 u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'],
03:37:58     INFO -  u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'],
03:37:58     INFO -  u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip',
03:37:58     INFO -               u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'],
03:37:58     INFO -  u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip',
03:37:58     INFO -             u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'],
03:37:58     INFO -  u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip',
03:37:58     INFO -                    u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'],
03:37:58     INFO -  u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'],
03:37:58     INFO -  u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip',
03:37:58     INFO -                u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']}
03:37:58     INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common
03:37:58     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
03:37:58     INFO - https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net
03:37:58     INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip
03:37:58     INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip
03:37:58     INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip
03:37:58     INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1
03:37:59     INFO - Downloaded 23024775 bytes.
03:37:59     INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests
03:37:59     INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip
03:37:59     INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760
03:38:02     INFO - Return code: 0
03:38:02     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
03:38:02     INFO - https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net
03:38:02     INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2
03:38:02     INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2
03:38:02     INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2
03:38:02     INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1
03:38:05     INFO - Downloaded 58123224 bytes.
03:38:05     INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2
03:38:05     INFO - mkdir: /builds/slave/test/properties
03:38:05     INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
03:38:05     INFO - Writing to file /builds/slave/test/properties/build_url
03:38:05     INFO - Contents:
03:38:05     INFO -  build_url:https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2
03:38:05     INFO - mkdir: /builds/slave/test/build/b2gdesktop
03:38:05     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
03:38:05     INFO - http://ftp.mozilla.org/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2 matches http://ftp.mozilla.org
03:38:05     INFO - URL Candidate: http://ftp.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2
03:38:05     INFO - trying http://ftp.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2
03:38:05     INFO - Downloading http://ftp.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2 to /builds/slave/test/build/b2g-37.0.multi.linux-i686.tar.bz2
03:38:05     INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://ftp.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2', 'file_name': '/builds/slave/test/build/b2g-37.0.multi.linux-i686.tar.bz2'}, attempt #1
03:38:07     INFO - Downloaded 99829313 bytes.
03:38:07     INFO - Running post-action listener: _resource_record_post_action
03:38:07     INFO - Running post-action listener: set_extra_try_arguments
03:38:07     INFO - #####
03:38:07     INFO - ##### Running pull step.
03:38:07     INFO - #####
03:38:07     INFO - Running pre-action listener: _resource_record_pre_action
03:38:07     INFO - Running main action method: pull
03:38:07     INFO - dest: /builds/slave/test/gaia
03:38:07     INFO - retry: Calling load_json_from_url with args: ('https://hg.mozilla.org/releases/mozilla-aurora/raw-file/687d3ecf6c87410410028e63659944bbdf9fa026/b2g/config/gaia.json',), kwargs: {}, attempt #1
03:38:08     INFO - rmtree: /builds/slave/test/gaia
03:38:08     INFO - Running command: ['git', 'clone', 'https://git.mozilla.org/releases/gaia.git'] in /builds/slave/test
03:38:08     INFO - Copy/paste: git clone https://git.mozilla.org/releases/gaia.git
03:38:08     INFO - Calling ['git', 'clone', 'https://git.mozilla.org/releases/gaia.git'] with output_timeout 1760
03:38:09     INFO -  Cloning into 'gaia'...
04:07:29     INFO - Automation Error: mozprocess timed out after 1760 seconds running ['git', 'clone', 'https://git.mozilla.org/releases/gaia.git']
04:07:29    ERROR - timed out after 1760 seconds of no output
04:07:29    ERROR - Return code: -9
04:07:29    ERROR - -9 not in success codes: [0]
04:07:29  WARNING - setting return code to 3
04:07:29    FATAL - Halting on failure while running ['git', 'clone', 'https://git.mozilla.org/releases/gaia.git']
04:07:29    FATAL - Running post_fatal callback...
04:07:29    FATAL - Exiting 3
04:07:29     INFO - Running post-action listener: _resource_record_post_action
04:07:29     INFO - Running post-run listener: _resource_record_post_run
04:07:29     INFO - Running post-run listener: _upload_blobber_files
04:07:29     INFO - Blob upload gear active.
04:07:29  WARNING - Blob upload directory does not exist!
program finished with exit code 3
elapsedTime=1774.170615
========= master_lag: 0.35 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/luciddream_unittest.py ...' exception (results: 4, elapsed: 29 mins, 34 secs) (at 2016-01-08 04:07:29.452863) =========

========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-08 04:07:29.455230) =========
bash -c 'for file in `ls -1`; do cat $file; done'
 in dir /builds/slave/test/properties (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
 environment:
  HOME=/home/cltbld
  LANG=en_US.UTF-8
  LOGNAME=cltbld
  MAIL=/var/mail/cltbld
  NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
  PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PWD=/builds/slave/test/properties
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1452253050.152668-831117805
  _=/tools/buildbot/bin/python
 using PTY: False
build_url:https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2
program finished with exit code 0
elapsedTime=0.029329
build_url: 'https://queue.taskcluster.net/v1/task/K26fWGclQtCHiZ9zcHKDSg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2'
========= master_lag: 0.04 =========
========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-08 04:07:29.524140) =========

========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 04:07:29.524655) =========
rm -f oauth.txt
 in dir /builds/slave/test/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-f', 'oauth.txt']
 environment:
  HOME=/home/cltbld
  LANG=en_US.UTF-8
  LOGNAME=cltbld
  MAIL=/var/mail/cltbld
  NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
  PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PWD=/builds/slave/test
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1452253050.152668-831117805
  _=/tools/buildbot/bin/python
 using PTY: False
program finished with exit code 0
elapsedTime=0.018235
========= master_lag: 0.26 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-08 04:07:29.804385) =========

========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-08 04:07:29.804875) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-08 04:07:29.806162) =========

========= Total master_lag: 0.89 =========