builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1467325163.25 results: success (0) buildid: 20160630122127 builduid: ff240aa6a5f044299e52cd622baaa375 revision: cea38bcafb6ad58462496b582cec8eb361a727df ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:23.248469) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:23.249045) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:23.249416) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:23.264868) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:23.265391) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-30 15:19:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (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% 46.4M=0s 2016-06-30 15:19:24 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102841 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:23.383055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:23.383387) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018027 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:23.433658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 15:19:23.434021) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cea38bcafb6ad58462496b582cec8eb361a727df --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 cea38bcafb6ad58462496b582cec8eb361a727df --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-30 15:19:24,457 truncating revision to first 12 chars 2016-06-30 15:19:24,457 Setting DEBUG logging. 2016-06-30 15:19:24,457 attempt 1/10 2016-06-30 15:19:24,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cea38bcafb6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-30 15:19:25,907 unpacking tar archive at: mozilla-aurora-cea38bcafb6a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.788445 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 15:19:25.238488) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:25.238870) ========= script_repo_revision: cea38bcafb6ad58462496b582cec8eb361a727df ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:25.239324) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:25.239622) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 15:19:25.260619) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-06-30 15:19:25.261017) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=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=a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:19:26 INFO - MultiFileLogger online at 20160630 15:19:26 in /builds/slave/test 15:19:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:19:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:19:26 INFO - {'append_to_log': False, 15:19:26 INFO - 'base_work_dir': '/builds/slave/test', 15:19:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:19:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:19:26 INFO - 'branch': 'Mozilla-Aurora', 15:19:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:19:26 INFO - 'config_files': ('talos/linux_config.py',), 15:19:26 INFO - 'default_actions': ('clobber', 15:19:26 INFO - 'read-buildbot-config', 15:19:26 INFO - 'download-and-extract', 15:19:26 INFO - 'populate-webroot', 15:19:26 INFO - 'create-virtualenv', 15:19:26 INFO - 'install', 15:19:26 INFO - 'run-tests'), 15:19:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:19:26 INFO - 'download_minidump_stackwalk': True, 15:19:26 INFO - 'download_symbols': 'ondemand', 15:19:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:19:26 INFO - 'tooltool.py': '/tools/tooltool.py', 15:19:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:19:26 INFO - '/tools/misc-python/virtualenv.py')}, 15:19:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:19:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:19:26 INFO - 'installer_path': 'installer.exe', 15:19:26 INFO - 'log_level': 'info', 15:19:26 INFO - 'log_name': 'talos', 15:19:26 INFO - 'log_to_console': True, 15:19:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:19:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:19:26 INFO - 'opt_config_files': (), 15:19:26 INFO - 'pip_index': False, 15:19:26 INFO - 'sps_profile': False, 15:19:26 INFO - 'sps_profile_interval': 0, 15:19:26 INFO - 'suite': 'dromaeojs', 15:19:26 INFO - 'system_bits': '32', 15:19:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:19:26 INFO - 'title': 'talos-linux64-ix-064', 15:19:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:19:26 INFO - 'use_talos_json': True, 15:19:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:19:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:19:26 INFO - 'work_dir': 'build'} 15:19:26 INFO - ##### 15:19:26 INFO - ##### Running clobber step. 15:19:26 INFO - ##### 15:19:26 INFO - Running pre-action listener: _resource_record_pre_action 15:19:26 INFO - Running main action method: clobber 15:19:26 INFO - rmtree: /builds/slave/test/build 15:19:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:19:26 INFO - Running post-action listener: _resource_record_post_action 15:19:26 INFO - ##### 15:19:26 INFO - ##### Finished clobber step (success) 15:19:26 INFO - ##### 15:19:26 INFO - ##### 15:19:26 INFO - ##### Running read-buildbot-config step. 15:19:26 INFO - ##### 15:19:26 INFO - Running pre-action listener: _resource_record_pre_action 15:19:26 INFO - Running main action method: read_buildbot_config 15:19:26 INFO - Using buildbot properties: 15:19:26 INFO - { 15:19:26 INFO - "project": "", 15:19:26 INFO - "product": "firefox", 15:19:26 INFO - "script_repo_revision": "production", 15:19:26 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:19:26 INFO - "repository": "", 15:19:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 15:19:26 INFO - "buildid": "20160630122127", 15:19:26 INFO - "pgo_build": "True", 15:19:26 INFO - "basedir": "/builds/slave/test", 15:19:26 INFO - "buildnumber": 328, 15:19:26 INFO - "slavename": "talos-linux64-ix-064", 15:19:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:19:26 INFO - "platform": "ubuntu64_hw", 15:19:26 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:19:26 INFO - "branch": "mozilla-aurora", 15:19:26 INFO - "repo_path": "releases/mozilla-aurora", 15:19:26 INFO - "revision": "cea38bcafb6ad58462496b582cec8eb361a727df", 15:19:26 INFO - "stage_platform": "linux64-pgo", 15:19:26 INFO - "builduid": "ff240aa6a5f044299e52cd622baaa375", 15:19:26 INFO - "slavebuilddir": "test" 15:19:26 INFO - } 15:19:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 15:19:26 INFO - Running post-action listener: _resource_record_post_action 15:19:26 INFO - ##### 15:19:26 INFO - ##### Finished read-buildbot-config step (success) 15:19:26 INFO - ##### 15:19:26 INFO - ##### 15:19:26 INFO - ##### Running download-and-extract step. 15:19:26 INFO - ##### 15:19:26 INFO - Running pre-action listener: _resource_record_pre_action 15:19:26 INFO - Running main action method: download_and_extract 15:19:26 INFO - mkdir: /builds/slave/test/build/tests 15:19:26 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')]} 15:19:26 INFO - https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:19:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 15:19:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 15:19:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 15:19:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:19:27 INFO - Downloaded 1361 bytes. 15:19:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 15:19:27 INFO - Using the following test package requirements: 15:19:27 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 15:19:27 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:19:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:19:27 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:19:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:19:27 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:19:27 INFO - u'jsshell-linux-x86_64.zip'], 15:19:27 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:19:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:19:27 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 15:19:27 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:19:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:19:27 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:19:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:19:27 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:19:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:19:27 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 15:19:27 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:19:27 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:19:27 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')]} 15:19:27 INFO - https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:19:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:19:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:19:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:19:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:19:28 INFO - Downloaded 24526463 bytes. 15:19:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:19:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:19:29 INFO - Return code: 0 15:19:29 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:19:29 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')]} 15:19:29 INFO - https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:19:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:19:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:19:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 15:19:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:19:30 INFO - Downloaded 24526463 bytes. 15:19:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:19:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:19:32 INFO - Return code: 0 15:19:32 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')]} 15:19:32 INFO - https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:19:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 15:19:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 15:19:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 15:19:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:19:33 INFO - Downloaded 10833369 bytes. 15:19:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:19:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:19:33 INFO - Return code: 0 15:19:33 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')]} 15:19:33 INFO - https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:19:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 15:19:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 15:19:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 15:19:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:19:34 INFO - Downloaded 63600939 bytes. 15:19:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 15:19:34 INFO - mkdir: /builds/slave/test/properties 15:19:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:19:34 INFO - Writing to file /builds/slave/test/properties/build_url 15:19:34 INFO - Contents: 15:19:34 INFO - build_url:https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 15:19:35 INFO - Running post-action listener: _resource_record_post_action 15:19:35 INFO - Running post-action listener: set_extra_try_arguments 15:19:35 INFO - ##### 15:19:35 INFO - ##### Finished download-and-extract step (success) 15:19:35 INFO - ##### 15:19:35 INFO - ##### 15:19:35 INFO - ##### Running populate-webroot step. 15:19:35 INFO - ##### 15:19:35 INFO - Running pre-action listener: _resource_record_pre_action 15:19:35 INFO - Running main action method: populate_webroot 15:19:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:19:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:19:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:19:35 INFO - '--tppagecycles', 15:19:35 INFO - '7'], 15:19:35 INFO - 'tests': ['tsvgm']}}, 15:19:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:19:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:19:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:19:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:19:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:35 INFO - 'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:19:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:35 INFO - 'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['damp', 'tps']}, 15:19:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:35 INFO - 'tests': ['damp', 'tps']}, 15:19:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['dromaeo_dom']}, 15:19:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:19:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['a11yr', 15:19:35 INFO - 'ts_paint', 15:19:35 INFO - 'tpaint', 15:19:35 INFO - 'sessionrestore', 15:19:35 INFO - 'sessionrestore_no_auto_restore', 15:19:35 INFO - 'tabpaint']}, 15:19:35 INFO - 'other-e10s': {'tests': ['a11yr', 15:19:35 INFO - 'ts_paint', 15:19:35 INFO - 'tpaint', 15:19:35 INFO - 'sessionrestore', 15:19:35 INFO - 'sessionrestore_no_auto_restore', 15:19:35 INFO - 'tabpaint']}, 15:19:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:19:35 INFO - 'ts_paint', 15:19:35 INFO - 'tpaint', 15:19:35 INFO - 'sessionrestore', 15:19:35 INFO - 'sessionrestore_no_auto_restore', 15:19:35 INFO - 'tabpaint']}, 15:19:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:19:35 INFO - 'ts_paint', 15:19:35 INFO - 'tpaint', 15:19:35 INFO - 'sessionrestore', 15:19:35 INFO - 'sessionrestore_no_auto_restore', 15:19:35 INFO - 'tabpaint']}, 15:19:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['a11yr', 15:19:35 INFO - 'ts_paint', 15:19:35 INFO - 'tpaint', 15:19:35 INFO - 'sessionrestore', 15:19:35 INFO - 'sessionrestore_no_auto_restore', 15:19:35 INFO - 'tabpaint']}, 15:19:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['a11yr', 15:19:35 INFO - 'ts_paint', 15:19:35 INFO - 'tpaint', 15:19:35 INFO - 'sessionrestore', 15:19:35 INFO - 'sessionrestore_no_auto_restore', 15:19:35 INFO - 'tabpaint']}, 15:19:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['tsvgx', 15:19:35 INFO - 'tsvgr_opacity', 15:19:35 INFO - 'tart', 15:19:35 INFO - 'tscrollx', 15:19:35 INFO - 'cart']}, 15:19:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:19:35 INFO - 'tsvgr_opacity', 15:19:35 INFO - 'tart', 15:19:35 INFO - 'tscrollx', 15:19:35 INFO - 'cart']}, 15:19:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:35 INFO - 'talos_options': ['--disable-e10s'], 15:19:35 INFO - 'tests': ['tp5o']}, 15:19:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:35 INFO - 'tests': ['tp5o']}, 15:19:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:35 INFO - 'talos_options': ['--disable-e10s', 15:19:35 INFO - '--xperf_path', 15:19:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:35 INFO - 'tests': ['tp5n']}, 15:19:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:19:35 INFO - 'talos_options': ['--xperf_path', 15:19:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:19:35 INFO - 'tests': ['tp5n']}}, 15:19:35 INFO - 'talos.zip': {'path': '', 15:19:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:19:35 INFO - Running post-action listener: _resource_record_post_action 15:19:35 INFO - ##### 15:19:35 INFO - ##### Finished populate-webroot step (success) 15:19:35 INFO - ##### 15:19:35 INFO - ##### 15:19:35 INFO - ##### Running create-virtualenv step. 15:19:35 INFO - ##### 15:19:35 INFO - Running pre-action listener: _resource_record_pre_action 15:19:35 INFO - Running main action method: create_virtualenv 15:19:35 INFO - Creating virtualenv /builds/slave/test/build/venv 15:19:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:19:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:19:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:19:35 INFO - Using real prefix '/usr' 15:19:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:19:36 INFO - Installing distribute.............................................................................................................................................................................................done. 15:19:38 INFO - Installing pip.................done. 15:19:38 INFO - Return code: 0 15:19:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:19:38 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')]} 15:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:38 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')]} 15:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:19:38 INFO - Using env: {'DISPLAY': ':0', 15:19:38 INFO - 'HOME': '/home/cltbld', 15:19:38 INFO - 'LANG': 'en_US.UTF-8', 15:19:38 INFO - 'LANGUAGE': 'en_US:en', 15:19:38 INFO - 'LOGNAME': 'cltbld', 15:19:38 INFO - 'MAIL': '/var/mail/cltbld', 15:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:38 INFO - 'MOZ_NO_REMOTE': '1', 15:19:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:38 INFO - 'NO_EM_RESTART': '1', 15:19:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:38 INFO - 'PWD': '/builds/slave/test', 15:19:38 INFO - 'SHELL': '/bin/bash', 15:19:38 INFO - 'SHLVL': '1', 15:19:38 INFO - 'TERM': 'linux', 15:19:38 INFO - 'TMOUT': '86400', 15:19:38 INFO - 'USER': 'cltbld', 15:19:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:38 INFO - '_': '/tools/buildbot/bin/python'} 15:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:19:38 INFO - Cleaning up... 15:19:38 INFO - Return code: 0 15:19:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:19:38 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')]} 15:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:38 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')]} 15:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:19:38 INFO - Using env: {'DISPLAY': ':0', 15:19:38 INFO - 'HOME': '/home/cltbld', 15:19:38 INFO - 'LANG': 'en_US.UTF-8', 15:19:38 INFO - 'LANGUAGE': 'en_US:en', 15:19:38 INFO - 'LOGNAME': 'cltbld', 15:19:38 INFO - 'MAIL': '/var/mail/cltbld', 15:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:38 INFO - 'MOZ_NO_REMOTE': '1', 15:19:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:38 INFO - 'NO_EM_RESTART': '1', 15:19:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:38 INFO - 'PWD': '/builds/slave/test', 15:19:38 INFO - 'SHELL': '/bin/bash', 15:19:38 INFO - 'SHLVL': '1', 15:19:38 INFO - 'TERM': 'linux', 15:19:38 INFO - 'TMOUT': '86400', 15:19:38 INFO - 'USER': 'cltbld', 15:19:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:38 INFO - '_': '/tools/buildbot/bin/python'} 15:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:38 INFO - Downloading/unpacking psutil>=3.1.1 15:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:19:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:40 INFO - Installing collected packages: psutil 15:19:40 INFO - Running setup.py install for psutil 15:19:40 INFO - building 'psutil._psutil_linux' extension 15:19:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:19:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:19:41 INFO - building 'psutil._psutil_posix' extension 15:19:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:19:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:19:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:41 INFO - Successfully installed psutil 15:19:41 INFO - Cleaning up... 15:19:41 INFO - Return code: 0 15:19:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:19:41 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')]} 15:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:41 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')]} 15:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:19:41 INFO - Using env: {'DISPLAY': ':0', 15:19:41 INFO - 'HOME': '/home/cltbld', 15:19:41 INFO - 'LANG': 'en_US.UTF-8', 15:19:41 INFO - 'LANGUAGE': 'en_US:en', 15:19:41 INFO - 'LOGNAME': 'cltbld', 15:19:41 INFO - 'MAIL': '/var/mail/cltbld', 15:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:41 INFO - 'MOZ_NO_REMOTE': '1', 15:19:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:41 INFO - 'NO_EM_RESTART': '1', 15:19:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:41 INFO - 'PWD': '/builds/slave/test', 15:19:41 INFO - 'SHELL': '/bin/bash', 15:19:41 INFO - 'SHLVL': '1', 15:19:41 INFO - 'TERM': 'linux', 15:19:41 INFO - 'TMOUT': '86400', 15:19:41 INFO - 'USER': 'cltbld', 15:19:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:41 INFO - '_': '/tools/buildbot/bin/python'} 15:19:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:19:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:19:43 INFO - Installing collected packages: mozsystemmonitor 15:19:43 INFO - Running setup.py install for mozsystemmonitor 15:19:43 INFO - Successfully installed mozsystemmonitor 15:19:43 INFO - Cleaning up... 15:19:43 INFO - Return code: 0 15:19:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:19:43 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')]} 15:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:43 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')]} 15:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:19:43 INFO - Using env: {'DISPLAY': ':0', 15:19:43 INFO - 'HOME': '/home/cltbld', 15:19:43 INFO - 'LANG': 'en_US.UTF-8', 15:19:43 INFO - 'LANGUAGE': 'en_US:en', 15:19:43 INFO - 'LOGNAME': 'cltbld', 15:19:43 INFO - 'MAIL': '/var/mail/cltbld', 15:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:43 INFO - 'MOZ_NO_REMOTE': '1', 15:19:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:43 INFO - 'NO_EM_RESTART': '1', 15:19:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:43 INFO - 'PWD': '/builds/slave/test', 15:19:43 INFO - 'SHELL': '/bin/bash', 15:19:43 INFO - 'SHLVL': '1', 15:19:43 INFO - 'TERM': 'linux', 15:19:43 INFO - 'TMOUT': '86400', 15:19:43 INFO - 'USER': 'cltbld', 15:19:43 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:43 INFO - '_': '/tools/buildbot/bin/python'} 15:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:43 INFO - Downloading/unpacking jsonschema==2.5.1 15:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:19:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:45 INFO - Downloading functools32-3.2.3-2.tar.gz 15:19:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:19:46 INFO - warning: no files found matching '*.txt' 15:19:46 INFO - no previously-included directories found matching 'build' 15:19:46 INFO - no previously-included directories found matching 'dist' 15:19:46 INFO - no previously-included directories found matching '.git*' 15:19:46 INFO - Installing collected packages: jsonschema, functools32 15:19:46 INFO - Running setup.py install for functools32 15:19:46 INFO - warning: no files found matching '*.txt' 15:19:46 INFO - no previously-included directories found matching 'build' 15:19:46 INFO - no previously-included directories found matching 'dist' 15:19:46 INFO - no previously-included directories found matching '.git*' 15:19:46 INFO - Successfully installed jsonschema functools32 15:19:46 INFO - Cleaning up... 15:19:46 INFO - Return code: 0 15:19:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:19:46 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')]} 15:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:46 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')]} 15:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:19:46 INFO - Using env: {'DISPLAY': ':0', 15:19:46 INFO - 'HOME': '/home/cltbld', 15:19:46 INFO - 'LANG': 'en_US.UTF-8', 15:19:46 INFO - 'LANGUAGE': 'en_US:en', 15:19:46 INFO - 'LOGNAME': 'cltbld', 15:19:46 INFO - 'MAIL': '/var/mail/cltbld', 15:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:46 INFO - 'MOZ_NO_REMOTE': '1', 15:19:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:46 INFO - 'NO_EM_RESTART': '1', 15:19:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:46 INFO - 'PWD': '/builds/slave/test', 15:19:46 INFO - 'SHELL': '/bin/bash', 15:19:46 INFO - 'SHLVL': '1', 15:19:46 INFO - 'TERM': 'linux', 15:19:46 INFO - 'TMOUT': '86400', 15:19:46 INFO - 'USER': 'cltbld', 15:19:46 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:46 INFO - '_': '/tools/buildbot/bin/python'} 15:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:46 INFO - Downloading/unpacking blobuploader==1.2.4 15:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:48 INFO - Downloading blobuploader-1.2.4.tar.gz 15:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:19:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:19:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:49 INFO - Downloading docopt-0.6.1.tar.gz 15:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:19:49 INFO - Installing collected packages: blobuploader, requests, docopt 15:19:49 INFO - Running setup.py install for blobuploader 15:19:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:19:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:19:49 INFO - Running setup.py install for requests 15:19:49 INFO - Running setup.py install for docopt 15:19:49 INFO - Successfully installed blobuploader requests docopt 15:19:49 INFO - Cleaning up... 15:19:49 INFO - Return code: 0 15:19:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:49 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')]} 15:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:49 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')]} 15:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:19:49 INFO - Using env: {'DISPLAY': ':0', 15:19:49 INFO - 'HOME': '/home/cltbld', 15:19:49 INFO - 'LANG': 'en_US.UTF-8', 15:19:49 INFO - 'LANGUAGE': 'en_US:en', 15:19:49 INFO - 'LOGNAME': 'cltbld', 15:19:49 INFO - 'MAIL': '/var/mail/cltbld', 15:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:49 INFO - 'MOZ_NO_REMOTE': '1', 15:19:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:49 INFO - 'NO_EM_RESTART': '1', 15:19:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:49 INFO - 'PWD': '/builds/slave/test', 15:19:49 INFO - 'SHELL': '/bin/bash', 15:19:49 INFO - 'SHLVL': '1', 15:19:49 INFO - 'TERM': 'linux', 15:19:49 INFO - 'TMOUT': '86400', 15:19:49 INFO - 'USER': 'cltbld', 15:19:49 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:49 INFO - '_': '/tools/buildbot/bin/python'} 15:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:49 INFO - Running setup.py (path:/tmp/pip-2O__2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:50 INFO - Running setup.py (path:/tmp/pip-7IXI3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:50 INFO - Running setup.py (path:/tmp/pip-xDqy7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:50 INFO - Running setup.py (path:/tmp/pip-uj2sen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:50 INFO - Running setup.py (path:/tmp/pip-pP7pKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:50 INFO - Running setup.py (path:/tmp/pip-7A0DJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:50 INFO - Running setup.py (path:/tmp/pip-hJfLc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:50 INFO - Running setup.py (path:/tmp/pip-LgN5HJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:50 INFO - Running setup.py (path:/tmp/pip-vyolUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:50 INFO - Running setup.py (path:/tmp/pip-KM4ES4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:50 INFO - Running setup.py (path:/tmp/pip-5ODCu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:50 INFO - Running setup.py (path:/tmp/pip-dN2FZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:50 INFO - Running setup.py (path:/tmp/pip-3ysmWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:50 INFO - Running setup.py (path:/tmp/pip-cAGetY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:50 INFO - Running setup.py (path:/tmp/pip-M7esrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:51 INFO - Running setup.py (path:/tmp/pip-PJu71m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:51 INFO - Running setup.py (path:/tmp/pip-QLMYFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:19:51 INFO - Running setup.py install for manifestparser 15:19:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:19:51 INFO - Running setup.py install for mozcrash 15:19:51 INFO - Running setup.py install for mozdebug 15:19:51 INFO - Running setup.py install for mozdevice 15:19:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:19:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:19:51 INFO - Running setup.py install for mozfile 15:19:51 INFO - Running setup.py install for mozhttpd 15:19:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:19:51 INFO - Running setup.py install for mozinfo 15:19:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:19:51 INFO - Running setup.py install for mozInstall 15:19:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:19:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:19:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:19:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:19:51 INFO - Running setup.py install for mozleak 15:19:52 INFO - Running setup.py install for mozlog 15:19:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:19:52 INFO - Running setup.py install for moznetwork 15:19:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:19:52 INFO - Running setup.py install for mozprocess 15:19:52 INFO - Running setup.py install for mozprofile 15:19:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:19:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:19:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:19:52 INFO - Running setup.py install for mozrunner 15:19:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:19:52 INFO - Running setup.py install for mozscreenshot 15:19:52 INFO - Running setup.py install for moztest 15:19:52 INFO - Running setup.py install for mozversion 15:19:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:19:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:19:52 INFO - Cleaning up... 15:19:52 INFO - Return code: 0 15:19:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:52 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')]} 15:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:52 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')]} 15:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:19:52 INFO - Using env: {'DISPLAY': ':0', 15:19:52 INFO - 'HOME': '/home/cltbld', 15:19:52 INFO - 'LANG': 'en_US.UTF-8', 15:19:52 INFO - 'LANGUAGE': 'en_US:en', 15:19:52 INFO - 'LOGNAME': 'cltbld', 15:19:52 INFO - 'MAIL': '/var/mail/cltbld', 15:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:52 INFO - 'MOZ_NO_REMOTE': '1', 15:19:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:52 INFO - 'NO_EM_RESTART': '1', 15:19:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:52 INFO - 'PWD': '/builds/slave/test', 15:19:52 INFO - 'SHELL': '/bin/bash', 15:19:52 INFO - 'SHLVL': '1', 15:19:52 INFO - 'TERM': 'linux', 15:19:52 INFO - 'TMOUT': '86400', 15:19:52 INFO - 'USER': 'cltbld', 15:19:52 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:52 INFO - '_': '/tools/buildbot/bin/python'} 15:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:53 INFO - Running setup.py (path:/tmp/pip-BHGAOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:53 INFO - Running setup.py (path:/tmp/pip-9OswM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:53 INFO - Running setup.py (path:/tmp/pip-xE8CKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:53 INFO - Running setup.py (path:/tmp/pip-eXVwBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:53 INFO - Running setup.py (path:/tmp/pip-NBO3X6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:53 INFO - Running setup.py (path:/tmp/pip-REeDHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:53 INFO - Running setup.py (path:/tmp/pip-0rjA9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:53 INFO - Running setup.py (path:/tmp/pip-uWu7E6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:53 INFO - Running setup.py (path:/tmp/pip-6Yaawy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:53 INFO - Running setup.py (path:/tmp/pip-BRIACN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:53 INFO - Running setup.py (path:/tmp/pip-Yd2aQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:19:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:53 INFO - Running setup.py (path:/tmp/pip-e_QT3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:54 INFO - Running setup.py (path:/tmp/pip-_gGiaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:54 INFO - Running setup.py (path:/tmp/pip-fC_Lru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:54 INFO - Running setup.py (path:/tmp/pip-d_4pJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:54 INFO - Running setup.py (path:/tmp/pip-CZd0MF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:54 INFO - Running setup.py (path:/tmp/pip-hjSDjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:19:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:56 INFO - Downloading blessings-1.6.tar.gz 15:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:19:56 INFO - Installing collected packages: blessings 15:19:56 INFO - Running setup.py install for blessings 15:19:56 INFO - Successfully installed blessings 15:19:56 INFO - Cleaning up... 15:19:56 INFO - Return code: 0 15:19:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:19:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:56 INFO - Reading from file tmpfile_stdout 15:19:56 INFO - Current package versions: 15:19:56 INFO - argparse == 1.2.1 15:19:56 INFO - blessings == 1.6 15:19:56 INFO - blobuploader == 1.2.4 15:19:56 INFO - docopt == 0.6.1 15:19:56 INFO - functools32 == 3.2.3-2 15:19:56 INFO - jsonschema == 2.5.1 15:19:56 INFO - manifestparser == 1.1 15:19:56 INFO - mozInstall == 1.12 15:19:56 INFO - mozcrash == 0.17 15:19:56 INFO - mozdebug == 0.1 15:19:56 INFO - mozdevice == 0.48 15:19:56 INFO - mozfile == 1.2 15:19:56 INFO - mozhttpd == 0.7 15:19:56 INFO - mozinfo == 0.9 15:19:56 INFO - mozleak == 0.1 15:19:56 INFO - mozlog == 3.2 15:19:56 INFO - moznetwork == 0.27 15:19:56 INFO - mozprocess == 0.22 15:19:56 INFO - mozprofile == 0.28 15:19:56 INFO - mozrunner == 6.11 15:19:56 INFO - mozscreenshot == 0.1 15:19:56 INFO - mozsystemmonitor == 0.3 15:19:56 INFO - moztest == 0.7 15:19:56 INFO - mozversion == 1.4 15:19:56 INFO - psutil == 3.1.1 15:19:56 INFO - requests == 1.2.3 15:19:56 INFO - wsgiref == 0.1.2 15:19:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19: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')]} 15:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19: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')]} 15:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:19:56 INFO - Using env: {'DISPLAY': ':0', 15:19:56 INFO - 'HOME': '/home/cltbld', 15:19:56 INFO - 'LANG': 'en_US.UTF-8', 15:19:56 INFO - 'LANGUAGE': 'en_US:en', 15:19:56 INFO - 'LOGNAME': 'cltbld', 15:19:56 INFO - 'MAIL': '/var/mail/cltbld', 15:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:56 INFO - 'MOZ_NO_REMOTE': '1', 15:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:56 INFO - 'NO_EM_RESTART': '1', 15:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:56 INFO - 'PWD': '/builds/slave/test', 15:19:56 INFO - 'SHELL': '/bin/bash', 15:19:56 INFO - 'SHLVL': '1', 15:19:56 INFO - 'TERM': 'linux', 15:19:56 INFO - 'TMOUT': '86400', 15:19:56 INFO - 'USER': 'cltbld', 15:19:56 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:56 INFO - '_': '/tools/buildbot/bin/python'} 15:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:19:56 INFO - Cleaning up... 15:19:57 INFO - Return code: 0 15:19:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:19:57 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')]} 15:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:57 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')]} 15:19:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:19:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:19:57 INFO - Using env: {'DISPLAY': ':0', 15:19:57 INFO - 'HOME': '/home/cltbld', 15:19:57 INFO - 'LANG': 'en_US.UTF-8', 15:19:57 INFO - 'LANGUAGE': 'en_US:en', 15:19:57 INFO - 'LOGNAME': 'cltbld', 15:19:57 INFO - 'MAIL': '/var/mail/cltbld', 15:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:57 INFO - 'MOZ_NO_REMOTE': '1', 15:19:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:57 INFO - 'NO_EM_RESTART': '1', 15:19:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:57 INFO - 'PWD': '/builds/slave/test', 15:19:57 INFO - 'SHELL': '/bin/bash', 15:19:57 INFO - 'SHLVL': '1', 15:19:57 INFO - 'TERM': 'linux', 15:19:57 INFO - 'TMOUT': '86400', 15:19:57 INFO - 'USER': 'cltbld', 15:19:57 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:57 INFO - '_': '/tools/buildbot/bin/python'} 15:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:19:57 INFO - Cleaning up... 15:19:57 INFO - Return code: 0 15:19:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:19:57 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')]} 15:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:57 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')]} 15:19:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c84109f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:19:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:19:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:19:57 INFO - Using env: {'DISPLAY': ':0', 15:19:57 INFO - 'HOME': '/home/cltbld', 15:19:57 INFO - 'LANG': 'en_US.UTF-8', 15:19:57 INFO - 'LANGUAGE': 'en_US:en', 15:19:57 INFO - 'LOGNAME': 'cltbld', 15:19:57 INFO - 'MAIL': '/var/mail/cltbld', 15:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:57 INFO - 'MOZ_NO_REMOTE': '1', 15:19:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:19:57 INFO - 'NO_EM_RESTART': '1', 15:19:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:57 INFO - 'PWD': '/builds/slave/test', 15:19:57 INFO - 'SHELL': '/bin/bash', 15:19:57 INFO - 'SHLVL': '1', 15:19:57 INFO - 'TERM': 'linux', 15:19:57 INFO - 'TMOUT': '86400', 15:19:57 INFO - 'USER': 'cltbld', 15:19:57 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:57 INFO - '_': '/tools/buildbot/bin/python'} 15:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:57 INFO - Downloading/unpacking flake8 15:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:19:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:19:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:20:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:20:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:20:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:20:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:20:00 INFO - no previously-included directories found matching 'docs/_build' 15:20:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:20:00 INFO - Running setup.py install for pep8 15:20:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:20:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:20:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:20:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:20:00 INFO - no previously-included directories found matching 'docs/_build' 15:20:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:20:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:20:00 INFO - Cleaning up... 15:20:00 INFO - Return code: 0 15:20:00 INFO - Running post-action listener: _resource_record_post_action 15:20:00 INFO - Running post-action listener: _start_resource_monitoring 15:20:00 INFO - Starting resource monitoring. 15:20:00 INFO - ##### 15:20:00 INFO - ##### Finished create-virtualenv step (success) 15:20:00 INFO - ##### 15:20:00 INFO - ##### 15:20:00 INFO - ##### Running install step. 15:20:00 INFO - ##### 15:20:00 INFO - Running pre-action listener: _resource_record_pre_action 15:20:00 INFO - Running main action method: install 15:20:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:00 INFO - Reading from file tmpfile_stdout 15:20:00 INFO - Detecting whether we're running mozinstall >=1.0... 15:20:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:20:00 INFO - Reading from file tmpfile_stdout 15:20:00 INFO - Output received: 15:20:00 INFO - Usage: mozinstall [options] installer 15:20:00 INFO - Options: 15:20:00 INFO - -h, --help show this help message and exit 15:20:00 INFO - -d DEST, --destination=DEST 15:20:00 INFO - Directory to install application into. [default: 15:20:00 INFO - "/builds/slave/test"] 15:20:00 INFO - --app=APP Application being installed. [default: firefox] 15:20:00 INFO - mkdir: /builds/slave/test/build/application 15:20:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:20:16 INFO - Reading from file tmpfile_stdout 15:20:16 INFO - Output received: 15:20:16 INFO - /builds/slave/test/build/application/firefox/firefox 15:20:16 INFO - Running post-action listener: _resource_record_post_action 15:20:16 INFO - ##### 15:20:16 INFO - ##### Finished install step (success) 15:20:16 INFO - ##### 15:20:16 INFO - ##### 15:20:16 INFO - ##### Running run-tests step. 15:20:16 INFO - ##### 15:20:16 INFO - Running pre-action listener: _resource_record_pre_action 15:20:16 INFO - Running main action method: run_tests 15:20:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:20:16 INFO - Python 2.7.3 15:20:16 INFO - Return code: 0 15:20:16 INFO - grabbing minidump binary from tooltool 15:20:16 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')]} 15:20:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec1c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ed0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ed550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:20:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:20:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:20:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:20:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:20:16 INFO - Return code: 0 15:20:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:20:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:20:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:20:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:20:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:20:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:20:17 INFO - Return code: 0 15:20:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:20:17 INFO - Using env: {'DISPLAY': ':0', 15:20:17 INFO - 'HOME': '/home/cltbld', 15:20:17 INFO - 'LANG': 'en_US.UTF-8', 15:20:17 INFO - 'LANGUAGE': 'en_US:en', 15:20:17 INFO - 'LOGNAME': 'cltbld', 15:20:17 INFO - 'MAIL': '/var/mail/cltbld', 15:20:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:20:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:17 INFO - 'MOZ_NO_REMOTE': '1', 15:20:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:20:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:17 INFO - 'NO_EM_RESTART': '1', 15:20:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:17 INFO - 'PWD': '/builds/slave/test', 15:20:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:20:17 INFO - 'SHELL': '/bin/bash', 15:20:17 INFO - 'SHLVL': '1', 15:20:17 INFO - 'TERM': 'linux', 15:20:17 INFO - 'TMOUT': '86400', 15:20:17 INFO - 'USER': 'cltbld', 15:20:17 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868', 15:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:17 INFO - '_': '/tools/buildbot/bin/python'} 15:20:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:20:17 INFO - mozversion application_buildid: 20160630122127 15:20:17 INFO - mozversion application_changeset: cea38bcafb6ad58462496b582cec8eb361a727df 15:20:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:20:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:20:17 INFO - mozversion application_name: Firefox 15:20:17 INFO - mozversion application_remotingname: firefox-dev 15:20:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:20:17 INFO - mozversion application_vendor: Mozilla 15:20:17 INFO - mozversion application_version: 49.0a2 15:20:17 INFO - mozversion platform_buildid: 20160630122127 15:20:17 INFO - mozversion platform_changeset: cea38bcafb6ad58462496b582cec8eb361a727df 15:20:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:20:17 INFO - mozversion platform_version: 49.0a2 15:20:17 INFO - using testdate: 1467325217 15:20:17 INFO - actual date: 1467325217 15:20:17 INFO - starting webserver on 'localhost:36872' 15:20:17 INFO - SUITE-START | Running 2 tests 15:20:17 INFO - TEST-START | dromaeo_css 15:20:17 INFO - operating with platform_type : linux_ 15:20:17 INFO - Initialising browser for dromaeo_css test... 15:20:17 INFO - TEST-INFO | started process 12036 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmoGsKY/profile http://localhost:36872/getInfo.html) 15:20:21 INFO - PROCESS | 12036 | __metrics Screen width/height:1600/1200 15:20:21 INFO - PROCESS | 12036 | colorDepth:24 15:20:21 INFO - PROCESS | 12036 | Browser inner width/height: 1024/697 15:20:21 INFO - PROCESS | 12036 | __metrics 15:20:23 ERROR - PROCESS | 12036 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:20:24 INFO - TEST-INFO | 12036: exit 0 15:20:24 INFO - Browser initialized. 15:20:24 INFO - Running cycle 1/1 for dromaeo_css test... 15:20:24 INFO - TEST-INFO | started process 12128 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmoGsKY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:20:35 INFO - PROCESS | 12128 | RSS: Main: 145514496 15:20:35 INFO - PROCESS | 12128 | 15:21:26 INFO - PROCESS | 12128 | 1467325286833 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:23:35 INFO - PROCESS | 12128 | Cycle 1(1): loaded http://localhost:36872/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36872/tests/dromaeo/cssquery-ext.html) 15:23:36 INFO - PROCESS | 12128 | RSS: Main: 259678208 15:23:36 INFO - PROCESS | 12128 | 15:26:38 INFO - PROCESS | 12128 | Cycle 1(1): loaded http://localhost:36872/tests/dromaeo/cssquery-ext.html (next: http://localhost:36872/tests/dromaeo/cssquery-jquery.html) 15:26:39 INFO - PROCESS | 12128 | RSS: Main: 227983360 15:26:39 INFO - PROCESS | 12128 | 15:29:41 INFO - PROCESS | 12128 | Cycle 1(1): loaded http://localhost:36872/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36872/tests/dromaeo/cssquery-mootools.html) 15:29:41 INFO - PROCESS | 12128 | RSS: Main: 225091584 15:29:41 INFO - PROCESS | 12128 | 15:32:43 INFO - PROCESS | 12128 | Cycle 1(1): loaded http://localhost:36872/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36872/tests/dromaeo/cssquery-prototype.html) 15:32:44 INFO - PROCESS | 12128 | RSS: Main: 221052928 15:32:44 INFO - PROCESS | 12128 | 15:35:47 INFO - PROCESS | 12128 | Cycle 1(1): loaded http://localhost:36872/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36872/tests/dromaeo/cssquery-yui.html) 15:35:47 INFO - PROCESS | 12128 | RSS: Main: 217718784 15:35:47 INFO - PROCESS | 12128 | 15:38:50 INFO - PROCESS | 12128 | Cycle 1(1): loaded http://localhost:36872/tests/dromaeo/cssquery-yui.html (next: http://localhost:36872/tests/dromaeo/cssquery-dojo.html) 15:38:50 INFO - PROCESS | 12128 | RSS: Main: 259293184 15:38:50 INFO - PROCESS | 12128 | 15:38:50 INFO - PROCESS | 12128 | __start_tp_report 15:38:50 INFO - PROCESS | 12128 | _x_x_mozilla_page_load 15:38:50 INFO - PROCESS | 12128 | _x_x_mozilla_page_load_details 15:38:50 INFO - PROCESS | 12128 | |i|pagename|runs| 15:38:50 INFO - PROCESS | 12128 | |0;dojo.html;3381.6449272826353;3393.440082386408;3395.202127500038;3408.182036311286;3430.5025771263167;5519.11694128939;5551.417101204371;5581.7209139543065;5597.692126933017;5607.243022192003;5562.805301814437;5595.160725891118;5599.972000139994;5614.185943038257;5649.887002259952;4945.307656928033;5006.073876332872;5043.773030213631;5067.594592432597;5110.105731496992;4980.601551875841;4998.600111991032;5010.073136469765;5021;5070.61970352225;5070.7464626768515;5146.639735218536;5153.149730294515;5158.406783219922;5170.069387510247;4936.037472692827;5083.74581270935;5098.133317336062;5128.589712822965;5149.098907691139;4939.55544001038;4982.750862456863;5009.724465154415;5044.268581055763;5049.091163590552;5460.808871689512;5488.451154884519;5524.944750552483;5538.252335934638;5539.695316757577;5721.971390143064;5807.477327040587;5939.168516407706;5950.196723442323;5950.702464876782;5910.881782364371;6002.039673652238;6014.488768454687;6030.969845150747;6035.909461358083;5614.803481878155;5618.073017951992;5619.297587801525;5626.887462250773;5626.971865140648;5867.237259156283;6010.66941318223;6089.995150800157;6100.811584977707;6113.296970848321;5819.592628515963;5851.824445266649;5892.646441213541;5893;5901.645901245939;5991.610545314541;5998.25021872266;6020.548458865515;6032.125341825411;6032.3967603239325;5715.628484148517;5759.049756790167;5787.189793428924;5935.258092738408;5943.762249510058;5830.47525722687;5835.620684655484;5881.088431293157;5885.61743486672;5911.142884281841;4518.502964673949;4522.208613492626;4524;4527.320901864747;4542.386777785023;3998.520177578709;4057.350823868167;4061.5735347788645;4081.8163182656854;4102.3026085565525;5783.59514833966;5792.710364481759;5795.536357091422;5813.709314534256;5818.359980402152;5711.7144142792695;6054.061620448838;6072.423119803611;6086.421789929949;6110.7861224856915;5580.888382232414;5586.385497595261;5592.3009623797025;5659.801906933238;5684.062129748545;5112.233165025202;5116.334876466036;5118.436971933084;5120.590352771769;5121.103806833789;6036.969815150984;6114.48026917717;6179.227596550431;6188.81433556994;6189.8452538686;5892.381299963442;5989.2812862456785;5989.610675306091;5998.400159983967;6026.096085587022;6167.722452489738;6175.752969881154;6179.196704428396;6191.783287584913;6195.256569211723;5702.315722113373;5786.450287222707;5792.884142317214;5870.853228669317;5872.236609240771;5984.461398474158;5984.910226346696;5986.790462333797;5996.910046349221;6019.367966363468;5768.798092066925;5834.095715164156;5835.7373918174635;5836.037053886062;5836.445537673914;5565.248691426767;5725.541956643542;5778.335491418541;5790.884182316414;5825.475707186373;5778.653280802997;5788.82633520995;5790.739416726342;5794.652320860761;5797.188393624811;5875.911861321998;5879.588428809942;5908.143319218775;5919.792807251725;5923.08192230205;5535.5848311376;5537.390887002346;5644.407337229532;5690.658560486384;5711.314642242957 15:38:50 INFO - PROCESS | 12128 | |1;ext.html;606.9514438844893;651.8142329436108;652.26620052441;655.7606473637122;677.9796606101819;32727.345453090955;46285.76857115714;47335;47381.289280660814;47428.76285618576;49300;49481.50518494814;49505;49726.50273497264;49798.75100624501;8202.343812495015;9031.548422578877;9192.494412807326;9195.494247816367;9198.4480931144;8486.448380855256;8553.059163492011;10659.520321585547;10977;10992.230543861893;10836.187285953545;10983;10985.780284394308;10996.725081872977;10996.725081872977;8462.534560599164;11041.503132359023;11097.889021109766;11117.833232501518;11120.61077862271;10476.26666133371;10751.139908807354;10855.40295283767;11247.887521124765;11250.718732031683;44636;44997.32504012442;45060.324095138596;45174.548254517365;45222.32166517472;3741.30785804627;3771.4719939208535;3815.4849095372047;3838.9808050959846;4145.8963525912095;33644.6635533644;35226;35304.29391412183;35391.29217415637;35473.29053418943;4726.787294571718;4765.499622539618;4785.569298763093;4794.856154315341;4911.729854857981;356158;358082.209588953;358245;358444;358703.6194457085;64680.35319646837;65176;65322.673386633716;65365;65369.673151633935;34888.65111348854;35358.823205883804;35455.82272088623;35513;70153.64923175352;16137.435189768301;16231.918840405722;16453.177341132894;16601.169941502878;16631.6673666526;11823.113266505046;13023.47906083746;13624.318784060757;13698.863011369758;13735.381907814173;20324.593508129754;21019.8949005254;21198.682019769716;21232.681509777365;21255.78744212538;7257.92742072583;7274;7444.553326800409;7547.433942454338;7595.886061709079;967.2310513142069;1074.5862842805577;1117.9217454778088;1136.9488373023235;1198.784218840617;34292.48561271583;36178.09554761152;36248.09379765527;36329.27341453156;36508.634913651054;1103.7682086761708;1181.4270079011671;1238.7089034076985;1241.74544218435;1259.4647274908311;17392.565185870455;17548.824511754974;17655.38206162778;17724.91137544304;17800.655776916265;584713;587394;591131;591537;594312;62893;62907.37092629106;63074.68462657657;63088.684556576925;63186.36813631805;1363.5704753002972;1378.4692893236177;1421.5620211337605;1480.1119328403088;1488.6129606302438;31222.843885780425;34582.654173457944;34882.825585871906;35161.58488168904;35217.647823522464;55546.72226638841;56166.71916640554;56173;56279.43720562742;56285;476512;479743;481975;483020;483440;26757.86621066882;29768.255793605334;30776.846115770175;30919.69080309258;31009.68990310068;51780.741096294274;52294.215586765466;52828;54046.459535404145;57672.423275766705;45318.32022519599;45699.54300456953;45865;45956.31065533953;46686.53313466822;44605;44729;44736;45039.774801125786;45934.77032614816;43811;44732;45193;45620;47041.294380585;38693.22613547769;39431.408528871514;39854.202915940936;40354.79822600868;40418.59581404148;29703.702962970092;30063.398732025675;30314.84842575773;30378.58263938186;30500.389992200475 15:38:50 INFO - PROCESS | 12128 | |2;jquery.html;431.6878504397819;433.5014733056986;434.8065111025594;434.99130017399654;448.6567775651625;18442.170102345393;19034.90482547587;20289.898550507245;20357.389278321636;20410.489737756565;16367.754483682755;18757.15592798324;18919.337823176193;19891.801081989175;19916.60166796663;5542.168674698787;5727.885442291152;5732.426757324276;5761.855953601173;5765.6540607563475;5260.395054568736;5584.162375643646;5763.9711801440935;5773.30720313563;5783.161441590967;5269.552088072518;5275.971185618806;5715.485606295453;5777.046787280115;5845.444682755132;5753.683547404891;5782.161586569966;5785;5804.883902321951;5861.531077513789;4882.316475693406;4953.257011448276;5004.124278251328;5042.294078828967;5049.368828896388;22876.885615571817;22918.31245062651;23909.282721518208;23919.760802391927;24052.27843164708;2502.311864237331;2506.5613517634342;2527.4186937004406;2538.2512158913164;2627.2906315294763;14543.563693089225;15050.623734406618;15060.472883449136;15162.696746065018;15376.077435353915;2004.7193392925003;2086.155107181594;2094.1309356616985;2120.5016821047043;2156.0513374115494;77112.80087472714;77768.61115694385;78451.60774196149;78949;79014;10733.141348692243;10856.78286434267;10914.617988370528;10917.617883374043;11168.385738784445;16148.27332770028;16218.270177842025;16241;16264.105474198795;16264.674706505804;8314.127016663284;8458.534780587126;8539.402241843012;8655.610497527627;8754;8326.583670816435;8330.083690794008;8420.284275836622;8427.536485493234;8434.746957591282;6642.335766423319;6873.20958089816;6878.828029299208;6920.5847649141215;6962.582245065312;1937.350987419202;1937.8062193780509;1950.6683863742915;1995.8004199579925;1999.720039194514;774.8140446292962;878.6309749905055;996.8554559588819;1013.2755080117752;1144.3991904250336;19846;19993.200271988957;20106;20168.596628067357;20566.97165141737;1053.378506681062;1088.7495875948575;1108.0802933565121;1110.1784679337393;1161.3032180691519;11262.267952583055;11377.943110284396;11564.363959982282;11564.76870462586;11669.88330116688;493019;498067;498611;502826;513535;49756.50243497591;52227;53497.46502534925;78983.60508197422;82147;1003.2325271167563;1119.9104071674246;1129.8079326514512;1144.1876267849752;1164.394514852272;17957.191926363084;18339;18390.81609183891;18473.81526184721;18872.433826985212;39116.6088339113;39268;39864.800675996434;39940.60059399369;41185.79407102945;50895.2365714522;51554;52414.737926310125;52433.47566524286;53419.7329013368;7615.771526854203;8074.798130046561;8655.653773848975;8818.162274583905;9014.729558113268;13009.739805203653;13024.153430027018;13296.734065318446;13356.599302020955;13399.799003015158;11525.769484610428;11774.941125294661;11825.822612660644;11882.227655202385;11891.762164756483;5704.857378565403;10136.695899123039;11386.032187264176;11441.599544015919;11538.211577205546;11320.490577924178;11383.829242561535;11466.541338346706;11714;11821.82267266009;7560.810979725551;7832.138464768757;8054.758357249292;8222.83554328922;8323.583820809057;8991.955040224757;10873.619423320146;11983.400829958642;12158.270503769802;12381.876181238433 15:38:50 INFO - PROCESS | 12128 | |3;mootools.html;1322.424745235822;1549.9922500387497;1550.0854495847452;1740.4517576963256;1848.1775609853603;9179.714839922408;9605.2796040297;9662.903370966296;9991;10011.399316041043;9706;9907.356021858574;9911.702648920525;9916.50417479125;9918.3057185997;2172;2429.6234083716963;2503.0238207099246;2525.583278759007;2555.4889022195543;2482.9255122346267;2516.4338023944647;2525.7095434025046;2528.532221539012;2530.202986059395;2463.7043554773454;2493.4389762303517;2503.962440563393;2505.6241563765398;2505.887235074426;2120.67129594913;2424.3696638873844;2506.786923111538;2511.3344963584664;2526.5831137862233;2440.5973014452593;2463.5935070713313;2473.703155621337;2485.2171565956846;2490.9252722418355;9897.35667181638;9919.107280344804;9953.402795832273;9959.103680668775;9975.152112070411;5464.26232458617;5577.442255774431;5585.58108141891;5588.6646801191655;5592.189132575753;3440.501127336522;3466;3564.465330200465;3602.8198590070656;3617.5116359291424;1122.4331712485225;1127.3743072594707;1146.8050431426677;1158.7856246594322;1189.1022278180005;13629.25039122848;13635.38640761178;13656.24890631015;13664.726705465835;13666.428360738542;4916.508349165055;4957.157283261854;4995.850124496271;5004.274380214849;5069.619778516626;11190.832137517944;11253.324800511995;11258.268212566158;11258.605948791754;11268.605598804003;4832.057748739033;5661.518770904437;5682.602217844711;5685.573581981368;5689.231953686282;4804.975975120172;4961.355023846877;4964.429090654548;5008.5993120550265;5014.648974571745;4266.679999000087;4325.3728209409455;4328.588784065509;4341.544137865479;4382.342648602735;1271.1864406779669;1276.3554405025588;1282.1858120093807;1321.2468892731051;1328.362386054699;936.3352020065694;943.1464524605243;1000.3347773730533;1043.4417586591137;1097.3909480238544;6751.2135261780195;6752.628605426748;6753.729850806011;6818.147731533559;6902.171739391205;971.3928794503436;1006.582268358623;1013.3008224325192;1037.7768779712246;1038.4496217004998;4445.866624001285;4452.154090722753;4533.705309154894;4535.092981403666;4578.107269082563;12461.314627695383;12780.61658150257;12832.679183020498;12845.678858028437;12864.614061578168;11786.292822430682;12386.071044671686;12398.194117382342;12622.305773182563;12749.235045897276;8805.16350946659;9108.362414631045;9127.634894604274;9161.083891610786;9175.220106290906;6817.829554261183;6841.931580684329;6855.245922948571;7000.859982800213;7082.752103676346;11447.141464390037;11562.537498500302;11621.5351385947;11824.82262766042;11832.053435724949;10974.615888443866;11382.658520244406;11396.829047564459;11874.346910919983;11875;7715.3441957432005;8337.333013358795;8346.916530834615;8553.572321383781;8571.100034496289;9726.610935562498;9740.805183896424;9767.609295628095;9809.607615695293;9810.117089461983;8686.782830429038;8803.251723603566;8819.691310804348;8899.866502002345;8919.063498332582;8343.749687509384;8551.358648101294;8561.529115898686;8587.613557390057;8813.779655508664;7769.844603108019;7883.802904927423;7886.881696774438;7903.644336004779;7998.240167184108;5533.418991006048;5547.472990065938;5611.270534830446;5667.178259152317;5672.14917762339;5761.452661997104;5814.592978491465;5893.911591326219;5985.25184351956;6081.726322315418 15:38:50 INFO - PROCESS | 12128 | |4;prototype.html;361.1783193235389;364.36600315451125;365.69647192829956;366.11948264424063;370.248395756614;5335.946640533593;5395.244665746789;5441.863953401167;5446.400895901453;5523.751431185597;5445.413428621403;5447.1829225616075;5463.726813659311;5469.234307196985;5478.45215478452;4134.152498737752;4156.376543518466;4327.372530983005;4331.436913201287;4371.475422949251;4332.3068309070395;4354.934675979863;4358.389825424443;4363.476382834048;4368.58498442649;4321.416608757824;4333.349997500369;4335.718178318415;4350.260455722535;4362.890927726817;4261.232978063932;4300.741955482681;4334.869953901388;4336.176126535964;4339.47926248849;3837.0982819037504;3856.9421458678144;3865.8260378283044;3867.9806600967095;3868.3810590305698;5297.894042119175;5332.027149954502;5337.653052551611;5354.170103633904;5367.731613419313;4697.647676424248;4916.53292937174;4925.438771838624;4929.605631549467;4975.7760900759195;5664.745086471119;5728.140778883201;5755.971220143872;5763.193152958589;5774.97112514439;4097.651699605537;4190.371444283352;4201.642860356873;4209.958170287408;4244.151169766028;5843.6493810371085;5938.8218353449465;5974.940250597482;5982.401759824026;5996.999999999956;4218.8523401680795;4227.408162857202;4242.257604919127;4256.808443620044;4267.591694740728;4315;4318.827246910088;4320.925955541357;4359.738415695005;4379.693421460467;4651.953480465219;4718.882027949328;4794.2089555223;4918.114739346952;4932;5736.368999410061;5748.051571490741;5754.877798829188;5773.855653608609;5773.971130144322;1482.947107553649;1492.7611582146803;1494.006485687006;1496.5884381794876;1503.1056521369985;909.554318383987;956.3985958193701;970.3304719743354;971.4851128901694;973.648638537923;4993.675411098339;5040.513048650657;5054.797808087709;5097.337346144977;5108.77389426535;5107.310513080761;5224.059669259494;5242.135047717084;5263.7894484220205;5264.552513036435;5030.522100400456;5102.336696229467;5118.846434606968;5310.548603368679;5838.1242813577455;3164.5095010273444;3183;3185.761067919915;3185.761067919915;3209.40625984189;6074.271087469444;6185.257769067741;6196.256449226032;6207.720652570645;6244.313125556185;5993.400659933972;5999.700014999233;6004.639721616717;6009.909851352233;6031.849203769853;6167.660778657217;6169.290531588835;6191.938080619227;6283.654399008098;6288.717007734572;5818.883622327445;5899.793507227227;5937.287525496795;5958.374370691015;5981.671008094597;5811.82564523065;5819.592628515963;5851.882962340815;5902.84894445579;5914.497267732291;5962.970185149046;6134.846628834315;6197.876042479215;6201.224846894138;6216.595921265103;4222.19778242133;4249.747633754116;4278.294081476522;4279.743215407086;4298.505671847777;5704.375987046005;5814.30228372598;5984;5984.850378740566;5990.760369585168;5709.971450142889;5719.656820590778;5725.826205627753;5760.769569217352;5771.624844385102;5769.192313076257;5771.509421699203;5778.595498315078;5811.244538210006;5858.560607954506;5575.968445837533;5693.28833895763;5737.770489180386;5756.856078598069;5761.971190144022;5780.421957804186;5828.883422331445;5834.474897259267;5858.033424484912;5868.735906884115;5489.737360407049;5690.630109042899;5708.457696518824;5720.742566584597;5758.222639943554 15:38:50 INFO - PROCESS | 12128 | |5;yui.html;745.9142198647154;811.1726039439768;850.9361797865159;851.808343122797;879.3580686099148;14920.552383428487;15022.699546009073;15031.398744050239;15143.545693629196;16200.756988645166;15206.391744330214;15355.155466449369;15355.539333819976;15369.69260614787;15388.38446462143;713.6788445199655;826.3513142183388;855.4396870050127;855.8373908957309;859.3597769661579;848.7750746052301;853.0744142605281;855.5551113421018;855.687673998989;856.1395797223781;904.5658084119626;906.207068814787;906.650939388337;906.9863952040724;907.0294784580501;743.947923645345;865.8051938313891;891.2558014058269;891.79488717595;896.1173244354305;660.4056349285634;661.6724721263006;661.9371159739865;662.2020465339302;662.552776875607;15187.924060379737;15217.391304347813;15245.923770381189;15278.31247593861;15287.770683439758;15052.397904083824;15107.54677359681;15140.697186056328;15313.923430382776;15399.615009624737;12930.935345323212;13441.39513721885;13475.86524134756;13503.25732084735;13635.590932271949;4674.439067311911;4697.976510117462;4707.8587642370785;4752.382190315271;4858.368412106439;74584.62707686427;78399;78603.21396786017;79068.60465697634;79254.20745792527;11255.493502792231;11287.774244515227;11414.143939204427;11438.370889601152;11558.595449159238;21020.579588408145;21182.15271389158;21188.364349069554;21308.467288317916;21772;9249.398789078688;9261.259099272042;9287.303452241109;9312.161905428544;9417.90582094184;5137.794488220504;5168.483151684802;5170.638055336166;5172.793088276427;5243.973780131075;3817.3701339279264;3957.2085582883537;3958.267720471722;3960.584138665456;3964.5044369453817;650.7364517370472;665.2449469851753;674.4233680203454;680.7072958627739;742.7920182348951;756.0700338583573;781.6784840773751;794.1145622630725;811.679386642273;833.3832963607008;10585.629502967513;10871.891281087088;10957.726056848483;11000.504977276041;11140.220184587162;797.8723404255292;810.9959450202712;817.8078151634361;832.9458585191943;836.8828364029041;5032.949670503322;5249.658772179872;5296.09966305729;5355.116405793001;5359.678419294854;76008.61995690096;100350;123041;124788;129292;103157;108160;109232.4538377319;109972.4501377488;111975;92003;93247.53376233076;94514;94622;94819.52590237005;10155.796884062425;10185.287029907835;10186.236032297458;10224.642137525449;10261.486925653538;54831;55111.72444137753;55562.72218639043;56428.43571564232;57306.14040789308;79406.6029669871;79655;79960;80973.59513202397;82883.58558207411;12589.811152832532;12738.363081845686;12742.74514509686;12902.483900643869;13034.739305214032;14383.856161438252;14805.18571478579;14987.700245995236;15132.243387830344;15283.61790955235;12950.287734174712;12954.870451295366;12995.740085198433;13046.41291141882;13354.532591359255;13680.31598420095;13821.792673110112;13971.860281397056;14467.348969296618;14784.852151478348;14611.780823287872;14622.780658289921;14752.926235368754;14865.331060102106;14878.181700006604;11656.125790565571;11676.70808229801;11709.297442153498;11910.583129590423;12350.814737779121;11897.167198296036;12160.878391216329;12167.695807604881;12491.375431228584;12633.87366126327 15:38:50 INFO - PROCESS | 12128 | __end_tp_report 15:38:50 INFO - PROCESS | 12128 | __start_cc_report 15:38:50 INFO - PROCESS | 12128 | _x_x_mozilla_cycle_collect,946 15:38:50 INFO - PROCESS | 12128 | __end_cc_report 15:38:50 INFO - PROCESS | 12128 | __startTimestamp1467326330326__endTimestamp 15:38:50 INFO - PROCESS | 12128 | 15:38:50 INFO - PROCESS | 12128 | ------- Summary: start ------- 15:38:50 INFO - PROCESS | 12128 | Number of tests: 6 15:38:50 INFO - PROCESS | 12128 | 15:38:50 INFO - PROCESS | 12128 | [#0] dojo.html Cycles:165 Average:5532.95 Median:5768.80 stddev:610.19 (10.6%) stddev-sans-first:588.26 15:38:50 INFO - PROCESS | 12128 | Values: 3381.6 3393.4 3395.2 3408.2 3430.5 5519.1 5551.4 5581.7 5597.7 5607.2 5562.8 5595.2 5600.0 5614.2 5649.9 4945.3 5006.1 5043.8 5067.6 5110.1 4980.6 4998.6 5010.1 5021.0 5070.6 5070.7 5146.6 5153.1 5158.4 5170.1 4936.0 5083.7 5098.1 5128.6 5149.1 4939.6 4982.8 5009.7 5044.3 5049.1 5460.8 5488.5 5524.9 5538.3 5539.7 5722.0 5807.5 5939.2 5950.2 5950.7 5910.9 6002.0 6014.5 6031.0 6035.9 5614.8 5618.1 5619.3 5626.9 5627.0 5867.2 6010.7 6090.0 6100.8 6113.3 5819.6 5851.8 5892.6 5893.0 5901.6 5991.6 5998.3 6020.5 6032.1 6032.4 5715.6 5759.0 5787.2 5935.3 5943.8 5830.5 5835.6 5881.1 5885.6 5911.1 4518.5 4522.2 4524.0 4527.3 4542.4 3998.5 4057.4 4061.6 4081.8 4102.3 5783.6 5792.7 5795.5 5813.7 5818.4 5711.7 6054.1 6072.4 6086.4 6110.8 5580.9 5586.4 5592.3 5659.8 5684.1 5112.2 5116.3 5118.4 5120.6 5121.1 6037.0 6114.5 6179.2 6188.8 6189.8 5892.4 5989.3 5989.6 5998.4 6026.1 6167.7 6175.8 6179.2 6191.8 6195.3 5702.3 5786.5 5792.9 5870.9 5872.2 5984.5 5984.9 5986.8 5996.9 6019.4 5768.8 5834.1 5835.7 5836.0 5836.4 5565.2 5725.5 5778.3 5790.9 5825.5 5778.7 5788.8 5790.7 5794.7 5797.2 5875.9 5879.6 5908.1 5919.8 5923.1 5535.6 5537.4 5644.4 5690.7 5711.3 15:38:50 INFO - PROCESS | 12128 | 15:38:50 INFO - PROCESS | 12128 | [#1] ext.html Cycles:180 Average:64692.73 Median:39062.32 stddev:128859.39 (329.9%) stddev-sans-first:129131.04 15:38:50 INFO - PROCESS | 12128 | Values: 607.0 651.8 652.3 655.8 678.0 32727.3 46285.8 47335.0 47381.3 47428.8 49300.0 49481.5 49505.0 49726.5 49798.8 8202.3 9031.5 9192.5 9195.5 9198.4 8486.4 8553.1 10659.5 10977.0 10992.2 10836.2 10983.0 10985.8 10996.7 10996.7 8462.5 11041.5 11097.9 11117.8 11120.6 10476.3 10751.1 10855.4 11247.9 11250.7 44636.0 44997.3 45060.3 45174.5 45222.3 3741.3 3771.5 3815.5 3839.0 4145.9 33644.7 35226.0 35304.3 35391.3 35473.3 4726.8 4765.5 4785.6 4794.9 4911.7 356158.0 358082.2 358245.0 358444.0 358703.6 64680.4 65176.0 65322.7 65365.0 65369.7 34888.7 35358.8 35455.8 35513.0 70153.6 16137.4 16231.9 16453.2 16601.2 16631.7 11823.1 13023.5 13624.3 13698.9 13735.4 20324.6 21019.9 21198.7 21232.7 21255.8 7257.9 7274.0 7444.6 7547.4 7595.9 967.2 1074.6 1117.9 1136.9 1198.8 34292.5 36178.1 36248.1 36329.3 36508.6 1103.8 1181.4 1238.7 1241.7 1259.5 17392.6 17548.8 17655.4 17724.9 17800.7 584713.0 587394.0 591131.0 591537.0 594312.0 62893.0 62907.4 63074.7 63088.7 63186.4 1363.6 1378.5 1421.6 1480.1 1488.6 31222.8 34582.7 34882.8 35161.6 35217.6 55546.7 56166.7 56173.0 56279.4 56285.0 476512.0 479743.0 481975.0 483020.0 483440.0 26757.9 29768.3 30776.8 30919.7 31009.7 51780.7 52294.2 52828.0 54046.5 57672.4 45318.3 45699.5 45865.0 45956.3 46686.5 44605.0 44729.0 44736.0 45039.8 45934.8 43811.0 44732.0 45193.0 45620.0 47041.3 38693.2 39431.4 39854.2 40354.8 40418.6 29703.7 30063.4 30314.8 30378.6 30500.4 15:38:50 INFO - PROCESS | 12128 | 15:38:50 INFO - PROCESS | 12128 | [#2] jquery.html Cycles:180 Average:28473.08 Median:2504.44 stddev:81993.74 (3273.9%) stddev-sans-first:82196.72 15:38:50 INFO - PROCESS | 12128 | Values: 431.7 433.5 434.8 435.0 448.7 18442.2 19034.9 20289.9 20357.4 20410.5 16367.8 18757.2 18919.3 19891.8 19916.6 5542.2 5727.9 5732.4 5761.9 5765.7 5260.4 5584.2 5764.0 5773.3 5783.2 5269.6 5276.0 5715.5 5777.0 5845.4 5753.7 5782.2 5785.0 5804.9 5861.5 4882.3 4953.3 5004.1 5042.3 5049.4 22876.9 22918.3 23909.3 23919.8 24052.3 2502.3 2506.6 2527.4 2538.3 2627.3 14543.6 15050.6 15060.5 15162.7 15376.1 2004.7 2086.2 2094.1 2120.5 2156.1 77112.8 77768.6 78451.6 78949.0 79014.0 10733.1 10856.8 10914.6 10917.6 11168.4 16148.3 16218.3 16241.0 16264.1 16264.7 8314.1 8458.5 8539.4 8655.6 8754.0 8326.6 8330.1 8420.3 8427.5 8434.7 6642.3 6873.2 6878.8 6920.6 6962.6 1937.4 1937.8 1950.7 1995.8 1999.7 774.8 878.6 996.9 1013.3 1144.4 19846.0 19993.2 20106.0 20168.6 20567.0 1053.4 1088.7 1108.1 1110.2 1161.3 11262.3 11377.9 11564.4 11564.8 11669.9 493019.0 498067.0 498611.0 502826.0 513535.0 49756.5 52227.0 53497.5 78983.6 82147.0 1003.2 1119.9 1129.8 1144.2 1164.4 17957.2 18339.0 18390.8 18473.8 18872.4 39116.6 39268.0 39864.8 39940.6 41185.8 50895.2 51554.0 52414.7 52433.5 53419.7 7615.8 8074.8 8655.7 8818.2 9014.7 13009.7 13024.2 13296.7 13356.6 13399.8 11525.8 11774.9 11825.8 11882.2 11891.8 5704.9 10136.7 11386.0 11441.6 11538.2 11320.5 11383.8 11466.5 11714.0 11821.8 7560.8 7832.1 8054.8 8222.8 8323.6 8992.0 10873.6 11983.4 12158.3 12381.9 15:38:50 INFO - PROCESS | 12128 | 15:38:50 INFO - PROCESS | 12128 | [#3] mootools.html Cycles:180 Average:6448.84 Median:4534.40 stddev:3782.12 (83.4%) stddev-sans-first:3773.10 15:38:50 INFO - PROCESS | 12128 | Values: 1322.4 1550.0 1550.1 1740.5 1848.2 9179.7 9605.3 9662.9 9991.0 10011.4 9706.0 9907.4 9911.7 9916.5 9918.3 2172.0 2429.6 2503.0 2525.6 2555.5 2482.9 2516.4 2525.7 2528.5 2530.2 2463.7 2493.4 2504.0 2505.6 2505.9 2120.7 2424.4 2506.8 2511.3 2526.6 2440.6 2463.6 2473.7 2485.2 2490.9 9897.4 9919.1 9953.4 9959.1 9975.2 5464.3 5577.4 5585.6 5588.7 5592.2 3440.5 3466.0 3564.5 3602.8 3617.5 1122.4 1127.4 1146.8 1158.8 1189.1 13629.3 13635.4 13656.2 13664.7 13666.4 4916.5 4957.2 4995.9 5004.3 5069.6 11190.8 11253.3 11258.3 11258.6 11268.6 4832.1 5661.5 5682.6 5685.6 5689.2 4805.0 4961.4 4964.4 5008.6 5014.6 4266.7 4325.4 4328.6 4341.5 4382.3 1271.2 1276.4 1282.2 1321.2 1328.4 936.3 943.1 1000.3 1043.4 1097.4 6751.2 6752.6 6753.7 6818.1 6902.2 971.4 1006.6 1013.3 1037.8 1038.4 4445.9 4452.2 4533.7 4535.1 4578.1 12461.3 12780.6 12832.7 12845.7 12864.6 11786.3 12386.1 12398.2 12622.3 12749.2 8805.2 9108.4 9127.6 9161.1 9175.2 6817.8 6841.9 6855.2 7000.9 7082.8 11447.1 11562.5 11621.5 11824.8 11832.1 10974.6 11382.7 11396.8 11874.3 11875.0 7715.3 8337.3 8346.9 8553.6 8571.1 9726.6 9740.8 9767.6 9809.6 9810.1 8686.8 8803.3 8819.7 8899.9 8919.1 8343.7 8551.4 8561.5 8587.6 8813.8 7769.8 7883.8 7886.9 7903.6 7998.2 5533.4 5547.5 5611.3 5667.2 5672.1 5761.5 5814.6 5893.9 5985.3 6081.7 15:38:50 INFO - PROCESS | 12128 | 15:38:50 INFO - PROCESS | 12128 | [#4] prototype.html Cycles:180 Average:4835.22 Median:5418.55 stddev:1409.51 (26.0%) stddev-sans-first:1372.88 15:38:50 INFO - PROCESS | 12128 | Values: 361.2 364.4 365.7 366.1 370.2 5335.9 5395.2 5441.9 5446.4 5523.8 5445.4 5447.2 5463.7 5469.2 5478.5 4134.2 4156.4 4327.4 4331.4 4371.5 4332.3 4354.9 4358.4 4363.5 4368.6 4321.4 4333.3 4335.7 4350.3 4362.9 4261.2 4300.7 4334.9 4336.2 4339.5 3837.1 3856.9 3865.8 3868.0 3868.4 5297.9 5332.0 5337.7 5354.2 5367.7 4697.6 4916.5 4925.4 4929.6 4975.8 5664.7 5728.1 5756.0 5763.2 5775.0 4097.7 4190.4 4201.6 4210.0 4244.2 5843.6 5938.8 5974.9 5982.4 5997.0 4218.9 4227.4 4242.3 4256.8 4267.6 4315.0 4318.8 4320.9 4359.7 4379.7 4652.0 4718.9 4794.2 4918.1 4932.0 5736.4 5748.1 5754.9 5773.9 5774.0 1482.9 1492.8 1494.0 1496.6 1503.1 909.6 956.4 970.3 971.5 973.6 4993.7 5040.5 5054.8 5097.3 5108.8 5107.3 5224.1 5242.1 5263.8 5264.6 5030.5 5102.3 5118.8 5310.5 5838.1 3164.5 3183.0 3185.8 3185.8 3209.4 6074.3 6185.3 6196.3 6207.7 6244.3 5993.4 5999.7 6004.6 6009.9 6031.8 6167.7 6169.3 6191.9 6283.7 6288.7 5818.9 5899.8 5937.3 5958.4 5981.7 5811.8 5819.6 5851.9 5902.8 5914.5 5963.0 6134.8 6197.9 6201.2 6216.6 4222.2 4249.7 4278.3 4279.7 4298.5 5704.4 5814.3 5984.0 5984.9 5990.8 5710.0 5719.7 5725.8 5760.8 5771.6 5769.2 5771.5 5778.6 5811.2 5858.6 5576.0 5693.3 5737.8 5756.9 5762.0 5780.4 5828.9 5834.5 5858.0 5868.7 5489.7 5690.6 5708.5 5720.7 5758.2 15:38:50 INFO - PROCESS | 12128 | 15:38:50 INFO - PROCESS | 12128 | [#5] yui.html Cycles:180 Average:21809.07 Median:3957.74 stddev:31351.01 (792.1%) stddev-sans-first:31399.06 15:38:50 INFO - PROCESS | 12128 | Values: 745.9 811.2 850.9 851.8 879.4 14920.6 15022.7 15031.4 15143.5 16200.8 15206.4 15355.2 15355.5 15369.7 15388.4 713.7 826.4 855.4 855.8 859.4 848.8 853.1 855.6 855.7 856.1 904.6 906.2 906.7 907.0 907.0 743.9 865.8 891.3 891.8 896.1 660.4 661.7 661.9 662.2 662.6 15187.9 15217.4 15245.9 15278.3 15287.8 15052.4 15107.5 15140.7 15313.9 15399.6 12930.9 13441.4 13475.9 13503.3 13635.6 4674.4 4698.0 4707.9 4752.4 4858.4 74584.6 78399.0 78603.2 79068.6 79254.2 11255.5 11287.8 11414.1 11438.4 11558.6 21020.6 21182.2 21188.4 21308.5 21772.0 9249.4 9261.3 9287.3 9312.2 9417.9 5137.8 5168.5 5170.6 5172.8 5244.0 3817.4 3957.2 3958.3 3960.6 3964.5 650.7 665.2 674.4 680.7 742.8 756.1 781.7 794.1 811.7 833.4 10585.6 10871.9 10957.7 11000.5 11140.2 797.9 811.0 817.8 832.9 836.9 5032.9 5249.7 5296.1 5355.1 5359.7 76008.6 100350.0 123041.0 124788.0 129292.0 103157.0 108160.0 109232.5 109972.5 111975.0 92003.0 93247.5 94514.0 94622.0 94819.5 10155.8 10185.3 10186.2 10224.6 10261.5 54831.0 55111.7 55562.7 56428.4 57306.1 79406.6 79655.0 79960.0 80973.6 82883.6 12589.8 12738.4 12742.7 12902.5 13034.7 14383.9 14805.2 14987.7 15132.2 15283.6 12950.3 12954.9 12995.7 13046.4 13354.5 13680.3 13821.8 13971.9 14467.3 14784.9 14611.8 14622.8 14752.9 14865.3 14878.2 11656.1 11676.7 11709.3 11910.6 12350.8 11897.2 12160.9 12167.7 12491.4 12633.9 15:38:50 INFO - PROCESS | 12128 | -------- Summary: end -------- 15:38:50 INFO - PROCESS | 12128 | 15:38:50 INFO - TEST-INFO | 12128: exit 0 15:38:50 INFO - TEST-OK | dromaeo_css | took 1113327ms 15:38:50 INFO - TEST-START | kraken 15:38:50 INFO - operating with platform_type : linux_ 15:38:50 INFO - Initialising browser for kraken test... 15:38:51 INFO - TEST-INFO | started process 12317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOPczH6/profile http://localhost:36872/getInfo.html) 15:38:55 INFO - PROCESS | 12317 | __metrics Screen width/height:1600/1200 15:38:55 INFO - PROCESS | 12317 | colorDepth:24 15:38:55 INFO - PROCESS | 12317 | Browser inner width/height: 1024/697 15:38:55 INFO - PROCESS | 12317 | __metrics 15:38:56 ERROR - PROCESS | 12317 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:38:57 INFO - TEST-INFO | 12317: exit 0 15:38:57 INFO - Browser initialized. 15:38:57 INFO - Running cycle 1/1 for kraken test... 15:38:57 INFO - TEST-INFO | started process 12409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOPczH6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:39:09 INFO - PROCESS | 12409 | RSS: Main: 145661952 15:39:09 INFO - PROCESS | 12409 | 15:39:11 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36872/tests/kraken/driver.html?testName=audio-beat-detection) 15:39:12 INFO - PROCESS | 12409 | RSS: Main: 194494464 15:39:12 INFO - PROCESS | 12409 | 15:39:15 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36872/tests/kraken/driver.html?testName=audio-dft) 15:39:15 INFO - PROCESS | 12409 | RSS: Main: 193093632 15:39:15 INFO - PROCESS | 12409 | 15:39:18 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36872/tests/kraken/driver.html?testName=audio-fft) 15:39:19 INFO - PROCESS | 12409 | RSS: Main: 278949888 15:39:19 INFO - PROCESS | 12409 | 15:39:21 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36872/tests/kraken/driver.html?testName=audio-oscillator) 15:39:21 INFO - PROCESS | 12409 | RSS: Main: 187871232 15:39:21 INFO - PROCESS | 12409 | 15:39:23 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36872/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:39:24 INFO - PROCESS | 12409 | RSS: Main: 188575744 15:39:24 INFO - PROCESS | 12409 | 15:39:27 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36872/tests/kraken/driver.html?testName=imaging-darkroom) 15:39:28 INFO - PROCESS | 12409 | RSS: Main: 223551488 15:39:28 INFO - PROCESS | 12409 | 15:39:31 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36872/tests/kraken/driver.html?testName=imaging-desaturate) 15:39:32 INFO - PROCESS | 12409 | RSS: Main: 223006720 15:39:32 INFO - PROCESS | 12409 | 15:39:34 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36872/tests/kraken/driver.html?testName=json-parse-financial) 15:39:35 INFO - PROCESS | 12409 | RSS: Main: 222695424 15:39:35 INFO - PROCESS | 12409 | 15:39:36 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36872/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:39:37 INFO - PROCESS | 12409 | RSS: Main: 201474048 15:39:37 INFO - PROCESS | 12409 | 15:39:39 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36872/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:39:39 INFO - PROCESS | 12409 | RSS: Main: 200687616 15:39:39 INFO - PROCESS | 12409 | 15:39:41 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36872/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:39:42 INFO - PROCESS | 12409 | RSS: Main: 209457152 15:39:42 INFO - PROCESS | 12409 | 15:39:45 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36872/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:39:45 INFO - PROCESS | 12409 | RSS: Main: 217542656 15:39:45 INFO - PROCESS | 12409 | 15:39:48 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36872/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:39:48 INFO - PROCESS | 12409 | RSS: Main: 217112576 15:39:48 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | Cycle 1(1): loaded http://localhost:36872/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36872/tests/kraken/driver.html?testName=ai-astar) 15:39:50 INFO - PROCESS | 12409 | RSS: Main: 259866624 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | __start_tp_report 15:39:50 INFO - PROCESS | 12409 | _x_x_mozilla_page_load 15:39:50 INFO - PROCESS | 12409 | _x_x_mozilla_page_load_details 15:39:50 INFO - PROCESS | 12409 | |i|pagename|runs| 15:39:50 INFO - PROCESS | 12409 | |0;ai-astar;109;102;109;108;109;109;109;109;108;109 15:39:50 INFO - PROCESS | 12409 | |1;audio-beat-detection;152;127;126;171;137;132;150;124;151;124 15:39:50 INFO - PROCESS | 12409 | |2;audio-dft;161;160;161;162;162;161;161;160;161;161 15:39:50 INFO - PROCESS | 12409 | |3;audio-fft;75;76;76;121;75;76;76;76;99;76 15:39:50 INFO - PROCESS | 12409 | |4;audio-oscillator;87;85;108;85;86;85;129;85;86;129 15:39:50 INFO - PROCESS | 12409 | |5;imaging-gaussian-blur;117;117;116;117;118;117;117;117;117;117 15:39:50 INFO - PROCESS | 12409 | |6;imaging-darkroom;145;146;146;145;147;146;145;146;145;145 15:39:50 INFO - PROCESS | 12409 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 15:39:50 INFO - PROCESS | 12409 | |8;json-parse-financial;51;52;52;53;53;53;55;54;52;53 15:39:50 INFO - PROCESS | 12409 | |9;json-stringify-tinderbox;68;59;60;60;60;60;61;59;65;61 15:39:50 INFO - PROCESS | 12409 | |10;stanford-crypto-aes;76;76;76;76;76;77;90;76;100;77 15:39:50 INFO - PROCESS | 12409 | |11;stanford-crypto-ccm;119;132;117;117;123;115;116;129;132;129 15:39:50 INFO - PROCESS | 12409 | |12;stanford-crypto-pbkdf2;160;153;154;154;158;158;151;159;154;150 15:39:50 INFO - PROCESS | 12409 | |13;stanford-crypto-sha256-iterative;64;61;63;62;62;62;62;64;61;60 15:39:50 INFO - PROCESS | 12409 | __end_tp_report 15:39:50 INFO - PROCESS | 12409 | __start_cc_report 15:39:50 INFO - PROCESS | 12409 | _x_x_mozilla_cycle_collect,1530 15:39:50 INFO - PROCESS | 12409 | __end_cc_report 15:39:50 INFO - PROCESS | 12409 | __startTimestamp1467326390739__endTimestamp 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | ------- Summary: start ------- 15:39:50 INFO - PROCESS | 12409 | Number of tests: 14 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#0] ai-astar Cycles:10 Average:108.10 Median:109.00 stddev:2.18 (2.0%) stddev-sans-first:2.29 15:39:50 INFO - PROCESS | 12409 | Values: 109.0 102.0 109.0 108.0 109.0 109.0 109.0 109.0 108.0 109.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:143.50 stddev:15.89 (11.1%) stddev-sans-first:16.19 15:39:50 INFO - PROCESS | 12409 | Values: 152.0 127.0 126.0 171.0 137.0 132.0 150.0 124.0 151.0 124.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 15:39:50 INFO - PROCESS | 12409 | Values: 161.0 160.0 161.0 162.0 162.0 161.0 161.0 160.0 161.0 161.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#3] audio-fft Cycles:10 Average:82.60 Median:76.00 stddev:15.35 (20.2%) stddev-sans-first:16.03 15:39:50 INFO - PROCESS | 12409 | Values: 75.0 76.0 76.0 121.0 75.0 76.0 76.0 76.0 99.0 76.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:85.00 stddev:18.51 (21.8%) stddev-sans-first:19.31 15:39:50 INFO - PROCESS | 12409 | Values: 87.0 85.0 108.0 85.0 86.0 85.0 129.0 85.0 86.0 129.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 15:39:50 INFO - PROCESS | 12409 | Values: 117.0 117.0 116.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 15:39:50 INFO - PROCESS | 12409 | Values: 145.0 146.0 146.0 145.0 147.0 146.0 145.0 146.0 145.0 145.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:39:50 INFO - PROCESS | 12409 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.14 (2.1%) stddev-sans-first:1.00 15:39:50 INFO - PROCESS | 12409 | Values: 51.0 52.0 52.0 53.0 53.0 53.0 55.0 54.0 52.0 53.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:60.50 stddev:2.91 (4.8%) stddev-sans-first:1.81 15:39:50 INFO - PROCESS | 12409 | Values: 68.0 59.0 60.0 60.0 60.0 60.0 61.0 59.0 65.0 61.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:76.00 stddev:8.26 (10.9%) stddev-sans-first:8.63 15:39:50 INFO - PROCESS | 12409 | Values: 76.0 76.0 76.0 76.0 76.0 77.0 90.0 76.0 100.0 77.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#11] stanford-crypto-ccm Cycles:10 Average:122.90 Median:126.00 stddev:6.95 (5.5%) stddev-sans-first:7.23 15:39:50 INFO - PROCESS | 12409 | Values: 119.0 132.0 117.0 117.0 123.0 115.0 116.0 129.0 132.0 129.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.10 Median:156.00 stddev:3.45 (2.2%) stddev-sans-first:3.17 15:39:50 INFO - PROCESS | 12409 | Values: 160.0 153.0 154.0 154.0 158.0 158.0 151.0 159.0 154.0 150.0 15:39:50 INFO - PROCESS | 12409 | 15:39:50 INFO - PROCESS | 12409 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:1.29 (2.1%) stddev-sans-first:1.17 15:39:50 INFO - PROCESS | 12409 | Values: 64.0 61.0 63.0 62.0 62.0 62.0 62.0 64.0 61.0 60.0 15:39:50 INFO - PROCESS | 12409 | -------- Summary: end -------- 15:39:50 INFO - PROCESS | 12409 | 15:39:51 INFO - TEST-INFO | 12409: exit 0 15:39:51 INFO - TEST-OK | kraken | took 60684ms 15:39:51 INFO - SUITE-END | took 1174s 15:39:51 INFO - Completed test suite (00:19:34) 15:39:51 INFO - javascript benchmark 15:39:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3381.6449272826353, 3393.440082386408, 3395.202127500038, 3408.182036311286, 3430.5025771263167, 5519.11694128939, 5551.417101204371, 5581.7209139543065, 5597.692126933017, 5607.243022192003, 5562.805301814437, 5595.160725891118, 5599.972000139994, 5614.185943038257, 5649.887002259952, 4945.307656928033, 5006.073876332872, 5043.773030213631, 5067.594592432597, 5110.105731496992, 4980.601551875841, 4998.600111991032, 5010.073136469765, 5021.0, 5070.61970352225, 5070.7464626768515, 5146.639735218536, 5153.149730294515, 5158.406783219922, 5170.069387510247, 4936.037472692827, 5083.74581270935, 5098.133317336062, 5128.589712822965, 5149.098907691139, 4939.55544001038, 4982.750862456863, 5009.724465154415, 5044.268581055763, 5049.091163590552, 5460.808871689512, 5488.451154884519, 5524.944750552483, 5538.252335934638, 5539.695316757577, 5721.971390143064, 5807.477327040587, 5939.168516407706, 5950.196723442323, 5950.702464876782, 5910.881782364371, 6002.039673652238, 6014.488768454687, 6030.969845150747, 6035.909461358083, 5614.803481878155, 5618.073017951992, 5619.297587801525, 5626.887462250773, 5626.971865140648, 5867.237259156283, 6010.66941318223, 6089.995150800157, 6100.811584977707, 6113.296970848321, 5819.592628515963, 5851.824445266649, 5892.646441213541, 5893.0, 5901.645901245939, 5991.610545314541, 5998.25021872266, 6020.548458865515, 6032.125341825411, 6032.3967603239325, 5715.628484148517, 5759.049756790167, 5787.189793428924, 5935.258092738408, 5943.762249510058, 5830.47525722687, 5835.620684655484, 5881.088431293157, 5885.61743486672, 5911.142884281841, 4518.502964673949, 4522.208613492626, 4524.0, 4527.320901864747, 4542.386777785023, 3998.520177578709, 4057.350823868167, 4061.5735347788645, 4081.8163182656854, 4102.3026085565525, 5783.59514833966, 5792.710364481759, 5795.536357091422, 5813.709314534256, 5818.359980402152, 5711.7144142792695, 6054.061620448838, 6072.423119803611, 6086.421789929949, 6110.7861224856915, 5580.888382232414, 5586.385497595261, 5592.3009623797025, 5659.801906933238, 5684.062129748545, 5112.233165025202, 5116.334876466036, 5118.436971933084, 5120.590352771769, 5121.103806833789, 6036.969815150984, 6114.48026917717, 6179.227596550431, 6188.81433556994, 6189.8452538686, 5892.381299963442, 5989.2812862456785, 5989.610675306091, 5998.400159983967, 6026.096085587022, 6167.722452489738, 6175.752969881154, 6179.196704428396, 6191.783287584913, 6195.256569211723, 5702.315722113373, 5786.450287222707, 5792.884142317214, 5870.853228669317, 5872.236609240771, 5984.461398474158, 5984.910226346696, 5986.790462333797, 5996.910046349221, 6019.367966363468, 5768.798092066925, 5834.095715164156, 5835.7373918174635, 5836.037053886062, 5836.445537673914, 5565.248691426767, 5725.541956643542, 5778.335491418541, 5790.884182316414, 5825.475707186373, 5778.653280802997, 5788.82633520995, 5790.739416726342, 5794.652320860761, 5797.188393624811, 5875.911861321998, 5879.588428809942, 5908.143319218775, 5919.792807251725, 5923.08192230205, 5535.5848311376, 5537.390887002346, 5644.407337229532, 5690.658560486384, 5711.314642242957], "value": 5493.881181091212, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.9514438844893, 651.8142329436108, 652.26620052441, 655.7606473637122, 677.9796606101819, 32727.345453090955, 46285.76857115714, 47335.0, 47381.289280660814, 47428.76285618576, 49300.0, 49481.50518494814, 49505.0, 49726.50273497264, 49798.75100624501, 8202.343812495015, 9031.548422578877, 9192.494412807326, 9195.494247816367, 9198.4480931144, 8486.448380855256, 8553.059163492011, 10659.520321585547, 10977.0, 10992.230543861893, 10836.187285953545, 10983.0, 10985.780284394308, 10996.725081872977, 10996.725081872977, 8462.534560599164, 11041.503132359023, 11097.889021109766, 11117.833232501518, 11120.61077862271, 10476.26666133371, 10751.139908807354, 10855.40295283767, 11247.887521124765, 11250.718732031683, 44636.0, 44997.32504012442, 45060.324095138596, 45174.548254517365, 45222.32166517472, 3741.30785804627, 3771.4719939208535, 3815.4849095372047, 3838.9808050959846, 4145.8963525912095, 33644.6635533644, 35226.0, 35304.29391412183, 35391.29217415637, 35473.29053418943, 4726.787294571718, 4765.499622539618, 4785.569298763093, 4794.856154315341, 4911.729854857981, 356158.0, 358082.209588953, 358245.0, 358444.0, 358703.6194457085, 64680.35319646837, 65176.0, 65322.673386633716, 65365.0, 65369.673151633935, 34888.65111348854, 35358.823205883804, 35455.82272088623, 35513.0, 70153.64923175352, 16137.435189768301, 16231.918840405722, 16453.177341132894, 16601.169941502878, 16631.6673666526, 11823.113266505046, 13023.47906083746, 13624.318784060757, 13698.863011369758, 13735.381907814173, 20324.593508129754, 21019.8949005254, 21198.682019769716, 21232.681509777365, 21255.78744212538, 7257.92742072583, 7274.0, 7444.553326800409, 7547.433942454338, 7595.886061709079, 967.2310513142069, 1074.5862842805577, 1117.9217454778088, 1136.9488373023235, 1198.784218840617, 34292.48561271583, 36178.09554761152, 36248.09379765527, 36329.27341453156, 36508.634913651054, 1103.7682086761708, 1181.4270079011671, 1238.7089034076985, 1241.74544218435, 1259.4647274908311, 17392.565185870455, 17548.824511754974, 17655.38206162778, 17724.91137544304, 17800.655776916265, 584713.0, 587394.0, 591131.0, 591537.0, 594312.0, 62893.0, 62907.37092629106, 63074.68462657657, 63088.684556576925, 63186.36813631805, 1363.5704753002972, 1378.4692893236177, 1421.5620211337605, 1480.1119328403088, 1488.6129606302438, 31222.843885780425, 34582.654173457944, 34882.825585871906, 35161.58488168904, 35217.647823522464, 55546.72226638841, 56166.71916640554, 56173.0, 56279.43720562742, 56285.0, 476512.0, 479743.0, 481975.0, 483020.0, 483440.0, 26757.86621066882, 29768.255793605334, 30776.846115770175, 30919.69080309258, 31009.68990310068, 51780.741096294274, 52294.215586765466, 52828.0, 54046.459535404145, 57672.423275766705, 45318.32022519599, 45699.54300456953, 45865.0, 45956.31065533953, 46686.53313466822, 44605.0, 44729.0, 44736.0, 45039.774801125786, 45934.77032614816, 43811.0, 44732.0, 45193.0, 45620.0, 47041.294380585, 38693.22613547769, 39431.408528871514, 39854.202915940936, 40354.79822600868, 40418.59581404148, 29703.702962970092, 30063.398732025675, 30314.84842575773, 30378.58263938186, 30500.389992200475], "value": 21630.29737355518, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.6878504397819, 433.5014733056986, 434.8065111025594, 434.99130017399654, 448.6567775651625, 18442.170102345393, 19034.90482547587, 20289.898550507245, 20357.389278321636, 20410.489737756565, 16367.754483682755, 18757.15592798324, 18919.337823176193, 19891.801081989175, 19916.60166796663, 5542.168674698787, 5727.885442291152, 5732.426757324276, 5761.855953601173, 5765.6540607563475, 5260.395054568736, 5584.162375643646, 5763.9711801440935, 5773.30720313563, 5783.161441590967, 5269.552088072518, 5275.971185618806, 5715.485606295453, 5777.046787280115, 5845.444682755132, 5753.683547404891, 5782.161586569966, 5785.0, 5804.883902321951, 5861.531077513789, 4882.316475693406, 4953.257011448276, 5004.124278251328, 5042.294078828967, 5049.368828896388, 22876.885615571817, 22918.31245062651, 23909.282721518208, 23919.760802391927, 24052.27843164708, 2502.311864237331, 2506.5613517634342, 2527.4186937004406, 2538.2512158913164, 2627.2906315294763, 14543.563693089225, 15050.623734406618, 15060.472883449136, 15162.696746065018, 15376.077435353915, 2004.7193392925003, 2086.155107181594, 2094.1309356616985, 2120.5016821047043, 2156.0513374115494, 77112.80087472714, 77768.61115694385, 78451.60774196149, 78949.0, 79014.0, 10733.141348692243, 10856.78286434267, 10914.617988370528, 10917.617883374043, 11168.385738784445, 16148.27332770028, 16218.270177842025, 16241.0, 16264.105474198795, 16264.674706505804, 8314.127016663284, 8458.534780587126, 8539.402241843012, 8655.610497527627, 8754.0, 8326.583670816435, 8330.083690794008, 8420.284275836622, 8427.536485493234, 8434.746957591282, 6642.335766423319, 6873.20958089816, 6878.828029299208, 6920.5847649141215, 6962.582245065312, 1937.350987419202, 1937.8062193780509, 1950.6683863742915, 1995.8004199579925, 1999.720039194514, 774.8140446292962, 878.6309749905055, 996.8554559588819, 1013.2755080117752, 1144.3991904250336, 19846.0, 19993.200271988957, 20106.0, 20168.596628067357, 20566.97165141737, 1053.378506681062, 1088.7495875948575, 1108.0802933565121, 1110.1784679337393, 1161.3032180691519, 11262.267952583055, 11377.943110284396, 11564.363959982282, 11564.76870462586, 11669.88330116688, 493019.0, 498067.0, 498611.0, 502826.0, 513535.0, 49756.50243497591, 52227.0, 53497.46502534925, 78983.60508197422, 82147.0, 1003.2325271167563, 1119.9104071674246, 1129.8079326514512, 1144.1876267849752, 1164.394514852272, 17957.191926363084, 18339.0, 18390.81609183891, 18473.81526184721, 18872.433826985212, 39116.6088339113, 39268.0, 39864.800675996434, 39940.60059399369, 41185.79407102945, 50895.2365714522, 51554.0, 52414.737926310125, 52433.47566524286, 53419.7329013368, 7615.771526854203, 8074.798130046561, 8655.653773848975, 8818.162274583905, 9014.729558113268, 13009.739805203653, 13024.153430027018, 13296.734065318446, 13356.599302020955, 13399.799003015158, 11525.769484610428, 11774.941125294661, 11825.822612660644, 11882.227655202385, 11891.762164756483, 5704.857378565403, 10136.695899123039, 11386.032187264176, 11441.599544015919, 11538.211577205546, 11320.490577924178, 11383.829242561535, 11466.541338346706, 11714.0, 11821.82267266009, 7560.810979725551, 7832.138464768757, 8054.758357249292, 8222.83554328922, 8323.583820809057, 8991.955040224757, 10873.619423320146, 11983.400829958642, 12158.270503769802, 12381.876181238433], "value": 9371.258210417585, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.424745235822, 1549.9922500387497, 1550.0854495847452, 1740.4517576963256, 1848.1775609853603, 9179.714839922408, 9605.2796040297, 9662.903370966296, 9991.0, 10011.399316041043, 9706.0, 9907.356021858574, 9911.702648920525, 9916.50417479125, 9918.3057185997, 2172.0, 2429.6234083716963, 2503.0238207099246, 2525.583278759007, 2555.4889022195543, 2482.9255122346267, 2516.4338023944647, 2525.7095434025046, 2528.532221539012, 2530.202986059395, 2463.7043554773454, 2493.4389762303517, 2503.962440563393, 2505.6241563765398, 2505.887235074426, 2120.67129594913, 2424.3696638873844, 2506.786923111538, 2511.3344963584664, 2526.5831137862233, 2440.5973014452593, 2463.5935070713313, 2473.703155621337, 2485.2171565956846, 2490.9252722418355, 9897.35667181638, 9919.107280344804, 9953.402795832273, 9959.103680668775, 9975.152112070411, 5464.26232458617, 5577.442255774431, 5585.58108141891, 5588.6646801191655, 5592.189132575753, 3440.501127336522, 3466.0, 3564.465330200465, 3602.8198590070656, 3617.5116359291424, 1122.4331712485225, 1127.3743072594707, 1146.8050431426677, 1158.7856246594322, 1189.1022278180005, 13629.25039122848, 13635.38640761178, 13656.24890631015, 13664.726705465835, 13666.428360738542, 4916.508349165055, 4957.157283261854, 4995.850124496271, 5004.274380214849, 5069.619778516626, 11190.832137517944, 11253.324800511995, 11258.268212566158, 11258.605948791754, 11268.605598804003, 4832.057748739033, 5661.518770904437, 5682.602217844711, 5685.573581981368, 5689.231953686282, 4804.975975120172, 4961.355023846877, 4964.429090654548, 5008.5993120550265, 5014.648974571745, 4266.679999000087, 4325.3728209409455, 4328.588784065509, 4341.544137865479, 4382.342648602735, 1271.1864406779669, 1276.3554405025588, 1282.1858120093807, 1321.2468892731051, 1328.362386054699, 936.3352020065694, 943.1464524605243, 1000.3347773730533, 1043.4417586591137, 1097.3909480238544, 6751.2135261780195, 6752.628605426748, 6753.729850806011, 6818.147731533559, 6902.171739391205, 971.3928794503436, 1006.582268358623, 1013.3008224325192, 1037.7768779712246, 1038.4496217004998, 4445.866624001285, 4452.154090722753, 4533.705309154894, 4535.092981403666, 4578.107269082563, 12461.314627695383, 12780.61658150257, 12832.679183020498, 12845.678858028437, 12864.614061578168, 11786.292822430682, 12386.071044671686, 12398.194117382342, 12622.305773182563, 12749.235045897276, 8805.16350946659, 9108.362414631045, 9127.634894604274, 9161.083891610786, 9175.220106290906, 6817.829554261183, 6841.931580684329, 6855.245922948571, 7000.859982800213, 7082.752103676346, 11447.141464390037, 11562.537498500302, 11621.5351385947, 11824.82262766042, 11832.053435724949, 10974.615888443866, 11382.658520244406, 11396.829047564459, 11874.346910919983, 11875.0, 7715.3441957432005, 8337.333013358795, 8346.916530834615, 8553.572321383781, 8571.100034496289, 9726.610935562498, 9740.805183896424, 9767.609295628095, 9809.607615695293, 9810.117089461983, 8686.782830429038, 8803.251723603566, 8819.691310804348, 8899.866502002345, 8919.063498332582, 8343.749687509384, 8551.358648101294, 8561.529115898686, 8587.613557390057, 8813.779655508664, 7769.844603108019, 7883.802904927423, 7886.881696774438, 7903.644336004779, 7998.240167184108, 5533.418991006048, 5547.472990065938, 5611.270534830446, 5667.178259152317, 5672.14917762339, 5761.452661997104, 5814.592978491465, 5893.911591326219, 5985.25184351956, 6081.726322315418], "value": 5073.603895060862, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.1783193235389, 364.36600315451125, 365.69647192829956, 366.11948264424063, 370.248395756614, 5335.946640533593, 5395.244665746789, 5441.863953401167, 5446.400895901453, 5523.751431185597, 5445.413428621403, 5447.1829225616075, 5463.726813659311, 5469.234307196985, 5478.45215478452, 4134.152498737752, 4156.376543518466, 4327.372530983005, 4331.436913201287, 4371.475422949251, 4332.3068309070395, 4354.934675979863, 4358.389825424443, 4363.476382834048, 4368.58498442649, 4321.416608757824, 4333.349997500369, 4335.718178318415, 4350.260455722535, 4362.890927726817, 4261.232978063932, 4300.741955482681, 4334.869953901388, 4336.176126535964, 4339.47926248849, 3837.0982819037504, 3856.9421458678144, 3865.8260378283044, 3867.9806600967095, 3868.3810590305698, 5297.894042119175, 5332.027149954502, 5337.653052551611, 5354.170103633904, 5367.731613419313, 4697.647676424248, 4916.53292937174, 4925.438771838624, 4929.605631549467, 4975.7760900759195, 5664.745086471119, 5728.140778883201, 5755.971220143872, 5763.193152958589, 5774.97112514439, 4097.651699605537, 4190.371444283352, 4201.642860356873, 4209.958170287408, 4244.151169766028, 5843.6493810371085, 5938.8218353449465, 5974.940250597482, 5982.401759824026, 5996.999999999956, 4218.8523401680795, 4227.408162857202, 4242.257604919127, 4256.808443620044, 4267.591694740728, 4315.0, 4318.827246910088, 4320.925955541357, 4359.738415695005, 4379.693421460467, 4651.953480465219, 4718.882027949328, 4794.2089555223, 4918.114739346952, 4932.0, 5736.368999410061, 5748.051571490741, 5754.877798829188, 5773.855653608609, 5773.971130144322, 1482.947107553649, 1492.7611582146803, 1494.006485687006, 1496.5884381794876, 1503.1056521369985, 909.554318383987, 956.3985958193701, 970.3304719743354, 971.4851128901694, 973.648638537923, 4993.675411098339, 5040.513048650657, 5054.797808087709, 5097.337346144977, 5108.77389426535, 5107.310513080761, 5224.059669259494, 5242.135047717084, 5263.7894484220205, 5264.552513036435, 5030.522100400456, 5102.336696229467, 5118.846434606968, 5310.548603368679, 5838.1242813577455, 3164.5095010273444, 3183.0, 3185.761067919915, 3185.761067919915, 3209.40625984189, 6074.271087469444, 6185.257769067741, 6196.256449226032, 6207.720652570645, 6244.313125556185, 5993.400659933972, 5999.700014999233, 6004.639721616717, 6009.909851352233, 6031.849203769853, 6167.660778657217, 6169.290531588835, 6191.938080619227, 6283.654399008098, 6288.717007734572, 5818.883622327445, 5899.793507227227, 5937.287525496795, 5958.374370691015, 5981.671008094597, 5811.82564523065, 5819.592628515963, 5851.882962340815, 5902.84894445579, 5914.497267732291, 5962.970185149046, 6134.846628834315, 6197.876042479215, 6201.224846894138, 6216.595921265103, 4222.19778242133, 4249.747633754116, 4278.294081476522, 4279.743215407086, 4298.505671847777, 5704.375987046005, 5814.30228372598, 5984.0, 5984.850378740566, 5990.760369585168, 5709.971450142889, 5719.656820590778, 5725.826205627753, 5760.769569217352, 5771.624844385102, 5769.192313076257, 5771.509421699203, 5778.595498315078, 5811.244538210006, 5858.560607954506, 5575.968445837533, 5693.28833895763, 5737.770489180386, 5756.856078598069, 5761.971190144022, 5780.421957804186, 5828.883422331445, 5834.474897259267, 5858.033424484912, 5868.735906884115, 5489.737360407049, 5690.630109042899, 5708.457696518824, 5720.742566584597, 5758.222639943554], "value": 4391.95640366828, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.9142198647154, 811.1726039439768, 850.9361797865159, 851.808343122797, 879.3580686099148, 14920.552383428487, 15022.699546009073, 15031.398744050239, 15143.545693629196, 16200.756988645166, 15206.391744330214, 15355.155466449369, 15355.539333819976, 15369.69260614787, 15388.38446462143, 713.6788445199655, 826.3513142183388, 855.4396870050127, 855.8373908957309, 859.3597769661579, 848.7750746052301, 853.0744142605281, 855.5551113421018, 855.687673998989, 856.1395797223781, 904.5658084119626, 906.207068814787, 906.650939388337, 906.9863952040724, 907.0294784580501, 743.947923645345, 865.8051938313891, 891.2558014058269, 891.79488717595, 896.1173244354305, 660.4056349285634, 661.6724721263006, 661.9371159739865, 662.2020465339302, 662.552776875607, 15187.924060379737, 15217.391304347813, 15245.923770381189, 15278.31247593861, 15287.770683439758, 15052.397904083824, 15107.54677359681, 15140.697186056328, 15313.923430382776, 15399.615009624737, 12930.935345323212, 13441.39513721885, 13475.86524134756, 13503.25732084735, 13635.590932271949, 4674.439067311911, 4697.976510117462, 4707.8587642370785, 4752.382190315271, 4858.368412106439, 74584.62707686427, 78399.0, 78603.21396786017, 79068.60465697634, 79254.20745792527, 11255.493502792231, 11287.774244515227, 11414.143939204427, 11438.370889601152, 11558.595449159238, 21020.579588408145, 21182.15271389158, 21188.364349069554, 21308.467288317916, 21772.0, 9249.398789078688, 9261.259099272042, 9287.303452241109, 9312.161905428544, 9417.90582094184, 5137.794488220504, 5168.483151684802, 5170.638055336166, 5172.793088276427, 5243.973780131075, 3817.3701339279264, 3957.2085582883537, 3958.267720471722, 3960.584138665456, 3964.5044369453817, 650.7364517370472, 665.2449469851753, 674.4233680203454, 680.7072958627739, 742.7920182348951, 756.0700338583573, 781.6784840773751, 794.1145622630725, 811.679386642273, 833.3832963607008, 10585.629502967513, 10871.891281087088, 10957.726056848483, 11000.504977276041, 11140.220184587162, 797.8723404255292, 810.9959450202712, 817.8078151634361, 832.9458585191943, 836.8828364029041, 5032.949670503322, 5249.658772179872, 5296.09966305729, 5355.116405793001, 5359.678419294854, 76008.61995690096, 100350.0, 123041.0, 124788.0, 129292.0, 103157.0, 108160.0, 109232.4538377319, 109972.4501377488, 111975.0, 92003.0, 93247.53376233076, 94514.0, 94622.0, 94819.52590237005, 10155.796884062425, 10185.287029907835, 10186.236032297458, 10224.642137525449, 10261.486925653538, 54831.0, 55111.72444137753, 55562.72218639043, 56428.43571564232, 57306.14040789308, 79406.6029669871, 79655.0, 79960.0, 80973.59513202397, 82883.58558207411, 12589.811152832532, 12738.363081845686, 12742.74514509686, 12902.483900643869, 13034.739305214032, 14383.856161438252, 14805.18571478579, 14987.700245995236, 15132.243387830344, 15283.61790955235, 12950.287734174712, 12954.870451295366, 12995.740085198433, 13046.41291141882, 13354.532591359255, 13680.31598420095, 13821.792673110112, 13971.860281397056, 14467.348969296618, 14784.852151478348, 14611.780823287872, 14622.780658289921, 14752.926235368754, 14865.331060102106, 14878.181700006604, 11656.125790565571, 11676.70808229801, 11709.297442153498, 11910.583129590423, 12350.814737779121, 11897.167198296036, 12160.878391216329, 12167.695807604881, 12491.375431228584, 12633.87366126327], "value": 8030.641164194617, "unit": "score"}], "name": "dromaeo_css", "value": 7642.7781933988235, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 102.0, 109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 108.0, 109.0], "value": 108.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 127.0, 126.0, 171.0, 137.0, 132.0, 150.0, 124.0, 151.0, 124.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 162.0, 162.0, 161.0, 161.0, 160.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 121.0, 75.0, 76.0, 76.0, 76.0, 99.0, 76.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 108.0, 85.0, 86.0, 85.0, 129.0, 85.0, 86.0, 129.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 147.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 53.0, 53.0, 53.0, 55.0, 54.0, 52.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 59.0, 60.0, 60.0, 60.0, 60.0, 61.0, 59.0, 65.0, 61.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 76.0, 76.0, 77.0, 90.0, 76.0, 100.0, 77.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 132.0, 117.0, 117.0, 123.0, 115.0, 116.0, 129.0, 132.0, 129.0], "value": 122.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 153.0, 154.0, 154.0, 158.0, 158.0, 151.0, 159.0, 154.0, 150.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 63.0, 62.0, 62.0, 62.0, 62.0, 64.0, 61.0, 60.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1479.3, "alertThreshold": 2.0}]} 15:39:52 INFO - Return code: 0 15:39:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:39:52 ERROR - # TBPL SUCCESS # 15:39:52 INFO - Running post-action listener: _resource_record_post_action 15:39:52 INFO - ##### 15:39:52 INFO - ##### Finished run-tests step (success) 15:39:52 INFO - ##### 15:39:52 INFO - Running post-run listener: _resource_record_post_run 15:39:52 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 507035648; Read time: 24; Write time: 540872 15:39:52 INFO - TinderboxPrint: CPU usage
13.1% 15:39:52 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 15:39:52 INFO - TinderboxPrint: I/O write bytes / time
507,035,648 / 540,872 15:39:52 INFO - TinderboxPrint: CPU idle
8,260.2 (86.9%) 15:39:52 INFO - TinderboxPrint: CPU user
1,197.7 (12.6%) 15:39:52 INFO - TinderboxPrint: Swap in / out
0 / 0 15:39:52 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252608512; Read time: 0; Write time: 337004 15:39:52 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 266240; Write bytes: 250662912; Read time: 24; Write time: 203428 15:39:52 INFO - Running post-run listener: _upload_blobber_files 15:39:52 INFO - Blob upload gear active. 15:39:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:39:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:39:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:39:53 INFO - (blobuploader) - INFO - Open directory for files ... 15:39:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:39:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:39:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:54 INFO - (blobuploader) - INFO - Done attempting. 15:39:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:39:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:39:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:57 INFO - (blobuploader) - INFO - Done attempting. 15:39:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:39:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:39:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:58 INFO - (blobuploader) - INFO - Done attempting. 15:39:58 INFO - (blobuploader) - INFO - Iteration through files over. 15:39:58 INFO - Return code: 0 15:39:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:39:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:39:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f63823f499565e53eb7eec4a76a62ce14d344babf1d95132f3f6c5441f07ddb1e401386fca6e1bce5f09a78073dca993d4a5031bccc262a53da12086744bafd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1033be913609fd666b20825f67d50ef7d299a39c9eb1afe8ec3342ba291cebbb5c4181edd8898abbb7d274aff382fd3f989896af4b71ed7430b03c145c5633ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5867fdf51b55a3e741e9f00dcbf93987a036c3d76a3872eaaa87396c5f977f96edf508a62f314e4e0b18000e05c4f45f72ec56c7aa06bdfe2e63f063eeb59fa6"} 15:39:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:39:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:39:58 INFO - Contents: 15:39:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f63823f499565e53eb7eec4a76a62ce14d344babf1d95132f3f6c5441f07ddb1e401386fca6e1bce5f09a78073dca993d4a5031bccc262a53da12086744bafd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1033be913609fd666b20825f67d50ef7d299a39c9eb1afe8ec3342ba291cebbb5c4181edd8898abbb7d274aff382fd3f989896af4b71ed7430b03c145c5633ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5867fdf51b55a3e741e9f00dcbf93987a036c3d76a3872eaaa87396c5f977f96edf508a62f314e4e0b18000e05c4f45f72ec56c7aa06bdfe2e63f063eeb59fa6"} 15:39:58 INFO - Running post-run listener: copy_logs_to_upload_dir 15:39:58 INFO - Copying logs to upload dir... 15:39:58 INFO - mkdir: /builds/slave/test/build/upload/logs 15:39:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.976702 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-06-30 15:39:57.233712) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 15:39:57.237057) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f63823f499565e53eb7eec4a76a62ce14d344babf1d95132f3f6c5441f07ddb1e401386fca6e1bce5f09a78073dca993d4a5031bccc262a53da12086744bafd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1033be913609fd666b20825f67d50ef7d299a39c9eb1afe8ec3342ba291cebbb5c4181edd8898abbb7d274aff382fd3f989896af4b71ed7430b03c145c5633ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5867fdf51b55a3e741e9f00dcbf93987a036c3d76a3872eaaa87396c5f977f96edf508a62f314e4e0b18000e05c4f45f72ec56c7aa06bdfe2e63f063eeb59fa6"} build_url:https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011302 build_url: 'https://queue.taskcluster.net/v1/task/fiQ77NOuTEqyrs0vWJYCsg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f63823f499565e53eb7eec4a76a62ce14d344babf1d95132f3f6c5441f07ddb1e401386fca6e1bce5f09a78073dca993d4a5031bccc262a53da12086744bafd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1033be913609fd666b20825f67d50ef7d299a39c9eb1afe8ec3342ba291cebbb5c4181edd8898abbb7d274aff382fd3f989896af4b71ed7430b03c145c5633ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5867fdf51b55a3e741e9f00dcbf93987a036c3d76a3872eaaa87396c5f977f96edf508a62f314e4e0b18000e05c4f45f72ec56c7aa06bdfe2e63f063eeb59fa6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 15:39:57.262911) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 15:39:57.263255) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=a12cd81e0ef515edd9e0d800000001bb-1467325163.495405-159408868 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004421 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 15:39:57.345794) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 15:39:57.346125) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 15:39:57.346462) ========= ========= Total master_lag: 0.15 =========