builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1465506087.32 results: success (0) buildid: 20160609095531 builduid: 0f9345d66527428d978b57a8a271bbda revision: a11eb3fab3c603e2b13f8185db8e1815b44d8692 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:27.319499) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:27.320252) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:27.320745) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:27.342417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:27.342767) ========= 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=8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-09 14:01:27-- 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% 223M=0s 2016-06-09 14:01:27 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137280 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:27.497227) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:27.497664) ========= 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=8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016989 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:27.552878) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:27.553486) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a11eb3fab3c603e2b13f8185db8e1815b44d8692 --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 a11eb3fab3c603e2b13f8185db8e1815b44d8692 --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=8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-09 14:01:27,736 truncating revision to first 12 chars 2016-06-09 14:01:27,736 Setting DEBUG logging. 2016-06-09 14:01:27,736 attempt 1/10 2016-06-09 14:01:27,736 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a11eb3fab3c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-09 14:01:28,056 unpacking tar archive at: mozilla-aurora-a11eb3fab3c6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662329 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:28.230585) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:28.230948) ========= script_repo_revision: a11eb3fab3c603e2b13f8185db8e1815b44d8692 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:28.231359) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:28.231642) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 14:01:28.242513) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-06-09 14:01:28.242921) ========= /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=8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:01:28 INFO - MultiFileLogger online at 20160609 14:01:28 in /builds/slave/test 14:01:28 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 14:01:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:01:28 INFO - {'append_to_log': False, 14:01:28 INFO - 'base_work_dir': '/builds/slave/test', 14:01:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:01:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:01:28 INFO - 'branch': 'Mozilla-Aurora', 14:01:28 INFO - 'buildbot_json_path': 'buildprops.json', 14:01:28 INFO - 'config_files': ('talos/linux_config.py',), 14:01:28 INFO - 'default_actions': ('clobber', 14:01:28 INFO - 'read-buildbot-config', 14:01:28 INFO - 'download-and-extract', 14:01:28 INFO - 'populate-webroot', 14:01:28 INFO - 'create-virtualenv', 14:01:28 INFO - 'install', 14:01:28 INFO - 'run-tests'), 14:01:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:01:28 INFO - 'download_minidump_stackwalk': True, 14:01:28 INFO - 'download_symbols': 'ondemand', 14:01:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:01:28 INFO - 'tooltool.py': '/tools/tooltool.py', 14:01:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:01:28 INFO - '/tools/misc-python/virtualenv.py')}, 14:01:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:01:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:01:28 INFO - 'installer_path': 'installer.exe', 14:01:28 INFO - 'log_level': 'info', 14:01:28 INFO - 'log_name': 'talos', 14:01:28 INFO - 'log_to_console': True, 14:01:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:01:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:01:28 INFO - 'opt_config_files': (), 14:01:28 INFO - 'pip_index': False, 14:01:28 INFO - 'sps_profile': False, 14:01:28 INFO - 'sps_profile_interval': 0, 14:01:28 INFO - 'suite': 'dromaeojs', 14:01:28 INFO - 'system_bits': '32', 14:01:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:01:28 INFO - 'title': 'talos-linux64-ix-001', 14:01:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:01:28 INFO - 'use_talos_json': True, 14:01:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:01:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:01:28 INFO - 'work_dir': 'build'} 14:01:28 INFO - ##### 14:01:28 INFO - ##### Running clobber step. 14:01:28 INFO - ##### 14:01:28 INFO - Running pre-action listener: _resource_record_pre_action 14:01:28 INFO - Running main action method: clobber 14:01:28 INFO - rmtree: /builds/slave/test/build 14:01:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:01:28 INFO - Running post-action listener: _resource_record_post_action 14:01:28 INFO - ##### 14:01:28 INFO - ##### Finished clobber step (success) 14:01:28 INFO - ##### 14:01:28 INFO - ##### 14:01:28 INFO - ##### Running read-buildbot-config step. 14:01:28 INFO - ##### 14:01:28 INFO - Running pre-action listener: _resource_record_pre_action 14:01:28 INFO - Running main action method: read_buildbot_config 14:01:28 INFO - Using buildbot properties: 14:01:28 INFO - { 14:01:28 INFO - "project": "", 14:01:28 INFO - "product": "firefox", 14:01:28 INFO - "script_repo_revision": "production", 14:01:28 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:01:28 INFO - "repository": "", 14:01:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:01:28 INFO - "buildid": "20160609095531", 14:01:28 INFO - "pgo_build": "True", 14:01:28 INFO - "basedir": "/builds/slave/test", 14:01:28 INFO - "buildnumber": 563, 14:01:28 INFO - "slavename": "talos-linux64-ix-001", 14:01:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:01:28 INFO - "platform": "ubuntu64_hw", 14:01:28 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:01:28 INFO - "branch": "mozilla-aurora", 14:01:28 INFO - "repo_path": "releases/mozilla-aurora", 14:01:28 INFO - "revision": "a11eb3fab3c603e2b13f8185db8e1815b44d8692", 14:01:28 INFO - "stage_platform": "linux64-pgo", 14:01:28 INFO - "builduid": "0f9345d66527428d978b57a8a271bbda", 14:01:28 INFO - "slavebuilddir": "test" 14:01:28 INFO - } 14:01:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 14:01:28 INFO - Running post-action listener: _resource_record_post_action 14:01:28 INFO - ##### 14:01:28 INFO - ##### Finished read-buildbot-config step (success) 14:01:28 INFO - ##### 14:01:28 INFO - ##### 14:01:28 INFO - ##### Running download-and-extract step. 14:01:28 INFO - ##### 14:01:28 INFO - Running pre-action listener: _resource_record_pre_action 14:01:28 INFO - Running main action method: download_and_extract 14:01:28 INFO - mkdir: /builds/slave/test/build/tests 14:01:28 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')]} 14:01:28 INFO - https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:01:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 14:01:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 14:01:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:29 INFO - Downloaded 1361 bytes. 14:01:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 14:01:29 INFO - Using the following test package requirements: 14:01:29 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 14:01:29 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:01:29 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:01:29 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:01:29 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:01:29 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:01:29 INFO - u'jsshell-linux-x86_64.zip'], 14:01:29 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:01:29 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:01:29 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 14:01:29 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:01:29 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:01:29 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:01:29 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:01:29 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:01:29 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:01:29 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 14:01:29 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:01:29 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:01: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')]} 14:01:29 INFO - https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:01:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 14:01:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 14:01:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:30 INFO - Downloaded 24473748 bytes. 14:01: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'] 14:01: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 14:01:31 INFO - Return code: 0 14:01:31 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 14:01:31 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')]} 14:01:31 INFO - https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:01:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 14:01:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 14:01:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:33 INFO - Downloaded 24473748 bytes. 14:01:33 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'] 14:01:33 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 14:01:34 INFO - Return code: 0 14:01:34 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')]} 14:01:34 INFO - https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:01:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 14:01:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 14:01:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:35 INFO - Downloaded 10833369 bytes. 14:01:35 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'] 14:01:35 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 14:01:36 INFO - Return code: 0 14:01:36 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')]} 14:01:36 INFO - https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:01:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 14:01:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 14:01:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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 14:01:38 INFO - Downloaded 63559230 bytes. 14:01:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 14:01:38 INFO - mkdir: /builds/slave/test/properties 14:01:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:01:38 INFO - Writing to file /builds/slave/test/properties/build_url 14:01:38 INFO - Contents: 14:01:38 INFO - build_url:https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 14:01:38 INFO - Running post-action listener: _resource_record_post_action 14:01:38 INFO - Running post-action listener: set_extra_try_arguments 14:01:38 INFO - ##### 14:01:38 INFO - ##### Finished download-and-extract step (success) 14:01:38 INFO - ##### 14:01:38 INFO - ##### 14:01:38 INFO - ##### Running populate-webroot step. 14:01:38 INFO - ##### 14:01:38 INFO - Running pre-action listener: _resource_record_pre_action 14:01:38 INFO - Running main action method: populate_webroot 14:01:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:01:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:01:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:01:38 INFO - '--tppagecycles', 14:01:38 INFO - '7'], 14:01:38 INFO - 'tests': ['tsvgm']}}, 14:01:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:01:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:01:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:01:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:01:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:38 INFO - 'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:01:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:01:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:38 INFO - 'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['damp', 'tps']}, 14:01:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:38 INFO - 'tests': ['damp', 'tps']}, 14:01:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['dromaeo_dom']}, 14:01:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:01:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['a11yr', 14:01:38 INFO - 'ts_paint', 14:01:38 INFO - 'tpaint', 14:01:38 INFO - 'sessionrestore', 14:01:38 INFO - 'sessionrestore_no_auto_restore', 14:01:38 INFO - 'tabpaint']}, 14:01:38 INFO - 'other-e10s': {'tests': ['a11yr', 14:01:38 INFO - 'ts_paint', 14:01:38 INFO - 'tpaint', 14:01:38 INFO - 'sessionrestore', 14:01:38 INFO - 'sessionrestore_no_auto_restore', 14:01:38 INFO - 'tabpaint']}, 14:01:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:01:38 INFO - 'ts_paint', 14:01:38 INFO - 'tpaint', 14:01:38 INFO - 'sessionrestore', 14:01:38 INFO - 'sessionrestore_no_auto_restore', 14:01:38 INFO - 'tabpaint']}, 14:01:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:01:38 INFO - 'ts_paint', 14:01:38 INFO - 'tpaint', 14:01:38 INFO - 'sessionrestore', 14:01:38 INFO - 'sessionrestore_no_auto_restore', 14:01:38 INFO - 'tabpaint']}, 14:01:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['a11yr', 14:01:38 INFO - 'ts_paint', 14:01:38 INFO - 'tpaint', 14:01:38 INFO - 'sessionrestore', 14:01:38 INFO - 'sessionrestore_no_auto_restore', 14:01:38 INFO - 'tabpaint']}, 14:01:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['a11yr', 14:01:38 INFO - 'ts_paint', 14:01:38 INFO - 'tpaint', 14:01:38 INFO - 'sessionrestore', 14:01:38 INFO - 'sessionrestore_no_auto_restore', 14:01:38 INFO - 'tabpaint']}, 14:01:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['tsvgx', 14:01:38 INFO - 'tsvgr_opacity', 14:01:38 INFO - 'tart', 14:01:38 INFO - 'tscrollx', 14:01:38 INFO - 'cart']}, 14:01:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:01:38 INFO - 'tsvgr_opacity', 14:01:38 INFO - 'tart', 14:01:38 INFO - 'tscrollx', 14:01:38 INFO - 'cart']}, 14:01:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:38 INFO - 'talos_options': ['--disable-e10s'], 14:01:38 INFO - 'tests': ['tp5o']}, 14:01:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:38 INFO - 'tests': ['tp5o']}, 14:01:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:38 INFO - 'talos_options': ['--disable-e10s', 14:01:38 INFO - '--xperf_path', 14:01:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:01:38 INFO - 'tests': ['tp5n']}, 14:01:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:38 INFO - 'talos_options': ['--xperf_path', 14:01:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:01:38 INFO - 'tests': ['tp5n']}}, 14:01:38 INFO - 'talos.zip': {'path': '', 14:01:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:01:38 INFO - Running post-action listener: _resource_record_post_action 14:01:38 INFO - ##### 14:01:38 INFO - ##### Finished populate-webroot step (success) 14:01:38 INFO - ##### 14:01:38 INFO - ##### 14:01:38 INFO - ##### Running create-virtualenv step. 14:01:38 INFO - ##### 14:01:38 INFO - Running pre-action listener: _resource_record_pre_action 14:01:38 INFO - Running main action method: create_virtualenv 14:01:38 INFO - Creating virtualenv /builds/slave/test/build/venv 14:01:38 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 14:01:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:01:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:01:39 INFO - Using real prefix '/usr' 14:01:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:01:39 INFO - Installing distribute.............................................................................................................................................................................................done. 14:01:41 INFO - Installing pip.................done. 14:01:41 INFO - Return code: 0 14:01:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:01: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')]} 14:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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')]} 14:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:01: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', 'pip>=1.5'] in /builds/slave/test/build 14:01: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 pip>=1.5 14:01:41 INFO - Using env: {'DISPLAY': ':0', 14:01:41 INFO - 'HOME': '/home/cltbld', 14:01:41 INFO - 'LANG': 'en_US.UTF-8', 14:01:41 INFO - 'LANGUAGE': 'en_US:en', 14:01:41 INFO - 'LOGNAME': 'cltbld', 14:01:41 INFO - 'MAIL': '/var/mail/cltbld', 14:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:41 INFO - 'MOZ_NO_REMOTE': '1', 14:01:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:41 INFO - 'NO_EM_RESTART': '1', 14:01:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:41 INFO - 'PWD': '/builds/slave/test', 14:01:41 INFO - 'SHELL': '/bin/bash', 14:01:41 INFO - 'SHLVL': '1', 14:01:41 INFO - 'TERM': 'linux', 14:01:41 INFO - 'TMOUT': '86400', 14:01:41 INFO - 'USER': 'cltbld', 14:01:41 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:41 INFO - '_': '/tools/buildbot/bin/python'} 14:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:41 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 14:01:41 INFO - Cleaning up... 14:01:41 INFO - Return code: 0 14:01:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:01: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')]} 14:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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')]} 14:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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', '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 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:01: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', 'psutil>=3.1.1'] in /builds/slave/test/build 14:01: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 psutil>=3.1.1 14:01:41 INFO - Using env: {'DISPLAY': ':0', 14:01:41 INFO - 'HOME': '/home/cltbld', 14:01:41 INFO - 'LANG': 'en_US.UTF-8', 14:01:41 INFO - 'LANGUAGE': 'en_US:en', 14:01:41 INFO - 'LOGNAME': 'cltbld', 14:01:41 INFO - 'MAIL': '/var/mail/cltbld', 14:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:41 INFO - 'MOZ_NO_REMOTE': '1', 14:01:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:41 INFO - 'NO_EM_RESTART': '1', 14:01:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:41 INFO - 'PWD': '/builds/slave/test', 14:01:41 INFO - 'SHELL': '/bin/bash', 14:01:41 INFO - 'SHLVL': '1', 14:01:41 INFO - 'TERM': 'linux', 14:01:41 INFO - 'TMOUT': '86400', 14:01:41 INFO - 'USER': 'cltbld', 14:01:41 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:41 INFO - '_': '/tools/buildbot/bin/python'} 14:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:41 INFO - Downloading/unpacking psutil>=3.1.1 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:01:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:43 INFO - Installing collected packages: psutil 14:01:43 INFO - Running setup.py install for psutil 14:01:43 INFO - building 'psutil._psutil_linux' extension 14:01:43 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 14:01:44 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 14:01:44 INFO - building 'psutil._psutil_posix' extension 14:01:44 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 14:01:44 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 14:01:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:44 INFO - Successfully installed psutil 14:01:44 INFO - Cleaning up... 14:01:44 INFO - Return code: 0 14:01:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:01:44 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')]} 14:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:44 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')]} 14:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:44 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 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:01:44 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 14:01:44 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 14:01:44 INFO - Using env: {'DISPLAY': ':0', 14:01:44 INFO - 'HOME': '/home/cltbld', 14:01:44 INFO - 'LANG': 'en_US.UTF-8', 14:01:44 INFO - 'LANGUAGE': 'en_US:en', 14:01:44 INFO - 'LOGNAME': 'cltbld', 14:01:44 INFO - 'MAIL': '/var/mail/cltbld', 14:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:44 INFO - 'MOZ_NO_REMOTE': '1', 14:01:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:44 INFO - 'NO_EM_RESTART': '1', 14:01:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:44 INFO - 'PWD': '/builds/slave/test', 14:01:44 INFO - 'SHELL': '/bin/bash', 14:01:44 INFO - 'SHLVL': '1', 14:01:44 INFO - 'TERM': 'linux', 14:01:44 INFO - 'TMOUT': '86400', 14:01:44 INFO - 'USER': 'cltbld', 14:01:44 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:44 INFO - '_': '/tools/buildbot/bin/python'} 14:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:01:44 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 14:01:44 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 14:01:44 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 14:01:44 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 14:01:44 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 14:01:44 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 14:01:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:01:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:01:46 INFO - Installing collected packages: mozsystemmonitor 14:01:46 INFO - Running setup.py install for mozsystemmonitor 14:01:46 INFO - Successfully installed mozsystemmonitor 14:01:46 INFO - Cleaning up... 14:01:46 INFO - Return code: 0 14:01:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:01: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')]} 14:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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')]} 14:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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', '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 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:01: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:01: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 jsonschema==2.5.1 14:01:46 INFO - Using env: {'DISPLAY': ':0', 14:01:46 INFO - 'HOME': '/home/cltbld', 14:01:46 INFO - 'LANG': 'en_US.UTF-8', 14:01:46 INFO - 'LANGUAGE': 'en_US:en', 14:01:46 INFO - 'LOGNAME': 'cltbld', 14:01:46 INFO - 'MAIL': '/var/mail/cltbld', 14:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:46 INFO - 'MOZ_NO_REMOTE': '1', 14:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:46 INFO - 'NO_EM_RESTART': '1', 14:01:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:46 INFO - 'PWD': '/builds/slave/test', 14:01:46 INFO - 'SHELL': '/bin/bash', 14:01:46 INFO - 'SHLVL': '1', 14:01:46 INFO - 'TERM': 'linux', 14:01:46 INFO - 'TMOUT': '86400', 14:01:46 INFO - 'USER': 'cltbld', 14:01:46 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:46 INFO - '_': '/tools/buildbot/bin/python'} 14:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:46 INFO - Downloading/unpacking jsonschema==2.5.1 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:01:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01:49 INFO - Downloading functools32-3.2.3-2.tar.gz 14:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:01:49 INFO - warning: no files found matching '*.txt' 14:01:49 INFO - no previously-included directories found matching 'build' 14:01:49 INFO - no previously-included directories found matching 'dist' 14:01:49 INFO - no previously-included directories found matching '.git*' 14:01:49 INFO - Installing collected packages: jsonschema, functools32 14:01:49 INFO - Running setup.py install for functools32 14:01:49 INFO - warning: no files found matching '*.txt' 14:01:49 INFO - no previously-included directories found matching 'build' 14:01:49 INFO - no previously-included directories found matching 'dist' 14:01:49 INFO - no previously-included directories found matching '.git*' 14:01:49 INFO - Successfully installed jsonschema functools32 14:01:49 INFO - Cleaning up... 14:01:49 INFO - Return code: 0 14:01:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:01: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')]} 14:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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')]} 14:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:49 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 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:01:49 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 14:01:49 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 14:01:49 INFO - Using env: {'DISPLAY': ':0', 14:01:49 INFO - 'HOME': '/home/cltbld', 14:01:49 INFO - 'LANG': 'en_US.UTF-8', 14:01:49 INFO - 'LANGUAGE': 'en_US:en', 14:01:49 INFO - 'LOGNAME': 'cltbld', 14:01:49 INFO - 'MAIL': '/var/mail/cltbld', 14:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:49 INFO - 'MOZ_NO_REMOTE': '1', 14:01:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:49 INFO - 'NO_EM_RESTART': '1', 14:01:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:49 INFO - 'PWD': '/builds/slave/test', 14:01:49 INFO - 'SHELL': '/bin/bash', 14:01:49 INFO - 'SHLVL': '1', 14:01:49 INFO - 'TERM': 'linux', 14:01:49 INFO - 'TMOUT': '86400', 14:01:49 INFO - 'USER': 'cltbld', 14:01:49 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:49 INFO - '_': '/tools/buildbot/bin/python'} 14:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:49 INFO - Downloading/unpacking blobuploader==1.2.4 14:01:49 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 14:01:49 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 14:01:49 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 14:01:49 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 14:01:49 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 14:01:49 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 14:01:51 INFO - Downloading blobuploader-1.2.4.tar.gz 14:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:01:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:01:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 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 14:01:51 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 14:01:52 INFO - Downloading docopt-0.6.1.tar.gz 14:01:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:01:52 INFO - Installing collected packages: blobuploader, requests, docopt 14:01:52 INFO - Running setup.py install for blobuploader 14:01:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:01:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:01:52 INFO - Running setup.py install for requests 14:01:52 INFO - Running setup.py install for docopt 14:01:52 INFO - Successfully installed blobuploader requests docopt 14:01:52 INFO - Cleaning up... 14:01:52 INFO - Return code: 0 14:01:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01: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')]} 14:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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')]} 14:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:52 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 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:01:52 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 14:01:52 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 14:01:52 INFO - Using env: {'DISPLAY': ':0', 14:01:52 INFO - 'HOME': '/home/cltbld', 14:01:52 INFO - 'LANG': 'en_US.UTF-8', 14:01:52 INFO - 'LANGUAGE': 'en_US:en', 14:01:52 INFO - 'LOGNAME': 'cltbld', 14:01:52 INFO - 'MAIL': '/var/mail/cltbld', 14:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:52 INFO - 'MOZ_NO_REMOTE': '1', 14:01:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:52 INFO - 'NO_EM_RESTART': '1', 14:01:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:52 INFO - 'PWD': '/builds/slave/test', 14:01:52 INFO - 'SHELL': '/bin/bash', 14:01:52 INFO - 'SHLVL': '1', 14:01:52 INFO - 'TERM': 'linux', 14:01:52 INFO - 'TMOUT': '86400', 14:01:52 INFO - 'USER': 'cltbld', 14:01:52 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:52 INFO - '_': '/tools/buildbot/bin/python'} 14:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:01:53 INFO - Running setup.py (path:/tmp/pip-XLi_bZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:01:53 INFO - Running setup.py (path:/tmp/pip-dcXeWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:01:53 INFO - Running setup.py (path:/tmp/pip-CAD3ni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:01:53 INFO - Running setup.py (path:/tmp/pip-aasyv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:01:53 INFO - Running setup.py (path:/tmp/pip-sWj_JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:01:53 INFO - Running setup.py (path:/tmp/pip-CRM_NY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:01:53 INFO - Running setup.py (path:/tmp/pip-GKBmCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:01:53 INFO - Running setup.py (path:/tmp/pip-pA28XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:01:53 INFO - Running setup.py (path:/tmp/pip-uWGNeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:01:53 INFO - Running setup.py (path:/tmp/pip-HZdN4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:01:53 INFO - Running setup.py (path:/tmp/pip-U1dnD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:01:53 INFO - Running setup.py (path:/tmp/pip-XDC7kL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:01:53 INFO - Running setup.py (path:/tmp/pip-V0bzJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:01:53 INFO - Running setup.py (path:/tmp/pip-BULGYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:54 INFO - Running setup.py (path:/tmp/pip-jAO4Fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:01:54 INFO - Running setup.py (path:/tmp/pip-eGDYHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:01:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:01:54 INFO - Running setup.py (path:/tmp/pip-e4YT_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:01:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:01:54 INFO - Running setup.py install for manifestparser 14:01:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:01:54 INFO - Running setup.py install for mozcrash 14:01:54 INFO - Running setup.py install for mozdebug 14:01:54 INFO - Running setup.py install for mozdevice 14:01:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:01:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:01:54 INFO - Running setup.py install for mozfile 14:01:54 INFO - Running setup.py install for mozhttpd 14:01:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:01:54 INFO - Running setup.py install for mozinfo 14:01:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:01:54 INFO - Running setup.py install for mozInstall 14:01:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Running setup.py install for mozleak 14:01:55 INFO - Running setup.py install for mozlog 14:01:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Running setup.py install for moznetwork 14:01:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Running setup.py install for mozprocess 14:01:55 INFO - Running setup.py install for mozprofile 14:01:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Running setup.py install for mozrunner 14:01:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:01:55 INFO - Running setup.py install for mozscreenshot 14:01:55 INFO - Running setup.py install for moztest 14:01:55 INFO - Running setup.py install for mozversion 14:01:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:01:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:01:56 INFO - Cleaning up... 14:01:56 INFO - Return code: 0 14:01:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01: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')]} 14:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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')]} 14:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:56 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 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:01:56 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 14:01:56 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 14:01:56 INFO - Using env: {'DISPLAY': ':0', 14:01:56 INFO - 'HOME': '/home/cltbld', 14:01:56 INFO - 'LANG': 'en_US.UTF-8', 14:01:56 INFO - 'LANGUAGE': 'en_US:en', 14:01:56 INFO - 'LOGNAME': 'cltbld', 14:01:56 INFO - 'MAIL': '/var/mail/cltbld', 14:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:56 INFO - 'MOZ_NO_REMOTE': '1', 14:01:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:56 INFO - 'NO_EM_RESTART': '1', 14:01:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:56 INFO - 'PWD': '/builds/slave/test', 14:01:56 INFO - 'SHELL': '/bin/bash', 14:01:56 INFO - 'SHLVL': '1', 14:01:56 INFO - 'TERM': 'linux', 14:01:56 INFO - 'TMOUT': '86400', 14:01:56 INFO - 'USER': 'cltbld', 14:01:56 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:56 INFO - '_': '/tools/buildbot/bin/python'} 14:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:01:56 INFO - Running setup.py (path:/tmp/pip-zKOkC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:01:56 INFO - Running setup.py (path:/tmp/pip-UyGlX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:01:56 INFO - Running setup.py (path:/tmp/pip-mprEPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:01:56 INFO - Running setup.py (path:/tmp/pip-fN6AgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:01:56 INFO - Running setup.py (path:/tmp/pip-1y_utb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:01:56 INFO - Running setup.py (path:/tmp/pip-d1wfh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:01:56 INFO - Running setup.py (path:/tmp/pip-CjMYo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:01:56 INFO - Running setup.py (path:/tmp/pip-FXLjFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:01:56 INFO - Running setup.py (path:/tmp/pip-qtrEey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:01:56 INFO - Running setup.py (path:/tmp/pip-MYArHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:01:56 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)) 14:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:01:56 INFO - Running setup.py (path:/tmp/pip-O6IHTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:01:57 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)) 14:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:01:57 INFO - Running setup.py (path:/tmp/pip-4jHQfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:01:57 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)) 14:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:01:57 INFO - Running setup.py (path:/tmp/pip-aM1bms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:01:57 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)) 14:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:01:57 INFO - Running setup.py (path:/tmp/pip-f296O5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:01:57 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)) 14:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:57 INFO - Running setup.py (path:/tmp/pip-W_FKZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:57 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)) 14:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:01:57 INFO - Running setup.py (path:/tmp/pip-05BCuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:01:57 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)) 14:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:01:57 INFO - Running setup.py (path:/tmp/pip-AdgSL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:01:57 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)) 14:01:57 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)) 14:01:57 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)) 14:01:57 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)) 14:01:57 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)) 14:01:57 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)) 14:01:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01: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 14:01:59 INFO - Downloading blessings-1.6.tar.gz 14:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:01:59 INFO - Installing collected packages: blessings 14:01:59 INFO - Running setup.py install for blessings 14:01:59 INFO - Successfully installed blessings 14:01:59 INFO - Cleaning up... 14:01:59 INFO - Return code: 0 14:01:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:01:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:01:59 INFO - Reading from file tmpfile_stdout 14:01:59 INFO - Current package versions: 14:01:59 INFO - argparse == 1.2.1 14:01:59 INFO - blessings == 1.6 14:01:59 INFO - blobuploader == 1.2.4 14:01:59 INFO - docopt == 0.6.1 14:01:59 INFO - functools32 == 3.2.3-2 14:01:59 INFO - jsonschema == 2.5.1 14:01:59 INFO - manifestparser == 1.1 14:01:59 INFO - mozInstall == 1.12 14:01:59 INFO - mozcrash == 0.17 14:01:59 INFO - mozdebug == 0.1 14:01:59 INFO - mozdevice == 0.48 14:01:59 INFO - mozfile == 1.2 14:01:59 INFO - mozhttpd == 0.7 14:01:59 INFO - mozinfo == 0.9 14:01:59 INFO - mozleak == 0.1 14:01:59 INFO - mozlog == 3.2 14:01:59 INFO - moznetwork == 0.27 14:01:59 INFO - mozprocess == 0.22 14:01:59 INFO - mozprofile == 0.28 14:01:59 INFO - mozrunner == 6.11 14:01:59 INFO - mozscreenshot == 0.1 14:01:59 INFO - mozsystemmonitor == 0.3 14:01:59 INFO - moztest == 0.7 14:01:59 INFO - mozversion == 1.4 14:01:59 INFO - psutil == 3.1.1 14:01:59 INFO - requests == 1.2.3 14:01:59 INFO - wsgiref == 0.1.2 14:01:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01:59 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')]} 14:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:59 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')]} 14:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:59 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 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:01:59 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 14:01:59 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 14:01:59 INFO - Using env: {'DISPLAY': ':0', 14:01:59 INFO - 'HOME': '/home/cltbld', 14:01:59 INFO - 'LANG': 'en_US.UTF-8', 14:01:59 INFO - 'LANGUAGE': 'en_US:en', 14:01:59 INFO - 'LOGNAME': 'cltbld', 14:01:59 INFO - 'MAIL': '/var/mail/cltbld', 14:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:59 INFO - 'MOZ_NO_REMOTE': '1', 14:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:01:59 INFO - 'NO_EM_RESTART': '1', 14:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:59 INFO - 'PWD': '/builds/slave/test', 14:01:59 INFO - 'SHELL': '/bin/bash', 14:01:59 INFO - 'SHLVL': '1', 14:01:59 INFO - 'TERM': 'linux', 14:01:59 INFO - 'TMOUT': '86400', 14:01:59 INFO - 'USER': 'cltbld', 14:01:59 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:59 INFO - '_': '/tools/buildbot/bin/python'} 14:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:00 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)) 14:02:00 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)) 14:02:00 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)) 14:02:00 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)) 14:02:00 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)) 14:02:00 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)) 14:02:00 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)) 14:02:00 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)) 14:02:00 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)) 14:02:00 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)) 14:02:00 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)) 14:02:00 INFO - Cleaning up... 14:02:00 INFO - Return code: 0 14:02:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:02:00 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')]} 14:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:00 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')]} 14:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:00 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 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:02:00 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 14:02:00 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 14:02:00 INFO - Using env: {'DISPLAY': ':0', 14:02:00 INFO - 'HOME': '/home/cltbld', 14:02:00 INFO - 'LANG': 'en_US.UTF-8', 14:02:00 INFO - 'LANGUAGE': 'en_US:en', 14:02:00 INFO - 'LOGNAME': 'cltbld', 14:02:00 INFO - 'MAIL': '/var/mail/cltbld', 14:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:00 INFO - 'MOZ_NO_REMOTE': '1', 14:02:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:02:00 INFO - 'NO_EM_RESTART': '1', 14:02:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:00 INFO - 'PWD': '/builds/slave/test', 14:02:00 INFO - 'SHELL': '/bin/bash', 14:02:00 INFO - 'SHLVL': '1', 14:02:00 INFO - 'TERM': 'linux', 14:02:00 INFO - 'TMOUT': '86400', 14:02:00 INFO - 'USER': 'cltbld', 14:02:00 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:00 INFO - '_': '/tools/buildbot/bin/python'} 14:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:02:00 INFO - Cleaning up... 14:02:00 INFO - Return code: 0 14:02:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:02:00 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')]} 14:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:00 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')]} 14:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:00 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 0x7fcd26cc4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21ec350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, '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': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', '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 14:02:00 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 14:02:00 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 14:02:00 INFO - Using env: {'DISPLAY': ':0', 14:02:00 INFO - 'HOME': '/home/cltbld', 14:02:00 INFO - 'LANG': 'en_US.UTF-8', 14:02:00 INFO - 'LANGUAGE': 'en_US:en', 14:02:00 INFO - 'LOGNAME': 'cltbld', 14:02:00 INFO - 'MAIL': '/var/mail/cltbld', 14:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:00 INFO - 'MOZ_NO_REMOTE': '1', 14:02:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:02:00 INFO - 'NO_EM_RESTART': '1', 14:02:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:00 INFO - 'PWD': '/builds/slave/test', 14:02:00 INFO - 'SHELL': '/bin/bash', 14:02:00 INFO - 'SHLVL': '1', 14:02:00 INFO - 'TERM': 'linux', 14:02:00 INFO - 'TMOUT': '86400', 14:02:00 INFO - 'USER': 'cltbld', 14:02:00 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:00 INFO - '_': '/tools/buildbot/bin/python'} 14:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:00 INFO - Downloading/unpacking flake8 14:02:00 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 14:02:00 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 14:02:00 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 14:02:00 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 14:02:00 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 14:02:00 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 14:02:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:02:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:02:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 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 14:02:02 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 14:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:02:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:02:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:02:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:02:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:02:03 INFO - no previously-included directories found matching 'docs/_build' 14:02:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:02:03 INFO - Running setup.py install for pep8 14:02:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:02:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:02:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:02:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:02:03 INFO - no previously-included directories found matching 'docs/_build' 14:02:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:02:03 INFO - Cleaning up... 14:02:03 INFO - Return code: 0 14:02:03 INFO - Running post-action listener: _resource_record_post_action 14:02:03 INFO - Running post-action listener: _start_resource_monitoring 14:02:03 INFO - Starting resource monitoring. 14:02:03 INFO - ##### 14:02:03 INFO - ##### Finished create-virtualenv step (success) 14:02:03 INFO - ##### 14:02:03 INFO - ##### 14:02:03 INFO - ##### Running install step. 14:02:03 INFO - ##### 14:02:03 INFO - Running pre-action listener: _resource_record_pre_action 14:02:03 INFO - Running main action method: install 14:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:02:03 INFO - Reading from file tmpfile_stdout 14:02:03 INFO - Detecting whether we're running mozinstall >=1.0... 14:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:02:03 INFO - Reading from file tmpfile_stdout 14:02:03 INFO - Output received: 14:02:03 INFO - Usage: mozinstall [options] installer 14:02:03 INFO - Options: 14:02:03 INFO - -h, --help show this help message and exit 14:02:03 INFO - -d DEST, --destination=DEST 14:02:03 INFO - Directory to install application into. [default: 14:02:03 INFO - "/builds/slave/test"] 14:02:03 INFO - --app=APP Application being installed. [default: firefox] 14:02:03 INFO - mkdir: /builds/slave/test/build/application 14:02:03 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'] 14:02:03 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 14:02:19 INFO - Reading from file tmpfile_stdout 14:02:19 INFO - Output received: 14:02:19 INFO - /builds/slave/test/build/application/firefox/firefox 14:02:19 INFO - Running post-action listener: _resource_record_post_action 14:02:19 INFO - ##### 14:02:19 INFO - ##### Finished install step (success) 14:02:19 INFO - ##### 14:02:19 INFO - ##### 14:02:19 INFO - ##### Running run-tests step. 14:02:19 INFO - ##### 14:02:19 INFO - Running pre-action listener: _resource_record_pre_action 14:02:19 INFO - Running main action method: run_tests 14:02:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:02:19 INFO - Python 2.7.3 14:02:19 INFO - Return code: 0 14:02:19 INFO - grabbing minidump binary from tooltool 14:02:19 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')]} 14:02:19 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 0x227b1c0>, '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 0x227c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227c550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:02:19 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 14:02:19 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 14:02:19 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 14:02:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:02:19 INFO - Return code: 0 14:02:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:02:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:02:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:02:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:02:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:02:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:02:20 INFO - Return code: 0 14:02:20 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/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 14:02:20 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/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 14:02:20 INFO - Using env: {'DISPLAY': ':0', 14:02:20 INFO - 'HOME': '/home/cltbld', 14:02:20 INFO - 'LANG': 'en_US.UTF-8', 14:02:20 INFO - 'LANGUAGE': 'en_US:en', 14:02:20 INFO - 'LOGNAME': 'cltbld', 14:02:20 INFO - 'MAIL': '/var/mail/cltbld', 14:02:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:02:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:20 INFO - 'MOZ_NO_REMOTE': '1', 14:02:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:02:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:02:20 INFO - 'NO_EM_RESTART': '1', 14:02:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:20 INFO - 'PWD': '/builds/slave/test', 14:02:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:02:20 INFO - 'SHELL': '/bin/bash', 14:02:20 INFO - 'SHLVL': '1', 14:02:20 INFO - 'TERM': 'linux', 14:02:20 INFO - 'TMOUT': '86400', 14:02:20 INFO - 'USER': 'cltbld', 14:02:20 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595', 14:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:20 INFO - '_': '/tools/buildbot/bin/python'} 14:02:20 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/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 14:02:20 INFO - mozversion application_buildid: 20160609095531 14:02:20 INFO - mozversion application_changeset: a11eb3fab3c603e2b13f8185db8e1815b44d8692 14:02:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:02:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:02:20 INFO - mozversion application_name: Firefox 14:02:20 INFO - mozversion application_remotingname: firefox-dev 14:02:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:02:20 INFO - mozversion application_vendor: Mozilla 14:02:20 INFO - mozversion application_version: 49.0a2 14:02:20 INFO - mozversion platform_buildid: 20160609095531 14:02:20 INFO - mozversion platform_changeset: a11eb3fab3c603e2b13f8185db8e1815b44d8692 14:02:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:02:20 INFO - mozversion platform_version: 49.0a2 14:02:20 INFO - using testdate: 1465506140 14:02:20 INFO - actual date: 1465506140 14:02:20 INFO - starting webserver on 'localhost:55585' 14:02:20 INFO - SUITE-START | Running 2 tests 14:02:20 INFO - TEST-START | dromaeo_css 14:02:20 INFO - operating with platform_type : linux_ 14:02:20 INFO - Initialising browser for dromaeo_css test... 14:02:20 INFO - TEST-INFO | started process 8291 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuGLtHm/profile http://localhost:55585/getInfo.html) 14:02:24 INFO - PROCESS | 8291 | __metrics Screen width/height:1600/1200 14:02:24 INFO - PROCESS | 8291 | colorDepth:24 14:02:24 INFO - PROCESS | 8291 | Browser inner width/height: 1024/697 14:02:24 INFO - PROCESS | 8291 | __metrics 14:02:25 ERROR - PROCESS | 8291 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:02:25 INFO - PROCESS | 8291 | console.error: PushService: 14:02:25 INFO - PROCESS | 8291 | stateChangeProcessEnqueue: Error transitioning state 14:02:25 INFO - PROCESS | 8291 | UnknownError 14:02:26 INFO - TEST-INFO | 8291: exit 0 14:02:26 INFO - Browser initialized. 14:02:26 INFO - Running cycle 1/1 for dromaeo_css test... 14:02:26 INFO - TEST-INFO | started process 8381 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuGLtHm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:02:38 INFO - PROCESS | 8381 | RSS: Main: 145530880 14:02:38 INFO - PROCESS | 8381 | 14:03:29 INFO - PROCESS | 8381 | 1465506209146 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] 14:05:37 INFO - PROCESS | 8381 | Cycle 1(1): loaded http://localhost:55585/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55585/tests/dromaeo/cssquery-ext.html) 14:05:38 INFO - PROCESS | 8381 | RSS: Main: 254124032 14:05:38 INFO - PROCESS | 8381 | 14:08:41 INFO - PROCESS | 8381 | Cycle 1(1): loaded http://localhost:55585/tests/dromaeo/cssquery-ext.html (next: http://localhost:55585/tests/dromaeo/cssquery-jquery.html) 14:08:41 INFO - PROCESS | 8381 | RSS: Main: 230174720 14:08:41 INFO - PROCESS | 8381 | 14:11:43 INFO - PROCESS | 8381 | Cycle 1(1): loaded http://localhost:55585/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55585/tests/dromaeo/cssquery-mootools.html) 14:11:44 INFO - PROCESS | 8381 | RSS: Main: 223793152 14:11:44 INFO - PROCESS | 8381 | 14:14:46 INFO - PROCESS | 8381 | Cycle 1(1): loaded http://localhost:55585/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55585/tests/dromaeo/cssquery-prototype.html) 14:14:46 INFO - PROCESS | 8381 | RSS: Main: 219181056 14:14:46 INFO - PROCESS | 8381 | 14:17:49 INFO - PROCESS | 8381 | Cycle 1(1): loaded http://localhost:55585/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55585/tests/dromaeo/cssquery-yui.html) 14:17:50 INFO - PROCESS | 8381 | RSS: Main: 217935872 14:17:50 INFO - PROCESS | 8381 | 14:20:52 INFO - PROCESS | 8381 | Cycle 1(1): loaded http://localhost:55585/tests/dromaeo/cssquery-yui.html (next: http://localhost:55585/tests/dromaeo/cssquery-dojo.html) 14:20:52 INFO - PROCESS | 8381 | RSS: Main: 262897664 14:20:52 INFO - PROCESS | 8381 | 14:20:52 INFO - PROCESS | 8381 | __start_tp_report 14:20:52 INFO - PROCESS | 8381 | _x_x_mozilla_page_load 14:20:52 INFO - PROCESS | 8381 | _x_x_mozilla_page_load_details 14:20:52 INFO - PROCESS | 8381 | |i|pagename|runs| 14:20:52 INFO - PROCESS | 8381 | |0;dojo.html;3363.7645364824457;3392.525046493491;3393.372226138164;3420.4014297497934;3441.7762845415045;5567.248421463107;5629.437056294369;5630.465105814941;5638.295213098363;5650.3219613646315;5634.943650563493;5691.601587888849;5705.657660540371;5708.08670612702;5711.514521265687;4998.750062496879;5057.519535644126;5145;5166.948330516694;5199.844004679866;5058;5098.490150984909;5108;5126.615503837208;5173.560247378978;5120.820771273006;5145.922811157836;5215.869603259911;5215.895682086356;5217.321748172732;5131.179011358202;5141.048905952411;5189.481051894818;5208.791648334081;5213.035588416136;4939.110960027194;5045.066662667418;5071.695698258116;5080.034793389244;5125.82059627915;5574.58190635704;5597.188407680904;5597.496225339739;5620.831375058755;5624.21261023457;5748.482636562688;6074.976289122938;6078.0668218499495;6100.816975490742;6107.572469927107;6011.879762404771;6082.361352058015;6100.572959892809;6127.050307202391;6148.200733904609;5768.077107662796;5772.0476121439915;5773.451522105393;5774.595778295521;5776.046952252916;6028.9397106029255;6067.271927368744;6226.813195604139;6241.78153764616;6242.687865606701;5878.882422351529;5927.9703601481715;5955.642661440327;5959.880802383928;5981.102834574762;6041.395860413923;6044.637321760709;6046.516278697693;6060.878782424327;6081.391860813883;5843.2403787507355;5886.411358864165;5891.263592050994;5987.880242395127;5988.610740301867;5908.645481271137;5944.138099975479;5950.464458198783;5952.464278214895;5978.222831031938;4484.304932735431;4554.863354099382;4567.588916997486;4569.908601828011;4601.148787474261;4027.436158937751;4083.4487344208746;4093.836246550164;4096;4132.462779838602;5758.1650660654805;5775.249217601685;5779.797707080232;5788.247527821356;5789.334226563915;6091.1987256073635;6092.329843717188;6099.207103076572;6102.786402475892;6137.693115344304;5575.4424557543925;5739.512141467939;5745.88508229833;5754.136879468029;5858.062709966385;5249.501297376743;5265.21021846726;5267.525922666901;5271.472852714698;5281.313429254173;6108.389161083945;6345.809625711236;6351.079093531504;6368.713407896656;6370;5958.672273025025;6008.218931538872;6017.939820601913;6065.8180254592435;6088.969555152196;6212.378762123752;6214.875702485834;6239.407256310825;6243.406876346739;6245.531585131224;5859.296884373903;5921.3190483094995;5931.584789064724;5937.2578427696535;5981.850453738692;5998.850028749316;6042.667653279112;6067.029275315928;6072.483838873745;6118;5948.970255148696;5959.22530071088;5961.582689211887;5961.91057134152;5963.4632883039;5772.567057470623;5786.132080188006;5931.436513531207;5943.1679564861815;5946.524278057832;5670.8015219467125;5756.366799651951;5760.020796464695;5763.366029736641;5794.391588883275;5916.201312822714;5927.644341339534;5962.16529685853;5965.552583556164;5974.9103763444455;5761.279840019997;5778.797742079007;5812.099124635688;5824.970875145597;5829.912551311649 14:20:52 INFO - PROCESS | 8381 | |1;ext.html;602.9547783916205;652.8237375908508;655.2661019657984;666.4768156996759;672.7712577723574;33353.83323083384;46832.53167468328;48194.75902620482;48389.51610483903;48791.75604121974;48973.51026489734;49611.25583116256;49914.50085499153;50012.249816252785;50207;8455.112213217615;8918.866217006751;9137.451752894847;9138.406003609778;10790.622328218515;10535.157187425026;10963.671089867317;10981.505832237528;10991.505382257777;11002.284851484668;10950.178736594748;10960.177986650993;11049.17131215158;11051.447427628586;11059;8488.66045358188;8590.226879580869;8697.130286971316;10746.946265268623;10946.288491248084;10573.841392379121;10985.340879547173;10993.670189894316;11007;11011.559537618487;45806.31290530645;46197.76901115506;46252.53747462516;46267.537324626996;46416.76791616054;3593.6226696197045;3641.1443310821937;3670.7797532148156;3960.485136932209;4323.373110898933;35066.6493335066;35684;35729.2233363445;35735.82132089323;35986.460203096976;4541.318802179667;4622.075584883003;4639;4682.6019788318035;4729.574338309534;259675;260810;261872;378546;381156;35920.640793592254;37037;66102.66948665322;66764;66958.66520667463;39223;39466;39476.407853882214;39507;39542.2091558173;16813.91593042027;16832.663346732996;16869.91565042167;16904.915475422546;16948.322067117424;12872.292023938586;13861.86138138626;13892.513762018483;13892.93053534726;14271.500497482746;9083.68207112748;9125.224355929688;9150.267978561833;9169.679061232824;9212;3432.5366075579477;3440.3807314683095;3483.4774783782636;3524.488949102366;3532.9823350883594;1029.7219750667277;1070.662741236508;1092.5029111754134;1111.127764704703;1142.1091548592112;34297.82851085729;34947.12632184216;35015;35214.29571408557;35267;1134.0983917785381;1139.8176291793272;1177.2877409167502;1266.4111188297486;1286.4275397448046;17924.55188620295;18054.18756155976;18120.818791812177;18179;18181.45455636333;631802;638113;638791;640709;643480;63303.36696632975;64836;65019.34980650227;65059.34940650533;65171;1379.9931000344934;1472.6244807574;1478.5490425420135;1478.7264356094156;1506.9321880515183;33385.165370864954;34147.4877876837;34856.47715284222;34948.30103397969;34997;54448.18327725008;54592.454075458736;54866.725666371414;54995.72502137624;55656.16515752186;417735;419163;419545;420586;421926;27153.456930860877;28136.43727125487;31066.378672426876;31149.844250779508;31942.840285798422;52053.7397313011;52098;52313.21530176974;52543.47456525539;52558.47441525536;42237.57762422336;42839.57160428481;43524.56475435205;44033.339499906884;44143.77928110339;42831.3575296377;43651.78174109236;43732;44146;44537.331940020274;43202;43465.78267108644;44154.337684935395;44408.11183776371;44706.55293447024;37249.44125838169;38503;38914.4162837552;39087.41368879412;39938.2012359757;29120.56319155172;29241.415171696874;29263.268418289714;29308.57893711355;29501.557476638296 14:20:52 INFO - PROCESS | 8381 | |2;jquery.html;406;407.58426405066814;413.08295583803965;425.6722323810666;425.7168982626554;14613.561593152195;18196.272149114036;19511.317103901365;19546.511337216576;20233.291834785785;18534.629307413845;19273.229070837184;19398.321058762947;19729.506762330948;20592.588148237028;5061.39263288405;5184.1186998210205;5184.688918664892;5189.844304670847;5204.375474942994;3865.8260378283044;5091.210862316347;5135.3067335909545;5137.717425541594;5139.897202055957;5407.675539467645;5618.943810561904;5664.858378540549;5760.0495918173665;5764.221830052931;5678.261825962619;5700.942990570083;5708.400617935098;6304.653244071575;6613.537052406334;5835.299764028301;5912.408759124096;5931.258592675916;5970.552208584373;5971.641701497924;12876.227426354355;20124.195032198695;22486.775132248633;23670.053197872065;23715.644265336035;2420.5279970405777;2426.1629737740627;2811.8031737778365;2952.660444048919;3060.127863558875;9974.80050398988;10152.23858210637;10229.641962531276;13374.197548147142;13390.263535505543;1978.6042791441769;2000.1099510717736;2015.254355888316;2025.1089520610883;2056.208359781495;77069.61465192639;78539.60730196313;79069;83418.58290708569;87753;8689.782755431164;9250.398724083048;9696.466694331879;10685.465726713634;10707.625233116803;14422.783658245135;14471.421143154155;14482.275886205647;14520.419183232552;14527.709445811235;8263.338932885355;8472.576371181416;8551.84550085743;8620.870686939701;8628.913710862811;8431.32549396059;8487.10885357041;8495.532745698922;8652.04827468978;8656.39405241627;4179.623833854967;4231.280682284019;4313.676474264443;4449.844255451108;4574.6569007323915;1857.647047061054;1949.2592814730313;2015.0932080563512;2051.98974005129;2136.9679454808193;912.7444315591645;1020.260311274335;1036.896310368972;1066.941318227505;1108.9667309980714;19860.801391985897;19863.30478433248;19900.402987910387;20120.798792011894;20286.492837679176;1017.5522769981333;1020.6784862768204;1032.746976990642;1062.6387028410377;1062.6440142552176;11011.28426652265;11224.494897729623;11306.94346528262;11353.091752659933;11627.825582616268;464096;487525;493380;494489;495456;99215;100614.49692751636;101306;101523;101649.49175254077;1042.1454407386022;1053.773438710681;1058.687687132298;1063.1335461598771;1077.7413420778958;17290.91354543219;17313.22090505905;17851.642967140844;18072.277108915496;18133.184006719992;31765.68234317627;41006.58993410028;41268;41269.38095928504;41394.37908431316;53841.73079134579;54551.45448545464;59095;59279;59377.70311148417;7682.923170768444;7774.805629859299;7943.126256111707;8099.14958929304;8316.251537361666;11982.760344793229;13583.52457663977;13719.519816806363;13932.442702291795;14066.859331406555;11575.131865109983;11900.166988310735;11915.285082895054;12215.022798175947;12367.257964522158;11552.711182220512;11557.30656160633;11963.581274655346;12229.571964981184;12520.123591348882;12352.876471235173;12634.115611907078;12690.87309126897;12715.618531444072;12932.676683082998;8110.31062359706;8176.100628930932;8265.214804593554;8267.917320826955;8365.707200247962;12460.501579936701;12582.87417125817;12592.496300147892;12677.746445070863;12717.173383730027 14:20:52 INFO - PROCESS | 8381 | |3;mootools.html;1394.3446580107359;1587.944421945232;1606.4136590144597;1807.466797294798;1935.3903520391075;8982.640694372225;9301.90698093019;9344.205742511895;9616.471094089822;9636.469994150319;9292.117248861365;9549.904500955006;9569.760755981104;9574.2340612751;9585.856212156823;2189.704389907366;2243.0242844362756;2247.741509726386;2435.610302351624;2473.88867500962;2378.108209421467;2444.9877750611313;2446.8164887633498;2455.373879660684;2462.8645424501647;2400.8079353651665;2402.4834660547976;2420.9273721788286;2425.417899704067;2439.658447817307;2155.3964889830872;2218.500837311608;2506.498700259946;2543.5040167167504;2546.2234018624313;2153.2678889177755;2367;2428.0409238350753;2428.623563347684;2441.07239249086;9672.75818104546;9728.708138755848;9738.464384458852;9745.90254097457;9759;5507.476789704971;5642.351129620105;5659.915101273484;5663.0655941769555;5664.40523745005;3535.540379750616;3654.250878569887;3688.1517251032146;3698.1863989922176;3699.5005674233903;1220.1641875315438;1269.2574843716345;1273.7643535945879;1280.5262053040435;1282.0576875996135;14231.857681423156;14289.071210371292;14294.499692510712;14304.928475357556;14318.71362572753;4846.127697014501;4868.756562171877;4892.021595680878;4919.3604831371695;4944.381952255968;11806.291622502677;11823.231489953125;11838.644840654793;11838.644840654793;11863.822042669366;5654.180143879197;5696.3733989261145;5704.828855134353;5716.857078572986;5718.971405143031;4559.817607295671;5149.356330458692;5192.065428222956;5244.239585260192;5323.893522129536;4218.093109981369;4222.4299719538085;4268.210381079511;4308.612224899774;4313.374560688745;1289.5884695619065;1305.1190446448609;1310.33173081729;1312.9080964332404;1313.382710126239;887.5384799904014;898.3486971945288;1043.5564884924029;1086.4187659602076;1094.7536804219146;6455.096286519891;6503.512236582275;6504.1869766279215;6591.670416479157;6618.238902526175;990.1781521337273;1063.1813503602184;1072.6460268111505;1082.2099867097063;1090.547422819537;4526.388937493462;4575.816967321336;4609.769511524478;4637.8840528986375;4686.671932964659;12166.75666486683;12550.309732964592;12604.180728252633;12627.810582841264;12646;11694.122940779476;12047.81928271059;12116.939415302868;12470.750584988431;12497.687557810945;8518.74443766688;8638.697645582375;8763.211310981915;8827.382083254111;8832.337574681796;6659;6708;6747.527673062838;6852.349026842442;6876.106106206161;11168.832467513157;11313.886861131612;11317.943410282896;11366.374849383363;11495;11315.434228288717;11627.534898604299;11641.185117041725;11664.066874649838;11669.299842009506;7650;8239.505629662;8492.065872753868;8531.701390451304;8607.741767746978;9494.525273736148;9519.762005949906;9521.095495927875;9575.90424095778;9584.712458626253;8346.79133021655;8358.66565337404;8504.064552899052;8519.787005324668;8566.78583035429;8136.1863813618165;8196.959015205124;8204.261616454547;8360.205780450848;8377.790555236168;7788.415868809749;7933.563653999086;7966.645670236189;7980.800479988046;7983.84032319362;5489.862753431036;5514.779408823602;5579.972100139636;5680.375158732619;5705.258316419005;5292.841214763564;5603.747831347518;5634.859128521656;5644.012297847942;5776.480116789509 14:20:52 INFO - PROCESS | 8381 | |4;prototype.html;341.96922276995065;347.66797708188665;349.758666520101;353.3339654750795;356.8572570971611;5527.861803454911;5534.972325138378;5535.335759708835;5553.111502159655;5630.127330263806;5437.265969094171;5492.286002819628;5501.669899806015;5512.862178445531;5521.917171242435;3934;4110.691698122645;4260.808263628144;4266.357452380825;4303.203907277148;4283.271843786564;4311.116221174651;4314.71954322968;4315.5900189481945;4329.67527435442;4134.586541345856;4295.7852107394665;4305.246581848164;4307.160103779748;4326.956730432703;4137.420761093449;4262.403263543091;4297.441332626739;4297.742135471851;4312;3791.658750712449;3792.544894612664;3800.1259710266518;3803.182315802088;3805.562360328542;5305.257263983045;5316.680999140064;5356.35723713153;5372.7313634318125;5386.326709161355;4952.380952380952;4957.975210123926;4959.181735013718;4962.255661650745;4962.751862406865;5523.558115350762;5540.196671482612;5682.232898558683;5723.370429252779;5788.768449262025;4093.792331262255;4176.352665336878;4177.642266263446;4193.448424083109;4202.894927626803;5924.940750592526;5929.902967950899;5931.576421658814;5940.851478713023;5948.7620495180145;4027.9798601006805;4217.873463796091;4224.8732538023905;4243;4245.745255284693;4282.743035417885;4289.056407590325;4298.4197133387215;4307.56924307573;4307.763073030951;4887.29134275528;4891.364122664102;4907.975460122748;4908.435529914034;4923.335349727741;5524.309461317335;5753.6985205917135;5810.1865738796605;5816.709164541756;5858.648481091148;1470.5588523247063;1487.256371814093;1490.4038384646228;1495.297210311152;1497.423491955605;924.1543987251589;942.6936245720168;948.5921053946731;950.5864948747317;951.8815391914472;5022.735222105587;5025.020121076355;5067.771950262247;5076.466970968069;5084.237364395296;5251.238570407269;5256.710880901587;5265.4730128263145;5268.341457317835;5308.221745715152;5071.619628527875;5073.909109541466;5076.822311219164;5322.3879253885525;5791.681457519877;3163.5254711793414;3175.412548678502;3193.5209718542405;3204.4872820348633;3222.758293128025;6030.379496313871;6066.99789069606;6088.726007329681;6094.664793436404;6123.663198524124;5910.468057874726;6046.7278972445465;6058.21243238385;6072.666003369769;6075;5851.180834683148;5987.311459182162;6081.753240585669;6115.938840611626;6162.784302549389;5893.351731309638;5944.346121926671;5961.046232602763;5997.13041608973;6027.78902738402;5643.661380317194;5865.94134058654;5919.171316015675;5920.348761636303;5976.905819513647;6140.846478838065;6166.242620853215;6184.350643182401;6199.56603037783;6218.657973811303;4249.32010878258;4273.124009577967;4279.229738647074;4281.850135245251;4290.935635965526;5832.24180856486;5947.851303717442;5949.19685842423;5966.880662386815;6021.157038014769;5777.422257774189;5800.796972105956;5854.648721076749;5858.882822343615;5860.824175274749;5587.832365029056;5847.508885234408;5856.180134781049;5859.677717725566;5884.7940322088525;5691.630044047124;5701.971490142523;5721.284839395075;5746.012073721725;5746.557712972054;5852.034414321589;5886.411358864079;5896.6756828374855;5903.586748927534;5916.89363084367;5605.523530499953;5793.754342816314;5807.054636696754;5823.650580965155;5827.4172582741385 14:20:52 INFO - PROCESS | 8381 | |5;yui.html;805.532790981231;814.991850081499;828.382854773194;859.3426029087748;885.3050355470955;15059.322330495126;16154.111523866182;16215.189240537984;16239;16249.187540622966;14187.858121418809;14225.359858806352;14318.856811431908;14320.785188222186;14365.569032929003;706.1455638677206;807.9717209897655;857.9184977427135;860.4708104515709;874.8031692869085;870.7213691618684;873.1966590736506;875.5797217335682;876.5441970175536;879.2350654930187;900.8693739407782;905.0858632780872;911.9133682300171;913.3241401362977;915.8671992561075;753.3596443023441;892.6786356911539;912.6258234123977;913.6893456224882;918.4764684130049;659.5679829711498;660.735705717712;662.4468568745103;666.3436515032689;681.3459079283894;14313;14392.136471811726;14395;14396.712065758731;14440.783388249185;14374.20941848198;15088.094714317067;15470.226488675522;15632.296546655427;15892.52322430329;13125.408050874972;13402.12886162406;13443.32783360828;13611.931940340333;13759.79360309586;4682.648801339913;4715.811367545294;4753.215719406294;4763.285507173917;4896.730679812609;77545.22454775436;77895.61052194703;80323.19676803157;81177;81975.18024819795;11579.247348922294;11682.532698692126;11713.472893719803;11787.351695656822;11929.582464613697;21145.68281475779;21695;21700.131994720352;21803.563928721338;21804;8395.202455766692;8457.112003239694;8481.830363392697;8655.437396569203;8719.825603488021;4534.79593418297;4604.677672562889;4697.295405689106;4711.199096153662;4723.055388922229;3527.3121741260206;3611.331903597843;3614.168741189541;3626.9818650906577;3637.399829028234;645.2676212498848;653.9803805885831;667.9699413526403;688.2360579756466;702.0978043214445;642.7043559962376;742.7177672484421;806.6208881825532;812.4231795425314;818.2308629887887;9537.618495260112;9674.75813104678;9730.75673108178;9786.657466988763;9840.704778856645;657.4510283912987;673.3434900971514;743.130537271383;755.6977209116287;784.6822037074879;4763.785629646674;4850.5149485051215;4853.150698627796;4910.779014944336;4964.627652926045;73138.26861731421;74341.25658743452;74684.62657686677;75223.62388188024;100271.49864250778;94159.52920235493;95256.52371738236;95926;96035.51982240044;96907;88299;90472;90583;91498.08501915028;91912;9966.40201587907;9972.401655900669;10249.487525623837;10362.378257304586;10414;52025.21962170495;52946;53357;53905.46094539004;54268.45731542634;69469;76828;77242;77248.6137569331;77534.612326938;13003.634618364935;13024.934875325564;13339.399727012114;13435.462581496631;13798.655033623838;14962.925185374439;15635.76546351783;16001.839981600035;16457.91771041137;16563.91718041402;13985.930070349583;14199.929000355345;14266.358013889192;14272.714545709236;15094.320755565806;14252.572422827332;14620.561383158521;14793.630159246104;14857.925710371379;15273.770893436385;13522.12106213093;13653.385597647932;14021;14046.367913444124;14243.572692819233;12024.639260822189;12165.81751273714;12172;12322.383880805744;12431.813522796985;12061.276323420623;12478.126531143096;12496.312702801433;12726.872731272939;12733.808992865299 14:20:52 INFO - PROCESS | 8381 | __end_tp_report 14:20:52 INFO - PROCESS | 8381 | __start_cc_report 14:20:52 INFO - PROCESS | 8381 | _x_x_mozilla_cycle_collect,943 14:20:52 INFO - PROCESS | 8381 | __end_cc_report 14:20:52 INFO - PROCESS | 8381 | __startTimestamp1465507252767__endTimestamp 14:20:52 INFO - PROCESS | 8381 | 14:20:52 INFO - PROCESS | 8381 | ------- Summary: start ------- 14:20:52 INFO - PROCESS | 8381 | Number of tests: 6 14:20:52 INFO - PROCESS | 8381 | 14:20:52 INFO - PROCESS | 8381 | [#0] dojo.html Cycles:165 Average:5614.70 Median:5789.33 stddev:628.26 (10.9%) stddev-sans-first:604.86 14:20:52 INFO - PROCESS | 8381 | Values: 3363.8 3392.5 3393.4 3420.4 3441.8 5567.2 5629.4 5630.5 5638.3 5650.3 5634.9 5691.6 5705.7 5708.1 5711.5 4998.8 5057.5 5145.0 5166.9 5199.8 5058.0 5098.5 5108.0 5126.6 5173.6 5120.8 5145.9 5215.9 5215.9 5217.3 5131.2 5141.0 5189.5 5208.8 5213.0 4939.1 5045.1 5071.7 5080.0 5125.8 5574.6 5597.2 5597.5 5620.8 5624.2 5748.5 6075.0 6078.1 6100.8 6107.6 6011.9 6082.4 6100.6 6127.1 6148.2 5768.1 5772.0 5773.5 5774.6 5776.0 6028.9 6067.3 6226.8 6241.8 6242.7 5878.9 5928.0 5955.6 5959.9 5981.1 6041.4 6044.6 6046.5 6060.9 6081.4 5843.2 5886.4 5891.3 5987.9 5988.6 5908.6 5944.1 5950.5 5952.5 5978.2 4484.3 4554.9 4567.6 4569.9 4601.1 4027.4 4083.4 4093.8 4096.0 4132.5 5758.2 5775.2 5779.8 5788.2 5789.3 6091.2 6092.3 6099.2 6102.8 6137.7 5575.4 5739.5 5745.9 5754.1 5858.1 5249.5 5265.2 5267.5 5271.5 5281.3 6108.4 6345.8 6351.1 6368.7 6370.0 5958.7 6008.2 6017.9 6065.8 6089.0 6212.4 6214.9 6239.4 6243.4 6245.5 5859.3 5921.3 5931.6 5937.3 5981.9 5998.9 6042.7 6067.0 6072.5 6118.0 5949.0 5959.2 5961.6 5961.9 5963.5 5772.6 5786.1 5931.4 5943.2 5946.5 5670.8 5756.4 5760.0 5763.4 5794.4 5916.2 5927.6 5962.2 5965.6 5974.9 5761.3 5778.8 5812.1 5825.0 5829.9 14:20:52 INFO - PROCESS | 8381 | 14:20:52 INFO - PROCESS | 8381 | [#1] ext.html Cycles:180 Average:62046.30 Median:38708.71 stddev:127224.85 (328.7%) stddev-sans-first:127498.11 14:20:52 INFO - PROCESS | 8381 | Values: 603.0 652.8 655.3 666.5 672.8 33353.8 46832.5 48194.8 48389.5 48791.8 48973.5 49611.3 49914.5 50012.2 50207.0 8455.1 8918.9 9137.5 9138.4 10790.6 10535.2 10963.7 10981.5 10991.5 11002.3 10950.2 10960.2 11049.2 11051.4 11059.0 8488.7 8590.2 8697.1 10746.9 10946.3 10573.8 10985.3 10993.7 11007.0 11011.6 45806.3 46197.8 46252.5 46267.5 46416.8 3593.6 3641.1 3670.8 3960.5 4323.4 35066.6 35684.0 35729.2 35735.8 35986.5 4541.3 4622.1 4639.0 4682.6 4729.6 259675.0 260810.0 261872.0 378546.0 381156.0 35920.6 37037.0 66102.7 66764.0 66958.7 39223.0 39466.0 39476.4 39507.0 39542.2 16813.9 16832.7 16869.9 16904.9 16948.3 12872.3 13861.9 13892.5 13892.9 14271.5 9083.7 9125.2 9150.3 9169.7 9212.0 3432.5 3440.4 3483.5 3524.5 3533.0 1029.7 1070.7 1092.5 1111.1 1142.1 34297.8 34947.1 35015.0 35214.3 35267.0 1134.1 1139.8 1177.3 1266.4 1286.4 17924.6 18054.2 18120.8 18179.0 18181.5 631802.0 638113.0 638791.0 640709.0 643480.0 63303.4 64836.0 65019.3 65059.3 65171.0 1380.0 1472.6 1478.5 1478.7 1506.9 33385.2 34147.5 34856.5 34948.3 34997.0 54448.2 54592.5 54866.7 54995.7 55656.2 417735.0 419163.0 419545.0 420586.0 421926.0 27153.5 28136.4 31066.4 31149.8 31942.8 52053.7 52098.0 52313.2 52543.5 52558.5 42237.6 42839.6 43524.6 44033.3 44143.8 42831.4 43651.8 43732.0 44146.0 44537.3 43202.0 43465.8 44154.3 44408.1 44706.6 37249.4 38503.0 38914.4 39087.4 39938.2 29120.6 29241.4 29263.3 29308.6 29501.6 14:20:52 INFO - PROCESS | 8381 | 14:20:52 INFO - PROCESS | 8381 | [#2] jquery.html Cycles:180 Average:29084.48 Median:12311.87 stddev:80530.52 (654.1%) stddev-sans-first:80727.64 14:20:52 INFO - PROCESS | 8381 | Values: 406.0 407.6 413.1 425.7 425.7 14613.6 18196.3 19511.3 19546.5 20233.3 18534.6 19273.2 19398.3 19729.5 20592.6 5061.4 5184.1 5184.7 5189.8 5204.4 3865.8 5091.2 5135.3 5137.7 5139.9 5407.7 5618.9 5664.9 5760.0 5764.2 5678.3 5700.9 5708.4 6304.7 6613.5 5835.3 5912.4 5931.3 5970.6 5971.6 12876.2 20124.2 22486.8 23670.1 23715.6 2420.5 2426.2 2811.8 2952.7 3060.1 9974.8 10152.2 10229.6 13374.2 13390.3 1978.6 2000.1 2015.3 2025.1 2056.2 77069.6 78539.6 79069.0 83418.6 87753.0 8689.8 9250.4 9696.5 10685.5 10707.6 14422.8 14471.4 14482.3 14520.4 14527.7 8263.3 8472.6 8551.8 8620.9 8628.9 8431.3 8487.1 8495.5 8652.0 8656.4 4179.6 4231.3 4313.7 4449.8 4574.7 1857.6 1949.3 2015.1 2052.0 2137.0 912.7 1020.3 1036.9 1066.9 1109.0 19860.8 19863.3 19900.4 20120.8 20286.5 1017.6 1020.7 1032.7 1062.6 1062.6 11011.3 11224.5 11306.9 11353.1 11627.8 464096.0 487525.0 493380.0 494489.0 495456.0 99215.0 100614.5 101306.0 101523.0 101649.5 1042.1 1053.8 1058.7 1063.1 1077.7 17290.9 17313.2 17851.6 18072.3 18133.2 31765.7 41006.6 41268.0 41269.4 41394.4 53841.7 54551.5 59095.0 59279.0 59377.7 7682.9 7774.8 7943.1 8099.1 8316.3 11982.8 13583.5 13719.5 13932.4 14066.9 11575.1 11900.2 11915.3 12215.0 12367.3 11552.7 11557.3 11963.6 12229.6 12520.1 12352.9 12634.1 12690.9 12715.6 12932.7 8110.3 8176.1 8265.2 8267.9 8365.7 12460.5 12582.9 12592.5 12677.7 12717.2 14:20:52 INFO - PROCESS | 8381 | 14:20:52 INFO - PROCESS | 8381 | [#3] mootools.html Cycles:180 Average:6391.86 Median:4623.83 stddev:3765.14 (81.4%) stddev-sans-first:3756.97 14:20:52 INFO - PROCESS | 8381 | Values: 1394.3 1587.9 1606.4 1807.5 1935.4 8982.6 9301.9 9344.2 9616.5 9636.5 9292.1 9549.9 9569.8 9574.2 9585.9 2189.7 2243.0 2247.7 2435.6 2473.9 2378.1 2445.0 2446.8 2455.4 2462.9 2400.8 2402.5 2420.9 2425.4 2439.7 2155.4 2218.5 2506.5 2543.5 2546.2 2153.3 2367.0 2428.0 2428.6 2441.1 9672.8 9728.7 9738.5 9745.9 9759.0 5507.5 5642.4 5659.9 5663.1 5664.4 3535.5 3654.3 3688.2 3698.2 3699.5 1220.2 1269.3 1273.8 1280.5 1282.1 14231.9 14289.1 14294.5 14304.9 14318.7 4846.1 4868.8 4892.0 4919.4 4944.4 11806.3 11823.2 11838.6 11838.6 11863.8 5654.2 5696.4 5704.8 5716.9 5719.0 4559.8 5149.4 5192.1 5244.2 5323.9 4218.1 4222.4 4268.2 4308.6 4313.4 1289.6 1305.1 1310.3 1312.9 1313.4 887.5 898.3 1043.6 1086.4 1094.8 6455.1 6503.5 6504.2 6591.7 6618.2 990.2 1063.2 1072.6 1082.2 1090.5 4526.4 4575.8 4609.8 4637.9 4686.7 12166.8 12550.3 12604.2 12627.8 12646.0 11694.1 12047.8 12116.9 12470.8 12497.7 8518.7 8638.7 8763.2 8827.4 8832.3 6659.0 6708.0 6747.5 6852.3 6876.1 11168.8 11313.9 11317.9 11366.4 11495.0 11315.4 11627.5 11641.2 11664.1 11669.3 7650.0 8239.5 8492.1 8531.7 8607.7 9494.5 9519.8 9521.1 9575.9 9584.7 8346.8 8358.7 8504.1 8519.8 8566.8 8136.2 8197.0 8204.3 8360.2 8377.8 7788.4 7933.6 7966.6 7980.8 7983.8 5489.9 5514.8 5580.0 5680.4 5705.3 5292.8 5603.7 5634.9 5644.0 5776.5 14:20:52 INFO - PROCESS | 8381 | 14:20:52 INFO - PROCESS | 8381 | [#4] prototype.html Cycles:180 Average:4834.67 Median:5496.98 stddev:1419.79 (25.8%) stddev-sans-first:1383.14 14:20:52 INFO - PROCESS | 8381 | Values: 342.0 347.7 349.8 353.3 356.9 5527.9 5535.0 5535.3 5553.1 5630.1 5437.3 5492.3 5501.7 5512.9 5521.9 3934.0 4110.7 4260.8 4266.4 4303.2 4283.3 4311.1 4314.7 4315.6 4329.7 4134.6 4295.8 4305.2 4307.2 4327.0 4137.4 4262.4 4297.4 4297.7 4312.0 3791.7 3792.5 3800.1 3803.2 3805.6 5305.3 5316.7 5356.4 5372.7 5386.3 4952.4 4958.0 4959.2 4962.3 4962.8 5523.6 5540.2 5682.2 5723.4 5788.8 4093.8 4176.4 4177.6 4193.4 4202.9 5924.9 5929.9 5931.6 5940.9 5948.8 4028.0 4217.9 4224.9 4243.0 4245.7 4282.7 4289.1 4298.4 4307.6 4307.8 4887.3 4891.4 4908.0 4908.4 4923.3 5524.3 5753.7 5810.2 5816.7 5858.6 1470.6 1487.3 1490.4 1495.3 1497.4 924.2 942.7 948.6 950.6 951.9 5022.7 5025.0 5067.8 5076.5 5084.2 5251.2 5256.7 5265.5 5268.3 5308.2 5071.6 5073.9 5076.8 5322.4 5791.7 3163.5 3175.4 3193.5 3204.5 3222.8 6030.4 6067.0 6088.7 6094.7 6123.7 5910.5 6046.7 6058.2 6072.7 6075.0 5851.2 5987.3 6081.8 6115.9 6162.8 5893.4 5944.3 5961.0 5997.1 6027.8 5643.7 5865.9 5919.2 5920.3 5976.9 6140.8 6166.2 6184.4 6199.6 6218.7 4249.3 4273.1 4279.2 4281.9 4290.9 5832.2 5947.9 5949.2 5966.9 6021.2 5777.4 5800.8 5854.6 5858.9 5860.8 5587.8 5847.5 5856.2 5859.7 5884.8 5691.6 5702.0 5721.3 5746.0 5746.6 5852.0 5886.4 5896.7 5903.6 5916.9 5605.5 5793.8 5807.1 5823.7 5827.4 14:20:52 INFO - PROCESS | 8381 | 14:20:52 INFO - PROCESS | 8381 | [#5] yui.html Cycles:180 Average:20326.28 Median:4874.94 stddev:27593.30 (566.0%) stddev-sans-first:27631.77 14:20:52 INFO - PROCESS | 8381 | Values: 805.5 815.0 828.4 859.3 885.3 15059.3 16154.1 16215.2 16239.0 16249.2 14187.9 14225.4 14318.9 14320.8 14365.6 706.1 808.0 857.9 860.5 874.8 870.7 873.2 875.6 876.5 879.2 900.9 905.1 911.9 913.3 915.9 753.4 892.7 912.6 913.7 918.5 659.6 660.7 662.4 666.3 681.3 14313.0 14392.1 14395.0 14396.7 14440.8 14374.2 15088.1 15470.2 15632.3 15892.5 13125.4 13402.1 13443.3 13611.9 13759.8 4682.6 4715.8 4753.2 4763.3 4896.7 77545.2 77895.6 80323.2 81177.0 81975.2 11579.2 11682.5 11713.5 11787.4 11929.6 21145.7 21695.0 21700.1 21803.6 21804.0 8395.2 8457.1 8481.8 8655.4 8719.8 4534.8 4604.7 4697.3 4711.2 4723.1 3527.3 3611.3 3614.2 3627.0 3637.4 645.3 654.0 668.0 688.2 702.1 642.7 742.7 806.6 812.4 818.2 9537.6 9674.8 9730.8 9786.7 9840.7 657.5 673.3 743.1 755.7 784.7 4763.8 4850.5 4853.2 4910.8 4964.6 73138.3 74341.3 74684.6 75223.6 100271.5 94159.5 95256.5 95926.0 96035.5 96907.0 88299.0 90472.0 90583.0 91498.1 91912.0 9966.4 9972.4 10249.5 10362.4 10414.0 52025.2 52946.0 53357.0 53905.5 54268.5 69469.0 76828.0 77242.0 77248.6 77534.6 13003.6 13024.9 13339.4 13435.5 13798.7 14962.9 15635.8 16001.8 16457.9 16563.9 13985.9 14199.9 14266.4 14272.7 15094.3 14252.6 14620.6 14793.6 14857.9 15273.8 13522.1 13653.4 14021.0 14046.4 14243.6 12024.6 12165.8 12172.0 12322.4 12431.8 12061.3 12478.1 12496.3 12726.9 12733.8 14:20:52 INFO - PROCESS | 8381 | -------- Summary: end -------- 14:20:52 INFO - PROCESS | 8381 | 14:20:53 INFO - TEST-INFO | 8381: exit 0 14:20:53 INFO - TEST-OK | dromaeo_css | took 1112730ms 14:20:53 INFO - TEST-START | kraken 14:20:53 INFO - operating with platform_type : linux_ 14:20:53 INFO - Initialising browser for kraken test... 14:20:53 INFO - TEST-INFO | started process 8572 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNmgWpD/profile http://localhost:55585/getInfo.html) 14:20:57 INFO - PROCESS | 8572 | __metrics Screen width/height:1600/1200 14:20:57 INFO - PROCESS | 8572 | colorDepth:24 14:20:57 INFO - PROCESS | 8572 | Browser inner width/height: 1024/697 14:20:57 INFO - PROCESS | 8572 | __metrics 14:20:58 ERROR - PROCESS | 8572 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:20:58 INFO - PROCESS | 8572 | console.error: PushService: 14:20:58 INFO - PROCESS | 8572 | stateChangeProcessEnqueue: Error transitioning state 14:20:58 INFO - PROCESS | 8572 | UnknownError 14:20:59 INFO - TEST-INFO | 8572: exit 0 14:20:59 INFO - Browser initialized. 14:20:59 INFO - Running cycle 1/1 for kraken test... 14:20:59 INFO - TEST-INFO | started process 8665 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNmgWpD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:21:10 INFO - PROCESS | 8665 | RSS: Main: 149082112 14:21:10 INFO - PROCESS | 8665 | 14:21:13 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55585/tests/kraken/driver.html?testName=audio-beat-detection) 14:21:14 INFO - PROCESS | 8665 | RSS: Main: 198488064 14:21:14 INFO - PROCESS | 8665 | 14:21:16 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55585/tests/kraken/driver.html?testName=audio-dft) 14:21:17 INFO - PROCESS | 8665 | RSS: Main: 195502080 14:21:17 INFO - PROCESS | 8665 | 14:21:20 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55585/tests/kraken/driver.html?testName=audio-fft) 14:21:20 INFO - PROCESS | 8665 | RSS: Main: 282796032 14:21:20 INFO - PROCESS | 8665 | 14:21:23 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55585/tests/kraken/driver.html?testName=audio-oscillator) 14:21:23 INFO - PROCESS | 8665 | RSS: Main: 191414272 14:21:23 INFO - PROCESS | 8665 | 14:21:25 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55585/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:21:26 INFO - PROCESS | 8665 | RSS: Main: 192110592 14:21:26 INFO - PROCESS | 8665 | 14:21:29 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55585/tests/kraken/driver.html?testName=imaging-darkroom) 14:21:29 INFO - PROCESS | 8665 | RSS: Main: 226750464 14:21:29 INFO - PROCESS | 8665 | 14:21:33 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55585/tests/kraken/driver.html?testName=imaging-desaturate) 14:21:33 INFO - PROCESS | 8665 | RSS: Main: 226902016 14:21:33 INFO - PROCESS | 8665 | 14:21:36 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55585/tests/kraken/driver.html?testName=json-parse-financial) 14:21:37 INFO - PROCESS | 8665 | RSS: Main: 227168256 14:21:37 INFO - PROCESS | 8665 | 14:21:38 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55585/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:21:39 INFO - PROCESS | 8665 | RSS: Main: 205103104 14:21:39 INFO - PROCESS | 8665 | 14:21:41 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55585/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:21:41 INFO - PROCESS | 8665 | RSS: Main: 208023552 14:21:41 INFO - PROCESS | 8665 | 14:21:43 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55585/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:21:44 INFO - PROCESS | 8665 | RSS: Main: 211718144 14:21:44 INFO - PROCESS | 8665 | 14:21:46 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55585/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:21:47 INFO - PROCESS | 8665 | RSS: Main: 214413312 14:21:47 INFO - PROCESS | 8665 | 14:21:50 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55585/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:21:50 INFO - PROCESS | 8665 | RSS: Main: 214446080 14:21:50 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | Cycle 1(1): loaded http://localhost:55585/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55585/tests/kraken/driver.html?testName=ai-astar) 14:21:52 INFO - PROCESS | 8665 | RSS: Main: 256856064 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | __start_tp_report 14:21:52 INFO - PROCESS | 8665 | _x_x_mozilla_page_load 14:21:52 INFO - PROCESS | 8665 | _x_x_mozilla_page_load_details 14:21:52 INFO - PROCESS | 8665 | |i|pagename|runs| 14:21:52 INFO - PROCESS | 8665 | |0;ai-astar;111;105;111;109;110;111;112;111;111;110 14:21:52 INFO - PROCESS | 8665 | |1;audio-beat-detection;154;123;125;174;124;127;155;125;162;124 14:21:52 INFO - PROCESS | 8665 | |2;audio-dft;161;160;160;160;161;159;162;161;160;166 14:21:52 INFO - PROCESS | 8665 | |3;audio-fft;74;75;75;110;75;76;75;74;97;74 14:21:52 INFO - PROCESS | 8665 | |4;audio-oscillator;85;84;118;85;84;85;117;84;85;120 14:21:52 INFO - PROCESS | 8665 | |5;imaging-gaussian-blur;117;117;116;116;116;117;117;116;116;117 14:21:52 INFO - PROCESS | 8665 | |6;imaging-darkroom;145;146;146;146;146;146;166;146;147;146 14:21:52 INFO - PROCESS | 8665 | |7;imaging-desaturate;95;96;95;95;95;95;96;94;95;94 14:21:52 INFO - PROCESS | 8665 | |8;json-parse-financial;51;51;52;51;52;51;58;51;51;51 14:21:52 INFO - PROCESS | 8665 | |9;json-stringify-tinderbox;59;61;61;60;60;60;60;60;60;61 14:21:52 INFO - PROCESS | 8665 | |10;stanford-crypto-aes;81;82;77;75;75;84;80;82;110;77 14:21:52 INFO - PROCESS | 8665 | |11;stanford-crypto-ccm;133;128;117;119;120;130;119;119;120;119 14:21:52 INFO - PROCESS | 8665 | |12;stanford-crypto-pbkdf2;161;154;154;155;155;159;155;157;154;153 14:21:52 INFO - PROCESS | 8665 | |13;stanford-crypto-sha256-iterative;62;61;61;61;61;61;60;62;61;60 14:21:52 INFO - PROCESS | 8665 | __end_tp_report 14:21:52 INFO - PROCESS | 8665 | __start_cc_report 14:21:52 INFO - PROCESS | 8665 | _x_x_mozilla_cycle_collect,1597 14:21:52 INFO - PROCESS | 8665 | __end_cc_report 14:21:52 INFO - PROCESS | 8665 | __startTimestamp1465507312558__endTimestamp 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | ------- Summary: start ------- 14:21:52 INFO - PROCESS | 8665 | Number of tests: 14 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:1.97 (1.8%) stddev-sans-first:2.06 14:21:52 INFO - PROCESS | 8665 | Values: 111.0 105.0 111.0 109.0 110.0 111.0 112.0 111.0 111.0 110.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:140.50 stddev:19.65 (14.0%) stddev-sans-first:20.11 14:21:52 INFO - PROCESS | 8665 | Values: 154.0 123.0 125.0 174.0 124.0 127.0 155.0 125.0 162.0 124.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.94 (1.2%) stddev-sans-first:2.06 14:21:52 INFO - PROCESS | 8665 | Values: 161.0 160.0 160.0 160.0 161.0 159.0 162.0 161.0 160.0 166.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#3] audio-fft Cycles:10 Average:80.50 Median:75.00 stddev:12.52 (16.7%) stddev-sans-first:13.06 14:21:52 INFO - PROCESS | 8665 | Values: 74.0 75.0 75.0 110.0 75.0 76.0 75.0 74.0 97.0 74.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:84.50 stddev:16.33 (19.3%) stddev-sans-first:16.94 14:21:52 INFO - PROCESS | 8665 | Values: 85.0 84.0 118.0 85.0 84.0 85.0 117.0 84.0 85.0 120.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 14:21:52 INFO - PROCESS | 8665 | Values: 117.0 117.0 116.0 116.0 116.0 117.0 117.0 116.0 116.0 117.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:146.00 stddev:6.34 (4.3%) stddev-sans-first:6.63 14:21:52 INFO - PROCESS | 8665 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 166.0 146.0 147.0 146.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 14:21:52 INFO - PROCESS | 8665 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 94.0 95.0 94.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:51.00 stddev:2.18 (4.3%) stddev-sans-first:2.29 14:21:52 INFO - PROCESS | 8665 | Values: 51.0 51.0 52.0 51.0 52.0 51.0 58.0 51.0 51.0 51.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.50 14:21:52 INFO - PROCESS | 8665 | Values: 59.0 61.0 61.0 60.0 60.0 60.0 60.0 60.0 60.0 61.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:80.50 stddev:10.22 (12.7%) stddev-sans-first:10.83 14:21:52 INFO - PROCESS | 8665 | Values: 81.0 82.0 77.0 75.0 75.0 84.0 80.0 82.0 110.0 77.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#11] stanford-crypto-ccm Cycles:10 Average:122.40 Median:120.00 stddev:5.66 (4.7%) stddev-sans-first:4.52 14:21:52 INFO - PROCESS | 8665 | Values: 133.0 128.0 117.0 119.0 120.0 130.0 119.0 119.0 120.0 119.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.70 Median:155.00 stddev:2.54 (1.6%) stddev-sans-first:1.83 14:21:52 INFO - PROCESS | 8665 | Values: 161.0 154.0 154.0 155.0 155.0 159.0 155.0 157.0 154.0 153.0 14:21:52 INFO - PROCESS | 8665 | 14:21:52 INFO - PROCESS | 8665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 14:21:52 INFO - PROCESS | 8665 | Values: 62.0 61.0 61.0 61.0 61.0 61.0 60.0 62.0 61.0 60.0 14:21:52 INFO - PROCESS | 8665 | -------- Summary: end -------- 14:21:52 INFO - PROCESS | 8665 | 14:21:53 INFO - TEST-INFO | 8665: exit 0 14:21:53 INFO - TEST-OK | kraken | took 60056ms 14:21:53 INFO - SUITE-END | took 1172s 14:21:53 INFO - Completed test suite (00:19:32) 14:21:53 INFO - javascript benchmark 14:21:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3363.7645364824457, 3392.525046493491, 3393.372226138164, 3420.4014297497934, 3441.7762845415045, 5567.248421463107, 5629.437056294369, 5630.465105814941, 5638.295213098363, 5650.3219613646315, 5634.943650563493, 5691.601587888849, 5705.657660540371, 5708.08670612702, 5711.514521265687, 4998.750062496879, 5057.519535644126, 5145.0, 5166.948330516694, 5199.844004679866, 5058.0, 5098.490150984909, 5108.0, 5126.615503837208, 5173.560247378978, 5120.820771273006, 5145.922811157836, 5215.869603259911, 5215.895682086356, 5217.321748172732, 5131.179011358202, 5141.048905952411, 5189.481051894818, 5208.791648334081, 5213.035588416136, 4939.110960027194, 5045.066662667418, 5071.695698258116, 5080.034793389244, 5125.82059627915, 5574.58190635704, 5597.188407680904, 5597.496225339739, 5620.831375058755, 5624.21261023457, 5748.482636562688, 6074.976289122938, 6078.0668218499495, 6100.816975490742, 6107.572469927107, 6011.879762404771, 6082.361352058015, 6100.572959892809, 6127.050307202391, 6148.200733904609, 5768.077107662796, 5772.0476121439915, 5773.451522105393, 5774.595778295521, 5776.046952252916, 6028.9397106029255, 6067.271927368744, 6226.813195604139, 6241.78153764616, 6242.687865606701, 5878.882422351529, 5927.9703601481715, 5955.642661440327, 5959.880802383928, 5981.102834574762, 6041.395860413923, 6044.637321760709, 6046.516278697693, 6060.878782424327, 6081.391860813883, 5843.2403787507355, 5886.411358864165, 5891.263592050994, 5987.880242395127, 5988.610740301867, 5908.645481271137, 5944.138099975479, 5950.464458198783, 5952.464278214895, 5978.222831031938, 4484.304932735431, 4554.863354099382, 4567.588916997486, 4569.908601828011, 4601.148787474261, 4027.436158937751, 4083.4487344208746, 4093.836246550164, 4096.0, 4132.462779838602, 5758.1650660654805, 5775.249217601685, 5779.797707080232, 5788.247527821356, 5789.334226563915, 6091.1987256073635, 6092.329843717188, 6099.207103076572, 6102.786402475892, 6137.693115344304, 5575.4424557543925, 5739.512141467939, 5745.88508229833, 5754.136879468029, 5858.062709966385, 5249.501297376743, 5265.21021846726, 5267.525922666901, 5271.472852714698, 5281.313429254173, 6108.389161083945, 6345.809625711236, 6351.079093531504, 6368.713407896656, 6370.0, 5958.672273025025, 6008.218931538872, 6017.939820601913, 6065.8180254592435, 6088.969555152196, 6212.378762123752, 6214.875702485834, 6239.407256310825, 6243.406876346739, 6245.531585131224, 5859.296884373903, 5921.3190483094995, 5931.584789064724, 5937.2578427696535, 5981.850453738692, 5998.850028749316, 6042.667653279112, 6067.029275315928, 6072.483838873745, 6118.0, 5948.970255148696, 5959.22530071088, 5961.582689211887, 5961.91057134152, 5963.4632883039, 5772.567057470623, 5786.132080188006, 5931.436513531207, 5943.1679564861815, 5946.524278057832, 5670.8015219467125, 5756.366799651951, 5760.020796464695, 5763.366029736641, 5794.391588883275, 5916.201312822714, 5927.644341339534, 5962.16529685853, 5965.552583556164, 5974.9103763444455, 5761.279840019997, 5778.797742079007, 5812.099124635688, 5824.970875145597, 5829.912551311649], "value": 5573.528917195628, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.9547783916205, 652.8237375908508, 655.2661019657984, 666.4768156996759, 672.7712577723574, 33353.83323083384, 46832.53167468328, 48194.75902620482, 48389.51610483903, 48791.75604121974, 48973.51026489734, 49611.25583116256, 49914.50085499153, 50012.249816252785, 50207.0, 8455.112213217615, 8918.866217006751, 9137.451752894847, 9138.406003609778, 10790.622328218515, 10535.157187425026, 10963.671089867317, 10981.505832237528, 10991.505382257777, 11002.284851484668, 10950.178736594748, 10960.177986650993, 11049.17131215158, 11051.447427628586, 11059.0, 8488.66045358188, 8590.226879580869, 8697.130286971316, 10746.946265268623, 10946.288491248084, 10573.841392379121, 10985.340879547173, 10993.670189894316, 11007.0, 11011.559537618487, 45806.31290530645, 46197.76901115506, 46252.53747462516, 46267.537324626996, 46416.76791616054, 3593.6226696197045, 3641.1443310821937, 3670.7797532148156, 3960.485136932209, 4323.373110898933, 35066.6493335066, 35684.0, 35729.2233363445, 35735.82132089323, 35986.460203096976, 4541.318802179667, 4622.075584883003, 4639.0, 4682.6019788318035, 4729.574338309534, 259675.0, 260810.0, 261872.0, 378546.0, 381156.0, 35920.640793592254, 37037.0, 66102.66948665322, 66764.0, 66958.66520667463, 39223.0, 39466.0, 39476.407853882214, 39507.0, 39542.2091558173, 16813.91593042027, 16832.663346732996, 16869.91565042167, 16904.915475422546, 16948.322067117424, 12872.292023938586, 13861.86138138626, 13892.513762018483, 13892.93053534726, 14271.500497482746, 9083.68207112748, 9125.224355929688, 9150.267978561833, 9169.679061232824, 9212.0, 3432.5366075579477, 3440.3807314683095, 3483.4774783782636, 3524.488949102366, 3532.9823350883594, 1029.7219750667277, 1070.662741236508, 1092.5029111754134, 1111.127764704703, 1142.1091548592112, 34297.82851085729, 34947.12632184216, 35015.0, 35214.29571408557, 35267.0, 1134.0983917785381, 1139.8176291793272, 1177.2877409167502, 1266.4111188297486, 1286.4275397448046, 17924.55188620295, 18054.18756155976, 18120.818791812177, 18179.0, 18181.45455636333, 631802.0, 638113.0, 638791.0, 640709.0, 643480.0, 63303.36696632975, 64836.0, 65019.34980650227, 65059.34940650533, 65171.0, 1379.9931000344934, 1472.6244807574, 1478.5490425420135, 1478.7264356094156, 1506.9321880515183, 33385.165370864954, 34147.4877876837, 34856.47715284222, 34948.30103397969, 34997.0, 54448.18327725008, 54592.454075458736, 54866.725666371414, 54995.72502137624, 55656.16515752186, 417735.0, 419163.0, 419545.0, 420586.0, 421926.0, 27153.456930860877, 28136.43727125487, 31066.378672426876, 31149.844250779508, 31942.840285798422, 52053.7397313011, 52098.0, 52313.21530176974, 52543.47456525539, 52558.47441525536, 42237.57762422336, 42839.57160428481, 43524.56475435205, 44033.339499906884, 44143.77928110339, 42831.3575296377, 43651.78174109236, 43732.0, 44146.0, 44537.331940020274, 43202.0, 43465.78267108644, 44154.337684935395, 44408.11183776371, 44706.55293447024, 37249.44125838169, 38503.0, 38914.4162837552, 39087.41368879412, 39938.2012359757, 29120.56319155172, 29241.415171696874, 29263.268418289714, 29308.57893711355, 29501.557476638296], "value": 20412.414746473605, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.0, 407.58426405066814, 413.08295583803965, 425.6722323810666, 425.7168982626554, 14613.561593152195, 18196.272149114036, 19511.317103901365, 19546.511337216576, 20233.291834785785, 18534.629307413845, 19273.229070837184, 19398.321058762947, 19729.506762330948, 20592.588148237028, 5061.39263288405, 5184.1186998210205, 5184.688918664892, 5189.844304670847, 5204.375474942994, 3865.8260378283044, 5091.210862316347, 5135.3067335909545, 5137.717425541594, 5139.897202055957, 5407.675539467645, 5618.943810561904, 5664.858378540549, 5760.0495918173665, 5764.221830052931, 5678.261825962619, 5700.942990570083, 5708.400617935098, 6304.653244071575, 6613.537052406334, 5835.299764028301, 5912.408759124096, 5931.258592675916, 5970.552208584373, 5971.641701497924, 12876.227426354355, 20124.195032198695, 22486.775132248633, 23670.053197872065, 23715.644265336035, 2420.5279970405777, 2426.1629737740627, 2811.8031737778365, 2952.660444048919, 3060.127863558875, 9974.80050398988, 10152.23858210637, 10229.641962531276, 13374.197548147142, 13390.263535505543, 1978.6042791441769, 2000.1099510717736, 2015.254355888316, 2025.1089520610883, 2056.208359781495, 77069.61465192639, 78539.60730196313, 79069.0, 83418.58290708569, 87753.0, 8689.782755431164, 9250.398724083048, 9696.466694331879, 10685.465726713634, 10707.625233116803, 14422.783658245135, 14471.421143154155, 14482.275886205647, 14520.419183232552, 14527.709445811235, 8263.338932885355, 8472.576371181416, 8551.84550085743, 8620.870686939701, 8628.913710862811, 8431.32549396059, 8487.10885357041, 8495.532745698922, 8652.04827468978, 8656.39405241627, 4179.623833854967, 4231.280682284019, 4313.676474264443, 4449.844255451108, 4574.6569007323915, 1857.647047061054, 1949.2592814730313, 2015.0932080563512, 2051.98974005129, 2136.9679454808193, 912.7444315591645, 1020.260311274335, 1036.896310368972, 1066.941318227505, 1108.9667309980714, 19860.801391985897, 19863.30478433248, 19900.402987910387, 20120.798792011894, 20286.492837679176, 1017.5522769981333, 1020.6784862768204, 1032.746976990642, 1062.6387028410377, 1062.6440142552176, 11011.28426652265, 11224.494897729623, 11306.94346528262, 11353.091752659933, 11627.825582616268, 464096.0, 487525.0, 493380.0, 494489.0, 495456.0, 99215.0, 100614.49692751636, 101306.0, 101523.0, 101649.49175254077, 1042.1454407386022, 1053.773438710681, 1058.687687132298, 1063.1335461598771, 1077.7413420778958, 17290.91354543219, 17313.22090505905, 17851.642967140844, 18072.277108915496, 18133.184006719992, 31765.68234317627, 41006.58993410028, 41268.0, 41269.38095928504, 41394.37908431316, 53841.73079134579, 54551.45448545464, 59095.0, 59279.0, 59377.70311148417, 7682.923170768444, 7774.805629859299, 7943.126256111707, 8099.14958929304, 8316.251537361666, 11982.760344793229, 13583.52457663977, 13719.519816806363, 13932.442702291795, 14066.859331406555, 11575.131865109983, 11900.166988310735, 11915.285082895054, 12215.022798175947, 12367.257964522158, 11552.711182220512, 11557.30656160633, 11963.581274655346, 12229.571964981184, 12520.123591348882, 12352.876471235173, 12634.115611907078, 12690.87309126897, 12715.618531444072, 12932.676683082998, 8110.31062359706, 8176.100628930932, 8265.214804593554, 8267.917320826955, 8365.707200247962, 12460.501579936701, 12582.87417125817, 12592.496300147892, 12677.746445070863, 12717.173383730027], "value": 9298.181644157425, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.3446580107359, 1587.944421945232, 1606.4136590144597, 1807.466797294798, 1935.3903520391075, 8982.640694372225, 9301.90698093019, 9344.205742511895, 9616.471094089822, 9636.469994150319, 9292.117248861365, 9549.904500955006, 9569.760755981104, 9574.2340612751, 9585.856212156823, 2189.704389907366, 2243.0242844362756, 2247.741509726386, 2435.610302351624, 2473.88867500962, 2378.108209421467, 2444.9877750611313, 2446.8164887633498, 2455.373879660684, 2462.8645424501647, 2400.8079353651665, 2402.4834660547976, 2420.9273721788286, 2425.417899704067, 2439.658447817307, 2155.3964889830872, 2218.500837311608, 2506.498700259946, 2543.5040167167504, 2546.2234018624313, 2153.2678889177755, 2367.0, 2428.0409238350753, 2428.623563347684, 2441.07239249086, 9672.75818104546, 9728.708138755848, 9738.464384458852, 9745.90254097457, 9759.0, 5507.476789704971, 5642.351129620105, 5659.915101273484, 5663.0655941769555, 5664.40523745005, 3535.540379750616, 3654.250878569887, 3688.1517251032146, 3698.1863989922176, 3699.5005674233903, 1220.1641875315438, 1269.2574843716345, 1273.7643535945879, 1280.5262053040435, 1282.0576875996135, 14231.857681423156, 14289.071210371292, 14294.499692510712, 14304.928475357556, 14318.71362572753, 4846.127697014501, 4868.756562171877, 4892.021595680878, 4919.3604831371695, 4944.381952255968, 11806.291622502677, 11823.231489953125, 11838.644840654793, 11838.644840654793, 11863.822042669366, 5654.180143879197, 5696.3733989261145, 5704.828855134353, 5716.857078572986, 5718.971405143031, 4559.817607295671, 5149.356330458692, 5192.065428222956, 5244.239585260192, 5323.893522129536, 4218.093109981369, 4222.4299719538085, 4268.210381079511, 4308.612224899774, 4313.374560688745, 1289.5884695619065, 1305.1190446448609, 1310.33173081729, 1312.9080964332404, 1313.382710126239, 887.5384799904014, 898.3486971945288, 1043.5564884924029, 1086.4187659602076, 1094.7536804219146, 6455.096286519891, 6503.512236582275, 6504.1869766279215, 6591.670416479157, 6618.238902526175, 990.1781521337273, 1063.1813503602184, 1072.6460268111505, 1082.2099867097063, 1090.547422819537, 4526.388937493462, 4575.816967321336, 4609.769511524478, 4637.8840528986375, 4686.671932964659, 12166.75666486683, 12550.309732964592, 12604.180728252633, 12627.810582841264, 12646.0, 11694.122940779476, 12047.81928271059, 12116.939415302868, 12470.750584988431, 12497.687557810945, 8518.74443766688, 8638.697645582375, 8763.211310981915, 8827.382083254111, 8832.337574681796, 6659.0, 6708.0, 6747.527673062838, 6852.349026842442, 6876.106106206161, 11168.832467513157, 11313.886861131612, 11317.943410282896, 11366.374849383363, 11495.0, 11315.434228288717, 11627.534898604299, 11641.185117041725, 11664.066874649838, 11669.299842009506, 7650.0, 8239.505629662, 8492.065872753868, 8531.701390451304, 8607.741767746978, 9494.525273736148, 9519.762005949906, 9521.095495927875, 9575.90424095778, 9584.712458626253, 8346.79133021655, 8358.66565337404, 8504.064552899052, 8519.787005324668, 8566.78583035429, 8136.1863813618165, 8196.959015205124, 8204.261616454547, 8360.205780450848, 8377.790555236168, 7788.415868809749, 7933.563653999086, 7966.645670236189, 7980.800479988046, 7983.84032319362, 5489.862753431036, 5514.779408823602, 5579.972100139636, 5680.375158732619, 5705.258316419005, 5292.841214763564, 5603.747831347518, 5634.859128521656, 5644.012297847942, 5776.480116789509], "value": 5045.781214801328, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.96922276995065, 347.66797708188665, 349.758666520101, 353.3339654750795, 356.8572570971611, 5527.861803454911, 5534.972325138378, 5535.335759708835, 5553.111502159655, 5630.127330263806, 5437.265969094171, 5492.286002819628, 5501.669899806015, 5512.862178445531, 5521.917171242435, 3934.0, 4110.691698122645, 4260.808263628144, 4266.357452380825, 4303.203907277148, 4283.271843786564, 4311.116221174651, 4314.71954322968, 4315.5900189481945, 4329.67527435442, 4134.586541345856, 4295.7852107394665, 4305.246581848164, 4307.160103779748, 4326.956730432703, 4137.420761093449, 4262.403263543091, 4297.441332626739, 4297.742135471851, 4312.0, 3791.658750712449, 3792.544894612664, 3800.1259710266518, 3803.182315802088, 3805.562360328542, 5305.257263983045, 5316.680999140064, 5356.35723713153, 5372.7313634318125, 5386.326709161355, 4952.380952380952, 4957.975210123926, 4959.181735013718, 4962.255661650745, 4962.751862406865, 5523.558115350762, 5540.196671482612, 5682.232898558683, 5723.370429252779, 5788.768449262025, 4093.792331262255, 4176.352665336878, 4177.642266263446, 4193.448424083109, 4202.894927626803, 5924.940750592526, 5929.902967950899, 5931.576421658814, 5940.851478713023, 5948.7620495180145, 4027.9798601006805, 4217.873463796091, 4224.8732538023905, 4243.0, 4245.745255284693, 4282.743035417885, 4289.056407590325, 4298.4197133387215, 4307.56924307573, 4307.763073030951, 4887.29134275528, 4891.364122664102, 4907.975460122748, 4908.435529914034, 4923.335349727741, 5524.309461317335, 5753.6985205917135, 5810.1865738796605, 5816.709164541756, 5858.648481091148, 1470.5588523247063, 1487.256371814093, 1490.4038384646228, 1495.297210311152, 1497.423491955605, 924.1543987251589, 942.6936245720168, 948.5921053946731, 950.5864948747317, 951.8815391914472, 5022.735222105587, 5025.020121076355, 5067.771950262247, 5076.466970968069, 5084.237364395296, 5251.238570407269, 5256.710880901587, 5265.4730128263145, 5268.341457317835, 5308.221745715152, 5071.619628527875, 5073.909109541466, 5076.822311219164, 5322.3879253885525, 5791.681457519877, 3163.5254711793414, 3175.412548678502, 3193.5209718542405, 3204.4872820348633, 3222.758293128025, 6030.379496313871, 6066.99789069606, 6088.726007329681, 6094.664793436404, 6123.663198524124, 5910.468057874726, 6046.7278972445465, 6058.21243238385, 6072.666003369769, 6075.0, 5851.180834683148, 5987.311459182162, 6081.753240585669, 6115.938840611626, 6162.784302549389, 5893.351731309638, 5944.346121926671, 5961.046232602763, 5997.13041608973, 6027.78902738402, 5643.661380317194, 5865.94134058654, 5919.171316015675, 5920.348761636303, 5976.905819513647, 6140.846478838065, 6166.242620853215, 6184.350643182401, 6199.56603037783, 6218.657973811303, 4249.32010878258, 4273.124009577967, 4279.229738647074, 4281.850135245251, 4290.935635965526, 5832.24180856486, 5947.851303717442, 5949.19685842423, 5966.880662386815, 6021.157038014769, 5777.422257774189, 5800.796972105956, 5854.648721076749, 5858.882822343615, 5860.824175274749, 5587.832365029056, 5847.508885234408, 5856.180134781049, 5859.677717725566, 5884.7940322088525, 5691.630044047124, 5701.971490142523, 5721.284839395075, 5746.012073721725, 5746.557712972054, 5852.034414321589, 5886.411358864079, 5896.6756828374855, 5903.586748927534, 5916.89363084367, 5605.523530499953, 5793.754342816314, 5807.054636696754, 5823.650580965155, 5827.4172582741385], "value": 4382.773603044223, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.532790981231, 814.991850081499, 828.382854773194, 859.3426029087748, 885.3050355470955, 15059.322330495126, 16154.111523866182, 16215.189240537984, 16239.0, 16249.187540622966, 14187.858121418809, 14225.359858806352, 14318.856811431908, 14320.785188222186, 14365.569032929003, 706.1455638677206, 807.9717209897655, 857.9184977427135, 860.4708104515709, 874.8031692869085, 870.7213691618684, 873.1966590736506, 875.5797217335682, 876.5441970175536, 879.2350654930187, 900.8693739407782, 905.0858632780872, 911.9133682300171, 913.3241401362977, 915.8671992561075, 753.3596443023441, 892.6786356911539, 912.6258234123977, 913.6893456224882, 918.4764684130049, 659.5679829711498, 660.735705717712, 662.4468568745103, 666.3436515032689, 681.3459079283894, 14313.0, 14392.136471811726, 14395.0, 14396.712065758731, 14440.783388249185, 14374.20941848198, 15088.094714317067, 15470.226488675522, 15632.296546655427, 15892.52322430329, 13125.408050874972, 13402.12886162406, 13443.32783360828, 13611.931940340333, 13759.79360309586, 4682.648801339913, 4715.811367545294, 4753.215719406294, 4763.285507173917, 4896.730679812609, 77545.22454775436, 77895.61052194703, 80323.19676803157, 81177.0, 81975.18024819795, 11579.247348922294, 11682.532698692126, 11713.472893719803, 11787.351695656822, 11929.582464613697, 21145.68281475779, 21695.0, 21700.131994720352, 21803.563928721338, 21804.0, 8395.202455766692, 8457.112003239694, 8481.830363392697, 8655.437396569203, 8719.825603488021, 4534.79593418297, 4604.677672562889, 4697.295405689106, 4711.199096153662, 4723.055388922229, 3527.3121741260206, 3611.331903597843, 3614.168741189541, 3626.9818650906577, 3637.399829028234, 645.2676212498848, 653.9803805885831, 667.9699413526403, 688.2360579756466, 702.0978043214445, 642.7043559962376, 742.7177672484421, 806.6208881825532, 812.4231795425314, 818.2308629887887, 9537.618495260112, 9674.75813104678, 9730.75673108178, 9786.657466988763, 9840.704778856645, 657.4510283912987, 673.3434900971514, 743.130537271383, 755.6977209116287, 784.6822037074879, 4763.785629646674, 4850.5149485051215, 4853.150698627796, 4910.779014944336, 4964.627652926045, 73138.26861731421, 74341.25658743452, 74684.62657686677, 75223.62388188024, 100271.49864250778, 94159.52920235493, 95256.52371738236, 95926.0, 96035.51982240044, 96907.0, 88299.0, 90472.0, 90583.0, 91498.08501915028, 91912.0, 9966.40201587907, 9972.401655900669, 10249.487525623837, 10362.378257304586, 10414.0, 52025.21962170495, 52946.0, 53357.0, 53905.46094539004, 54268.45731542634, 69469.0, 76828.0, 77242.0, 77248.6137569331, 77534.612326938, 13003.634618364935, 13024.934875325564, 13339.399727012114, 13435.462581496631, 13798.655033623838, 14962.925185374439, 15635.76546351783, 16001.839981600035, 16457.91771041137, 16563.91718041402, 13985.930070349583, 14199.929000355345, 14266.358013889192, 14272.714545709236, 15094.320755565806, 14252.572422827332, 14620.561383158521, 14793.630159246104, 14857.925710371379, 15273.770893436385, 13522.12106213093, 13653.385597647932, 14021.0, 14046.367913444124, 14243.572692819233, 12024.639260822189, 12165.81751273714, 12172.0, 12322.383880805744, 12431.813522796985, 12061.276323420623, 12478.126531143096, 12496.312702801433, 12726.872731272939, 12733.808992865299], "value": 7820.8678053563, "unit": "score"}], "name": "dromaeo_css", "value": 7534.700075327156, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 111.0, 109.0, 110.0, 111.0, 112.0, 111.0, 111.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 125.0, 174.0, 124.0, 127.0, 155.0, 125.0, 162.0, 124.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 161.0, 159.0, 162.0, 161.0, 160.0, 166.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 110.0, 75.0, 76.0, 75.0, 74.0, 97.0, 74.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 118.0, 85.0, 84.0, 85.0, 117.0, 84.0, 85.0, 120.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 166.0, 146.0, 147.0, 146.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 51.0, 52.0, 51.0, 58.0, 51.0, 51.0, 51.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 77.0, 75.0, 75.0, 84.0, 80.0, 82.0, 110.0, 77.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 128.0, 117.0, 119.0, 120.0, 130.0, 119.0, 119.0, 120.0, 119.0], "value": 122.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 154.0, 154.0, 155.0, 155.0, 159.0, 155.0, 157.0, 154.0, 153.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 62.0, 61.0, 60.0], "value": 61.0, "unit": "score"}], "name": "kraken", "value": 1478.6000000000001, "alertThreshold": 2.0}]} 14:21:53 INFO - Return code: 0 14:21:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:21:53 ERROR - # TBPL SUCCESS # 14:21:53 INFO - Running post-action listener: _resource_record_post_action 14:21:53 INFO - ##### 14:21:53 INFO - ##### Finished run-tests step (success) 14:21:53 INFO - ##### 14:21:53 INFO - Running post-run listener: _resource_record_post_run 14:21:53 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 505688064; Read time: 4; Write time: 354364 14:21:53 INFO - TinderboxPrint: CPU usage
13.1% 14:21:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 14:21:53 INFO - TinderboxPrint: I/O write bytes / time
505,688,064 / 354,364 14:21:53 INFO - TinderboxPrint: CPU idle
8,255.6 (86.9%) 14:21:53 INFO - TinderboxPrint: CPU user
1,197.4 (12.6%) 14:21:53 INFO - TinderboxPrint: Swap in / out
0 / 0 14:21:53 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252657664; Read time: 0; Write time: 233412 14:21:53 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 253030400; Read time: 4; Write time: 120952 14:21:54 INFO - Running post-run listener: _upload_blobber_files 14:21:54 INFO - Blob upload gear active. 14:21:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:21:54 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 14:21:54 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'] 14:21:54 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 14:21:54 INFO - (blobuploader) - INFO - Open directory for files ... 14:21:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:21:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:21:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:58 INFO - (blobuploader) - INFO - Done attempting. 14:21:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:21:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:21:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:59 INFO - (blobuploader) - INFO - Done attempting. 14:21:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:21:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:21:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:59 INFO - (blobuploader) - INFO - Done attempting. 14:21:59 INFO - (blobuploader) - INFO - Iteration through files over. 14:21:59 INFO - Return code: 0 14:21:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:21:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:21:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b7198a98f261359e258d9bec263020567613afbcc277de3c4d4f35c303a81219cc970542252e2379baef403d3b05ce8ac1c9a92e5f026892d2e4f6ba25d8416", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e8d38b42e4750a24c3544a5158592a7697b6f1a7d0e28ca357acc96594106036c3b11dbb9a8456ac08935356e10d2a0fc36c7b866aeca4b8c754c933a766c11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e9bff668c29901183fe5d860fdcd5766db7d7dfc42a086daa8d01351eeb888993279341742f5e139fcbe468cae3735e633ed9fe5c5b4988361ec11a16787e56"} 14:21:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:21:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:21:59 INFO - Contents: 14:21:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b7198a98f261359e258d9bec263020567613afbcc277de3c4d4f35c303a81219cc970542252e2379baef403d3b05ce8ac1c9a92e5f026892d2e4f6ba25d8416", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e8d38b42e4750a24c3544a5158592a7697b6f1a7d0e28ca357acc96594106036c3b11dbb9a8456ac08935356e10d2a0fc36c7b866aeca4b8c754c933a766c11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e9bff668c29901183fe5d860fdcd5766db7d7dfc42a086daa8d01351eeb888993279341742f5e139fcbe468cae3735e633ed9fe5c5b4988361ec11a16787e56"} 14:21:59 INFO - Running post-run listener: copy_logs_to_upload_dir 14:21:59 INFO - Copying logs to upload dir... 14:21:59 INFO - mkdir: /builds/slave/test/build/upload/logs 14:21:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.453704 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-06-09 14:21:59.717988) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 14:21:59.721904) ========= 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=8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595 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/0b7198a98f261359e258d9bec263020567613afbcc277de3c4d4f35c303a81219cc970542252e2379baef403d3b05ce8ac1c9a92e5f026892d2e4f6ba25d8416", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e8d38b42e4750a24c3544a5158592a7697b6f1a7d0e28ca357acc96594106036c3b11dbb9a8456ac08935356e10d2a0fc36c7b866aeca4b8c754c933a766c11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e9bff668c29901183fe5d860fdcd5766db7d7dfc42a086daa8d01351eeb888993279341742f5e139fcbe468cae3735e633ed9fe5c5b4988361ec11a16787e56"} build_url:https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011415 build_url: 'https://queue.taskcluster.net/v1/task/CroygOq3Qdmv-C0FzBWfcQ/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/0b7198a98f261359e258d9bec263020567613afbcc277de3c4d4f35c303a81219cc970542252e2379baef403d3b05ce8ac1c9a92e5f026892d2e4f6ba25d8416", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e8d38b42e4750a24c3544a5158592a7697b6f1a7d0e28ca357acc96594106036c3b11dbb9a8456ac08935356e10d2a0fc36c7b866aeca4b8c754c933a766c11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e9bff668c29901183fe5d860fdcd5766db7d7dfc42a086daa8d01351eeb888993279341742f5e139fcbe468cae3735e633ed9fe5c5b4988361ec11a16787e56"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 14:21:59.751175) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 14:21:59.751512) ========= 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=8887e36bb8e381bfd44124ac000001a8-1465506086.335054-1388780595 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004571 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 14:21:59.803043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 14:21:59.803356) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 14:21:59.803692) ========= ========= Total master_lag: 0.16 =========