builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-035 starttime: 1468438801.02 results: success (0) buildid: 20160713120543 builduid: 229418782baf436c95a2bc65ed60fd80 revision: 3e3b1eb03a908d02dac0bcb25c93eb8b734b5124 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.024766) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.025560) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.025849) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.041523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.041781) ========= 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=ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-13 12:40:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-07-13 12:40:01 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125940 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.181339) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.181664) ========= 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=ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014098 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.231681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.232024) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3e3b1eb03a908d02dac0bcb25c93eb8b734b5124 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 3e3b1eb03a908d02dac0bcb25c93eb8b734b5124 --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=ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-13 12:40:01,507 truncating revision to first 12 chars 2016-07-13 12:40:01,507 Setting DEBUG logging. 2016-07-13 12:40:01,507 attempt 1/10 2016-07-13 12:40:01,507 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3e3b1eb03a90?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 12:40:01,835 unpacking tar archive at: fx-team-3e3b1eb03a90/testing/mozharness/ program finished with exit code 0 elapsedTime=0.643369 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.889403) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.889720) ========= script_repo_revision: 3e3b1eb03a908d02dac0bcb25c93eb8b734b5124 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.890119) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.890426) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 12:40:01.901127) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-13 12:40:01.901542) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:40:02 INFO - MultiFileLogger online at 20160713 12:40:02 in /builds/slave/test 12:40:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 12:40:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:40:02 INFO - {'append_to_log': False, 12:40:02 INFO - 'base_work_dir': '/builds/slave/test', 12:40:02 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 12:40:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:40:02 INFO - 'branch': 'Fx-Team-Non-PGO', 12:40:02 INFO - 'buildbot_json_path': 'buildprops.json', 12:40:02 INFO - 'config_files': ('talos/linux_config.py',), 12:40:02 INFO - 'default_actions': ('clobber', 12:40:02 INFO - 'read-buildbot-config', 12:40:02 INFO - 'download-and-extract', 12:40:02 INFO - 'populate-webroot', 12:40:02 INFO - 'create-virtualenv', 12:40:02 INFO - 'install', 12:40:02 INFO - 'run-tests'), 12:40:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:40:02 INFO - 'download_minidump_stackwalk': True, 12:40:02 INFO - 'download_symbols': 'ondemand', 12:40:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:40:02 INFO - 'tooltool.py': '/tools/tooltool.py', 12:40:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:40:02 INFO - '/tools/misc-python/virtualenv.py')}, 12:40:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:40:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:40:02 INFO - 'installer_path': 'installer.exe', 12:40:02 INFO - 'log_level': 'info', 12:40:02 INFO - 'log_name': 'talos', 12:40:02 INFO - 'log_to_console': True, 12:40:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:40:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:40:02 INFO - 'opt_config_files': (), 12:40:02 INFO - 'pip_index': False, 12:40:02 INFO - 'sps_profile': False, 12:40:02 INFO - 'sps_profile_interval': 0, 12:40:02 INFO - 'suite': 'dromaeojs', 12:40:02 INFO - 'system_bits': '32', 12:40:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:40:02 INFO - 'title': 'talos-linux64-ix-035', 12:40:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:40:02 INFO - 'use_talos_json': True, 12:40:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:40:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:40:02 INFO - 'work_dir': 'build'} 12:40:02 INFO - ##### 12:40:02 INFO - ##### Running clobber step. 12:40:02 INFO - ##### 12:40:02 INFO - Running pre-action listener: _resource_record_pre_action 12:40:02 INFO - Running main action method: clobber 12:40:02 INFO - rmtree: /builds/slave/test/build 12:40:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:40:02 INFO - Running post-action listener: _resource_record_post_action 12:40:02 INFO - ##### 12:40:02 INFO - ##### Finished clobber step (success) 12:40:02 INFO - ##### 12:40:02 INFO - ##### 12:40:02 INFO - ##### Running read-buildbot-config step. 12:40:02 INFO - ##### 12:40:02 INFO - Running pre-action listener: _resource_record_pre_action 12:40:02 INFO - Running main action method: read_buildbot_config 12:40:02 INFO - Using buildbot properties: 12:40:02 INFO - { 12:40:02 INFO - "project": "", 12:40:02 INFO - "product": "firefox", 12:40:02 INFO - "script_repo_revision": "production", 12:40:02 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 12:40:02 INFO - "repository": "", 12:40:02 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 12:40:02 INFO - "buildid": "20160713120543", 12:40:02 INFO - "pgo_build": "False", 12:40:02 INFO - "basedir": "/builds/slave/test", 12:40:02 INFO - "buildnumber": 1988, 12:40:02 INFO - "slavename": "talos-linux64-ix-035", 12:40:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:40:02 INFO - "platform": "ubuntu64_hw", 12:40:02 INFO - "scheduler": "tests-fx-team-linux64-talos", 12:40:02 INFO - "branch": "fx-team", 12:40:02 INFO - "repo_path": "integration/fx-team", 12:40:02 INFO - "revision": "3e3b1eb03a908d02dac0bcb25c93eb8b734b5124", 12:40:02 INFO - "stage_platform": "linux64", 12:40:02 INFO - "builduid": "229418782baf436c95a2bc65ed60fd80", 12:40:02 INFO - "slavebuilddir": "test" 12:40:02 INFO - } 12:40:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 12:40:02 INFO - Running post-action listener: _resource_record_post_action 12:40:02 INFO - ##### 12:40:02 INFO - ##### Finished read-buildbot-config step (success) 12:40:02 INFO - ##### 12:40:02 INFO - ##### 12:40:02 INFO - ##### Running download-and-extract step. 12:40:02 INFO - ##### 12:40:02 INFO - Running pre-action listener: _resource_record_pre_action 12:40:02 INFO - Running main action method: download_and_extract 12:40:02 INFO - mkdir: /builds/slave/test/build/tests 12:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:02 INFO - https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:40:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:40:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:40:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:40:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:40:07 INFO - Downloaded 1361 bytes. 12:40:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:40:07 INFO - Using the following test package requirements: 12:40:07 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:40:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:40:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:40:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:40:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:40:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:40:07 INFO - u'jsshell-linux-x86_64.zip'], 12:40:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:40:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:40:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:40:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:40:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:40:07 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:40:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:40:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:40:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:40:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:40:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:40:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:40:07 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')]} 12:40:07 INFO - https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:40:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:40:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:40:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:40:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:40:08 INFO - Downloaded 22755434 bytes. 12:40:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:40:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:40:10 INFO - Return code: 0 12:40:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:40:10 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')]} 12:40:10 INFO - https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:40:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:40:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:40:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:40:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:40:11 INFO - Downloaded 22755434 bytes. 12:40:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:40:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 12:40:12 INFO - Return code: 0 12:40:12 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')]} 12:40:12 INFO - https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:40:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:40:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:40:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:40:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:40:13 INFO - Downloaded 14403317 bytes. 12:40:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:40:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 12:40:13 INFO - Return code: 0 12:40:13 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')]} 12:40:13 INFO - https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:40:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:40:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:40:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:40:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:40:16 INFO - Downloaded 57050038 bytes. 12:40:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:40:16 INFO - mkdir: /builds/slave/test/properties 12:40:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:40:16 INFO - Writing to file /builds/slave/test/properties/build_url 12:40:16 INFO - Contents: 12:40:16 INFO - build_url:https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:40:17 INFO - Running post-action listener: _resource_record_post_action 12:40:17 INFO - Running post-action listener: set_extra_try_arguments 12:40:17 INFO - ##### 12:40:17 INFO - ##### Finished download-and-extract step (success) 12:40:17 INFO - ##### 12:40:17 INFO - ##### 12:40:17 INFO - ##### Running populate-webroot step. 12:40:17 INFO - ##### 12:40:17 INFO - Running pre-action listener: _resource_record_pre_action 12:40:17 INFO - Running main action method: populate_webroot 12:40:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:40:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:40:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:40:17 INFO - '--tppagecycles', 12:40:17 INFO - '7'], 12:40:17 INFO - 'tests': ['tsvgm']}}, 12:40:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:40:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:40:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:40:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:40:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:17 INFO - 'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:40:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:40:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:17 INFO - 'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['damp', 'tps']}, 12:40:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:17 INFO - 'tests': ['damp', 'tps']}, 12:40:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['dromaeo_dom']}, 12:40:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:40:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['basic_compositor_video']}, 12:40:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:40:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['a11yr', 12:40:17 INFO - 'ts_paint', 12:40:17 INFO - 'tpaint', 12:40:17 INFO - 'sessionrestore', 12:40:17 INFO - 'sessionrestore_no_auto_restore', 12:40:17 INFO - 'tabpaint']}, 12:40:17 INFO - 'other-e10s': {'tests': ['a11yr', 12:40:17 INFO - 'ts_paint', 12:40:17 INFO - 'tpaint', 12:40:17 INFO - 'sessionrestore', 12:40:17 INFO - 'sessionrestore_no_auto_restore', 12:40:17 INFO - 'tabpaint']}, 12:40:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:40:17 INFO - 'ts_paint', 12:40:17 INFO - 'tpaint', 12:40:17 INFO - 'sessionrestore', 12:40:17 INFO - 'sessionrestore_no_auto_restore', 12:40:17 INFO - 'tabpaint']}, 12:40:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:40:17 INFO - 'ts_paint', 12:40:17 INFO - 'tpaint', 12:40:17 INFO - 'sessionrestore', 12:40:17 INFO - 'sessionrestore_no_auto_restore', 12:40:17 INFO - 'tabpaint']}, 12:40:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['a11yr', 12:40:17 INFO - 'ts_paint', 12:40:17 INFO - 'tpaint', 12:40:17 INFO - 'sessionrestore', 12:40:17 INFO - 'sessionrestore_no_auto_restore', 12:40:17 INFO - 'tabpaint']}, 12:40:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['a11yr', 12:40:17 INFO - 'ts_paint', 12:40:17 INFO - 'tpaint', 12:40:17 INFO - 'sessionrestore', 12:40:17 INFO - 'sessionrestore_no_auto_restore', 12:40:17 INFO - 'tabpaint']}, 12:40:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['tsvgx', 12:40:17 INFO - 'tsvgr_opacity', 12:40:17 INFO - 'tart', 12:40:17 INFO - 'tscrollx', 12:40:17 INFO - 'cart']}, 12:40:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:40:17 INFO - 'tsvgr_opacity', 12:40:17 INFO - 'tart', 12:40:17 INFO - 'tscrollx', 12:40:17 INFO - 'cart']}, 12:40:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:17 INFO - 'talos_options': ['--disable-e10s'], 12:40:17 INFO - 'tests': ['tp5o']}, 12:40:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:17 INFO - 'tests': ['tp5o']}, 12:40:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:17 INFO - 'talos_options': ['--disable-e10s', 12:40:17 INFO - '--xperf_path', 12:40:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:40:17 INFO - 'tests': ['tp5n']}, 12:40:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:17 INFO - 'talos_options': ['--xperf_path', 12:40:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:40:17 INFO - 'tests': ['tp5n']}}, 12:40:17 INFO - 'talos.zip': {'path': '', 12:40:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:40:17 INFO - Running post-action listener: _resource_record_post_action 12:40:17 INFO - ##### 12:40:17 INFO - ##### Finished populate-webroot step (success) 12:40:17 INFO - ##### 12:40:17 INFO - ##### 12:40:17 INFO - ##### Running create-virtualenv step. 12:40:17 INFO - ##### 12:40:17 INFO - Running pre-action listener: _resource_record_pre_action 12:40:17 INFO - Running main action method: create_virtualenv 12:40:17 INFO - Creating virtualenv /builds/slave/test/build/venv 12:40:17 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 12:40:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:40:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:40:17 INFO - Using real prefix '/usr' 12:40:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:40:17 INFO - Installing distribute.............................................................................................................................................................................................done. 12:40:19 INFO - Installing pip.................done. 12:40:19 INFO - Return code: 0 12:40:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:19 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 0x17fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, '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': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', '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 12:40:19 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 12:40:19 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 12:40:19 INFO - Using env: {'DISPLAY': ':0', 12:40:19 INFO - 'HOME': '/home/cltbld', 12:40:19 INFO - 'LANG': 'en_US.UTF-8', 12:40:19 INFO - 'LANGUAGE': 'en_US:en', 12:40:19 INFO - 'LOGNAME': 'cltbld', 12:40:19 INFO - 'MAIL': '/var/mail/cltbld', 12:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:19 INFO - 'MOZ_NO_REMOTE': '1', 12:40:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:19 INFO - 'NO_EM_RESTART': '1', 12:40:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:19 INFO - 'PWD': '/builds/slave/test', 12:40:19 INFO - 'SHELL': '/bin/bash', 12:40:19 INFO - 'SHLVL': '1', 12:40:19 INFO - 'TERM': 'linux', 12:40:19 INFO - 'TMOUT': '86400', 12:40:19 INFO - 'USER': 'cltbld', 12:40:19 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:19 INFO - '_': '/tools/buildbot/bin/python'} 12:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:20 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 12:40:20 INFO - Cleaning up... 12:40:20 INFO - Return code: 0 12:40:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:40:20 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')]} 12:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:20 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')]} 12:40:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:20 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 0x17fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, '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': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', '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 12:40:20 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 12:40:20 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 12:40:20 INFO - Using env: {'DISPLAY': ':0', 12:40:20 INFO - 'HOME': '/home/cltbld', 12:40:20 INFO - 'LANG': 'en_US.UTF-8', 12:40:20 INFO - 'LANGUAGE': 'en_US:en', 12:40:20 INFO - 'LOGNAME': 'cltbld', 12:40:20 INFO - 'MAIL': '/var/mail/cltbld', 12:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:20 INFO - 'MOZ_NO_REMOTE': '1', 12:40:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:20 INFO - 'NO_EM_RESTART': '1', 12:40:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:20 INFO - 'PWD': '/builds/slave/test', 12:40:20 INFO - 'SHELL': '/bin/bash', 12:40:20 INFO - 'SHLVL': '1', 12:40:20 INFO - 'TERM': 'linux', 12:40:20 INFO - 'TMOUT': '86400', 12:40:20 INFO - 'USER': 'cltbld', 12:40:20 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:20 INFO - '_': '/tools/buildbot/bin/python'} 12:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:20 INFO - Downloading/unpacking psutil>=3.1.1 12:40:20 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 12:40:20 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 12:40:20 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 12:40:20 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 12:40:20 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 12:40:20 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 12:40:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:40:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:22 INFO - Installing collected packages: psutil 12:40:22 INFO - Running setup.py install for psutil 12:40:22 INFO - building 'psutil._psutil_linux' extension 12:40:22 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 12:40:22 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 12:40:22 INFO - building 'psutil._psutil_posix' extension 12:40:22 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 12:40:22 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 12:40:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:22 INFO - Successfully installed psutil 12:40:22 INFO - Cleaning up... 12:40:22 INFO - Return code: 0 12:40:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:40:22 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')]} 12:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:22 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')]} 12:40:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:22 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 0x17fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, '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': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', '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 12:40:22 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 12:40:22 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 12:40:22 INFO - Using env: {'DISPLAY': ':0', 12:40:22 INFO - 'HOME': '/home/cltbld', 12:40:22 INFO - 'LANG': 'en_US.UTF-8', 12:40:22 INFO - 'LANGUAGE': 'en_US:en', 12:40:22 INFO - 'LOGNAME': 'cltbld', 12:40:22 INFO - 'MAIL': '/var/mail/cltbld', 12:40:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:22 INFO - 'MOZ_NO_REMOTE': '1', 12:40:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:22 INFO - 'NO_EM_RESTART': '1', 12:40:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:22 INFO - 'PWD': '/builds/slave/test', 12:40:22 INFO - 'SHELL': '/bin/bash', 12:40:22 INFO - 'SHLVL': '1', 12:40:22 INFO - 'TERM': 'linux', 12:40:22 INFO - 'TMOUT': '86400', 12:40:22 INFO - 'USER': 'cltbld', 12:40:22 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:22 INFO - '_': '/tools/buildbot/bin/python'} 12:40:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:40:23 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 12:40:23 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 12:40:23 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 12:40:23 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 12:40:23 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 12:40:23 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 12:40:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:40:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:40:25 INFO - Installing collected packages: mozsystemmonitor 12:40:25 INFO - Running setup.py install for mozsystemmonitor 12:40:25 INFO - Successfully installed mozsystemmonitor 12:40:25 INFO - Cleaning up... 12:40:25 INFO - Return code: 0 12:40:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:40:25 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')]} 12:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:25 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')]} 12:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:25 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 0x17fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, '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': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', '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 12:40:25 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 12:40:25 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 12:40:25 INFO - Using env: {'DISPLAY': ':0', 12:40:25 INFO - 'HOME': '/home/cltbld', 12:40:25 INFO - 'LANG': 'en_US.UTF-8', 12:40:25 INFO - 'LANGUAGE': 'en_US:en', 12:40:25 INFO - 'LOGNAME': 'cltbld', 12:40:25 INFO - 'MAIL': '/var/mail/cltbld', 12:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:25 INFO - 'MOZ_NO_REMOTE': '1', 12:40:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:25 INFO - 'NO_EM_RESTART': '1', 12:40:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:25 INFO - 'PWD': '/builds/slave/test', 12:40:25 INFO - 'SHELL': '/bin/bash', 12:40:25 INFO - 'SHLVL': '1', 12:40:25 INFO - 'TERM': 'linux', 12:40:25 INFO - 'TMOUT': '86400', 12:40:25 INFO - 'USER': 'cltbld', 12:40:25 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:25 INFO - '_': '/tools/buildbot/bin/python'} 12:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:25 INFO - Downloading/unpacking jsonschema==2.5.1 12:40:25 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 12:40:25 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 12:40:25 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 12:40:25 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 12:40:25 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 12:40:25 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 12:40:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:40:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:40:27 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 12:40:27 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 12:40:27 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 12:40:27 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 12:40:27 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 12:40:27 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 12:40:27 INFO - Downloading functools32-3.2.3-2.tar.gz 12:40:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:40:27 INFO - warning: no files found matching '*.txt' 12:40:27 INFO - no previously-included directories found matching 'build' 12:40:27 INFO - no previously-included directories found matching 'dist' 12:40:27 INFO - no previously-included directories found matching '.git*' 12:40:27 INFO - Installing collected packages: jsonschema, functools32 12:40:27 INFO - Running setup.py install for functools32 12:40:27 INFO - warning: no files found matching '*.txt' 12:40:27 INFO - no previously-included directories found matching 'build' 12:40:27 INFO - no previously-included directories found matching 'dist' 12:40:27 INFO - no previously-included directories found matching '.git*' 12:40:27 INFO - Successfully installed jsonschema functools32 12:40:27 INFO - Cleaning up... 12:40:27 INFO - Return code: 0 12:40:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:27 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 0x17fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, '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': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', '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 12:40:27 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 12:40:27 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 12:40:27 INFO - Using env: {'DISPLAY': ':0', 12:40:27 INFO - 'HOME': '/home/cltbld', 12:40:27 INFO - 'LANG': 'en_US.UTF-8', 12:40:27 INFO - 'LANGUAGE': 'en_US:en', 12:40:27 INFO - 'LOGNAME': 'cltbld', 12:40:27 INFO - 'MAIL': '/var/mail/cltbld', 12:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:27 INFO - 'MOZ_NO_REMOTE': '1', 12:40:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:27 INFO - 'NO_EM_RESTART': '1', 12:40:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:27 INFO - 'PWD': '/builds/slave/test', 12:40:27 INFO - 'SHELL': '/bin/bash', 12:40:27 INFO - 'SHLVL': '1', 12:40:27 INFO - 'TERM': 'linux', 12:40:27 INFO - 'TMOUT': '86400', 12:40:27 INFO - 'USER': 'cltbld', 12:40:27 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:27 INFO - '_': '/tools/buildbot/bin/python'} 12:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:28 INFO - Downloading/unpacking blobuploader==1.2.4 12:40:28 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 12:40:28 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 12:40:28 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 12:40:28 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 12:40:28 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 12:40:28 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 12:40:29 INFO - Downloading blobuploader-1.2.4.tar.gz 12:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:40:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:40:30 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 12:40:30 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 12:40:30 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 12:40:30 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 12:40:30 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 12:40:30 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 12:40:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:40:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:40:30 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 12:40:30 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 12:40:30 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 12:40:30 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 12:40:30 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 12:40:30 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 12:40:30 INFO - Downloading docopt-0.6.1.tar.gz 12:40:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:40:30 INFO - Installing collected packages: blobuploader, requests, docopt 12:40:30 INFO - Running setup.py install for blobuploader 12:40:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:40:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:40:30 INFO - Running setup.py install for requests 12:40:31 INFO - Running setup.py install for docopt 12:40:31 INFO - Successfully installed blobuploader requests docopt 12:40:31 INFO - Cleaning up... 12:40:31 INFO - Return code: 0 12:40:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:31 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 0x17fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, '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': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', '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 12:40:31 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 12:40:31 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 12:40:31 INFO - Using env: {'DISPLAY': ':0', 12:40:31 INFO - 'HOME': '/home/cltbld', 12:40:31 INFO - 'LANG': 'en_US.UTF-8', 12:40:31 INFO - 'LANGUAGE': 'en_US:en', 12:40:31 INFO - 'LOGNAME': 'cltbld', 12:40:31 INFO - 'MAIL': '/var/mail/cltbld', 12:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:31 INFO - 'MOZ_NO_REMOTE': '1', 12:40:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:31 INFO - 'NO_EM_RESTART': '1', 12:40:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:31 INFO - 'PWD': '/builds/slave/test', 12:40:31 INFO - 'SHELL': '/bin/bash', 12:40:31 INFO - 'SHLVL': '1', 12:40:31 INFO - 'TERM': 'linux', 12:40:31 INFO - 'TMOUT': '86400', 12:40:31 INFO - 'USER': 'cltbld', 12:40:31 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:31 INFO - '_': '/tools/buildbot/bin/python'} 12:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:31 INFO - Running setup.py (path:/tmp/pip-ZHwGhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:31 INFO - Running setup.py (path:/tmp/pip-b008GN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:31 INFO - Running setup.py (path:/tmp/pip-Cx6JaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:31 INFO - Running setup.py (path:/tmp/pip-3a3pr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:31 INFO - Running setup.py (path:/tmp/pip-w1plpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:31 INFO - Running setup.py (path:/tmp/pip-6Zg3Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:31 INFO - Running setup.py (path:/tmp/pip-gTQ1Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:32 INFO - Running setup.py (path:/tmp/pip-OLxB8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:32 INFO - Running setup.py (path:/tmp/pip-jye1MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:32 INFO - Running setup.py (path:/tmp/pip-mjqYeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:32 INFO - Running setup.py (path:/tmp/pip-euOq4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:32 INFO - Running setup.py (path:/tmp/pip-b6XCRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:32 INFO - Running setup.py (path:/tmp/pip-7iVM78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:32 INFO - Running setup.py (path:/tmp/pip-gH1XZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:32 INFO - Running setup.py (path:/tmp/pip-qgam8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:32 INFO - Running setup.py (path:/tmp/pip-XccyHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:32 INFO - Running setup.py (path:/tmp/pip-DNqe2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:40:32 INFO - Running setup.py install for manifestparser 12:40:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:40:32 INFO - Running setup.py install for mozcrash 12:40:32 INFO - Running setup.py install for mozdebug 12:40:33 INFO - Running setup.py install for mozdevice 12:40:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Running setup.py install for mozfile 12:40:33 INFO - Running setup.py install for mozhttpd 12:40:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Running setup.py install for mozinfo 12:40:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Running setup.py install for mozInstall 12:40:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Running setup.py install for mozleak 12:40:33 INFO - Running setup.py install for mozlog 12:40:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Running setup.py install for moznetwork 12:40:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:40:33 INFO - Running setup.py install for mozprocess 12:40:34 INFO - Running setup.py install for mozprofile 12:40:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:40:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:40:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:40:34 INFO - Running setup.py install for mozrunner 12:40:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:40:34 INFO - Running setup.py install for mozscreenshot 12:40:34 INFO - Running setup.py install for moztest 12:40:34 INFO - Running setup.py install for mozversion 12:40:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:40:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:40:34 INFO - Cleaning up... 12:40:34 INFO - Return code: 0 12:40:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:34 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 0x17fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, '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': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', '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 12:40:34 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 12:40:34 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 12:40:34 INFO - Using env: {'DISPLAY': ':0', 12:40:34 INFO - 'HOME': '/home/cltbld', 12:40:34 INFO - 'LANG': 'en_US.UTF-8', 12:40:34 INFO - 'LANGUAGE': 'en_US:en', 12:40:34 INFO - 'LOGNAME': 'cltbld', 12:40:34 INFO - 'MAIL': '/var/mail/cltbld', 12:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:34 INFO - 'MOZ_NO_REMOTE': '1', 12:40:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:34 INFO - 'NO_EM_RESTART': '1', 12:40:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:34 INFO - 'PWD': '/builds/slave/test', 12:40:34 INFO - 'SHELL': '/bin/bash', 12:40:34 INFO - 'SHLVL': '1', 12:40:34 INFO - 'TERM': 'linux', 12:40:34 INFO - 'TMOUT': '86400', 12:40:34 INFO - 'USER': 'cltbld', 12:40:34 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:34 INFO - '_': '/tools/buildbot/bin/python'} 12:40:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:34 INFO - Running setup.py (path:/tmp/pip-ZO2oEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:34 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)) 12:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:34 INFO - Running setup.py (path:/tmp/pip-_VLfL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:34 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)) 12:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:34 INFO - Running setup.py (path:/tmp/pip-sGcA8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:35 INFO - Running setup.py (path:/tmp/pip-eUphLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:35 INFO - Running setup.py (path:/tmp/pip-0ILZNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:35 INFO - Running setup.py (path:/tmp/pip-jhM7kU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:35 INFO - Running setup.py (path:/tmp/pip-6pVo9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:35 INFO - Running setup.py (path:/tmp/pip-eonf_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:35 INFO - Running setup.py (path:/tmp/pip-gGYBzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:35 INFO - Running setup.py (path:/tmp/pip-hZ1_K5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:35 INFO - Running setup.py (path:/tmp/pip-2hz2D9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:35 INFO - Running setup.py (path:/tmp/pip-l2SSNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:35 INFO - Running setup.py (path:/tmp/pip-1lRWKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:35 INFO - Running setup.py (path:/tmp/pip-hw2aHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:35 INFO - Running setup.py (path:/tmp/pip-YzQ3VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:35 INFO - Running setup.py (path:/tmp/pip-NXvmqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:35 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)) 12:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:35 INFO - Running setup.py (path:/tmp/pip-QPCH6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:36 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)) 12:40:36 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)) 12:40:36 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)) 12:40:36 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)) 12:40:36 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)) 12:40:36 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)) 12:40:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:40:36 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 12:40:36 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 12:40:36 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 12:40:36 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 12:40:36 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 12:40:36 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 12:40:38 INFO - Downloading blessings-1.6.tar.gz 12:40:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:40:38 INFO - Installing collected packages: blessings 12:40:38 INFO - Running setup.py install for blessings 12:40:38 INFO - Successfully installed blessings 12:40:38 INFO - Cleaning up... 12:40:38 INFO - Return code: 0 12:40:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:40:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:38 INFO - Reading from file tmpfile_stdout 12:40:38 INFO - Current package versions: 12:40:38 INFO - argparse == 1.2.1 12:40:38 INFO - blessings == 1.6 12:40:38 INFO - blobuploader == 1.2.4 12:40:38 INFO - docopt == 0.6.1 12:40:38 INFO - functools32 == 3.2.3-2 12:40:38 INFO - jsonschema == 2.5.1 12:40:38 INFO - manifestparser == 1.1 12:40:38 INFO - mozInstall == 1.12 12:40:38 INFO - mozcrash == 0.17 12:40:38 INFO - mozdebug == 0.1 12:40:38 INFO - mozdevice == 0.48 12:40:38 INFO - mozfile == 1.2 12:40:38 INFO - mozhttpd == 0.7 12:40:38 INFO - mozinfo == 0.9 12:40:38 INFO - mozleak == 0.1 12:40:38 INFO - mozlog == 3.2 12:40:38 INFO - moznetwork == 0.27 12:40:38 INFO - mozprocess == 0.23 12:40:38 INFO - mozprofile == 0.28 12:40:38 INFO - mozrunner == 6.12 12:40:38 INFO - mozscreenshot == 0.1 12:40:38 INFO - mozsystemmonitor == 0.3 12:40:38 INFO - moztest == 0.7 12:40:38 INFO - mozversion == 1.4 12:40:38 INFO - psutil == 3.1.1 12:40:38 INFO - requests == 1.2.3 12:40:38 INFO - wsgiref == 0.1.2 12:40:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:38 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 0x17fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, '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': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', '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 12:40:38 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 12:40:38 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 12:40:38 INFO - Using env: {'DISPLAY': ':0', 12:40:38 INFO - 'HOME': '/home/cltbld', 12:40:38 INFO - 'LANG': 'en_US.UTF-8', 12:40:38 INFO - 'LANGUAGE': 'en_US:en', 12:40:38 INFO - 'LOGNAME': 'cltbld', 12:40:38 INFO - 'MAIL': '/var/mail/cltbld', 12:40:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:38 INFO - 'MOZ_NO_REMOTE': '1', 12:40:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:38 INFO - 'NO_EM_RESTART': '1', 12:40:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:38 INFO - 'PWD': '/builds/slave/test', 12:40:38 INFO - 'SHELL': '/bin/bash', 12:40:38 INFO - 'SHLVL': '1', 12:40:38 INFO - 'TERM': 'linux', 12:40:38 INFO - 'TMOUT': '86400', 12:40:38 INFO - 'USER': 'cltbld', 12:40:38 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:38 INFO - '_': '/tools/buildbot/bin/python'} 12:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:38 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)) 12:40:38 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)) 12:40:38 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)) 12:40:38 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)) 12:40:38 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)) 12:40:38 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)) 12:40:38 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)) 12:40:38 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)) 12:40:38 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)) 12:40:38 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)) 12:40:38 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)) 12:40:38 INFO - Cleaning up... 12:40:38 INFO - Return code: 0 12:40:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:40:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17fb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abc180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, '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': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', '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 12:40:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:40:38 INFO - Using env: {'DISPLAY': ':0', 12:40:38 INFO - 'HOME': '/home/cltbld', 12:40:38 INFO - 'LANG': 'en_US.UTF-8', 12:40:38 INFO - 'LANGUAGE': 'en_US:en', 12:40:38 INFO - 'LOGNAME': 'cltbld', 12:40:38 INFO - 'MAIL': '/var/mail/cltbld', 12:40:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:38 INFO - 'MOZ_NO_REMOTE': '1', 12:40:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:38 INFO - 'NO_EM_RESTART': '1', 12:40:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:38 INFO - 'PWD': '/builds/slave/test', 12:40:38 INFO - 'SHELL': '/bin/bash', 12:40:38 INFO - 'SHLVL': '1', 12:40:38 INFO - 'TERM': 'linux', 12:40:38 INFO - 'TMOUT': '86400', 12:40:38 INFO - 'USER': 'cltbld', 12:40:38 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:38 INFO - '_': '/tools/buildbot/bin/python'} 12:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:40:39 INFO - Cleaning up... 12:40:39 INFO - Return code: 0 12:40:39 INFO - Running post-action listener: _resource_record_post_action 12:40:39 INFO - Running post-action listener: _start_resource_monitoring 12:40:39 INFO - Starting resource monitoring. 12:40:39 INFO - ##### 12:40:39 INFO - ##### Finished create-virtualenv step (success) 12:40:39 INFO - ##### 12:40:39 INFO - ##### 12:40:39 INFO - ##### Running install step. 12:40:39 INFO - ##### 12:40:39 INFO - Running pre-action listener: _resource_record_pre_action 12:40:39 INFO - Running main action method: install 12:40:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:39 INFO - Reading from file tmpfile_stdout 12:40:39 INFO - Detecting whether we're running mozinstall >=1.0... 12:40:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:40:39 INFO - Reading from file tmpfile_stdout 12:40:39 INFO - Output received: 12:40:39 INFO - Usage: mozinstall [options] installer 12:40:39 INFO - Options: 12:40:39 INFO - -h, --help show this help message and exit 12:40:39 INFO - -d DEST, --destination=DEST 12:40:39 INFO - Directory to install application into. [default: 12:40:39 INFO - "/builds/slave/test"] 12:40:39 INFO - --app=APP Application being installed. [default: firefox] 12:40:39 INFO - mkdir: /builds/slave/test/build/application 12:40:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:40:55 INFO - Reading from file tmpfile_stdout 12:40:55 INFO - Output received: 12:40:55 INFO - /builds/slave/test/build/application/firefox/firefox 12:40:55 INFO - Running post-action listener: _resource_record_post_action 12:40:55 INFO - ##### 12:40:55 INFO - ##### Finished install step (success) 12:40:55 INFO - ##### 12:40:55 INFO - ##### 12:40:55 INFO - ##### Running run-tests step. 12:40:55 INFO - ##### 12:40:55 INFO - Running pre-action listener: _resource_record_pre_action 12:40:55 INFO - Running main action method: run_tests 12:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:40:55 INFO - Python 2.7.3 12:40:55 INFO - Return code: 0 12:40:55 INFO - grabbing minidump binary from tooltool 12:40:55 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')]} 12:40:55 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 0x1aba3b0>, '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 0x1abb2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abb740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:40:55 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 12:40:55 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 12:40:55 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 12:40:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:40:55 INFO - Return code: 0 12:40:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:40:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:40:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:40:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:40:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 12:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 12:40:55 INFO - Using env: {'DISPLAY': ':0', 12:40:55 INFO - 'HOME': '/home/cltbld', 12:40:55 INFO - 'LANG': 'en_US.UTF-8', 12:40:55 INFO - 'LANGUAGE': 'en_US:en', 12:40:55 INFO - 'LOGNAME': 'cltbld', 12:40:55 INFO - 'MAIL': '/var/mail/cltbld', 12:40:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:40:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:55 INFO - 'MOZ_NO_REMOTE': '1', 12:40:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:40:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:40:55 INFO - 'NO_EM_RESTART': '1', 12:40:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:55 INFO - 'PWD': '/builds/slave/test', 12:40:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:40:55 INFO - 'SHELL': '/bin/bash', 12:40:55 INFO - 'SHLVL': '1', 12:40:55 INFO - 'TERM': 'linux', 12:40:55 INFO - 'TMOUT': '86400', 12:40:55 INFO - 'USER': 'cltbld', 12:40:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449', 12:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:55 INFO - '_': '/tools/buildbot/bin/python'} 12:40:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 12:40:55 INFO - mozversion application_buildid: 20160713120543 12:40:55 INFO - mozversion application_changeset: 3e3b1eb03a908d02dac0bcb25c93eb8b734b5124 12:40:55 INFO - mozversion application_display_name: Nightly 12:40:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:40:55 INFO - mozversion application_name: Firefox 12:40:55 INFO - mozversion application_remotingname: firefox 12:40:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:40:55 INFO - mozversion application_vendor: Mozilla 12:40:55 INFO - mozversion application_version: 50.0a1 12:40:55 INFO - mozversion platform_buildid: 20160713120543 12:40:55 INFO - mozversion platform_changeset: 3e3b1eb03a908d02dac0bcb25c93eb8b734b5124 12:40:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:40:55 INFO - mozversion platform_version: 50.0a1 12:40:55 INFO - using testdate: 1468438855 12:40:55 INFO - actual date: 1468438855 12:40:55 INFO - starting webserver on 'localhost:56185' 12:40:55 INFO - SUITE-START | Running 2 tests 12:40:55 INFO - TEST-START | dromaeo_css 12:40:55 INFO - operating with platform_type : linux_ 12:40:55 INFO - Initialising browser for dromaeo_css test... 12:40:56 INFO - TEST-INFO | started process 25490 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5YABA_/profile http://localhost:56185/getInfo.html) 12:41:00 INFO - PROCESS | 25490 | __metrics Screen width/height:1600/1200 12:41:00 INFO - PROCESS | 25490 | colorDepth:24 12:41:00 INFO - PROCESS | 25490 | Browser inner width/height: 1024/697 12:41:00 INFO - PROCESS | 25490 | __metrics 12:41:00 INFO - PROCESS | 25490 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:41:00 INFO - PROCESS | 25490 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:41:01 INFO - TEST-INFO | 25490: exit 0 12:41:01 INFO - Browser initialized. 12:41:01 INFO - Running cycle 1/1 for dromaeo_css test... 12:41:01 INFO - TEST-INFO | started process 25571 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5YABA_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:13 INFO - PROCESS | 25571 | RSS: Main: 159129600 12:41:13 INFO - PROCESS | 25571 | 12:42:05 INFO - PROCESS | 25571 | 1468438925429 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] 12:44:16 INFO - PROCESS | 25571 | Cycle 1(1): loaded http://localhost:56185/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56185/tests/dromaeo/cssquery-ext.html) 12:44:18 INFO - PROCESS | 25571 | RSS: Main: 262381568 12:44:18 INFO - PROCESS | 25571 | 12:47:20 INFO - PROCESS | 25571 | Cycle 1(1): loaded http://localhost:56185/tests/dromaeo/cssquery-ext.html (next: http://localhost:56185/tests/dromaeo/cssquery-jquery.html) 12:47:21 INFO - PROCESS | 25571 | RSS: Main: 248369152 12:47:21 INFO - PROCESS | 25571 | 12:50:23 INFO - PROCESS | 25571 | Cycle 1(1): loaded http://localhost:56185/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56185/tests/dromaeo/cssquery-mootools.html) 12:50:24 INFO - PROCESS | 25571 | RSS: Main: 235311104 12:50:24 INFO - PROCESS | 25571 | 12:53:26 INFO - PROCESS | 25571 | Cycle 1(1): loaded http://localhost:56185/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56185/tests/dromaeo/cssquery-prototype.html) 12:53:27 INFO - PROCESS | 25571 | RSS: Main: 247914496 12:53:27 INFO - PROCESS | 25571 | 12:56:29 INFO - PROCESS | 25571 | Cycle 1(1): loaded http://localhost:56185/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56185/tests/dromaeo/cssquery-yui.html) 12:56:30 INFO - PROCESS | 25571 | RSS: Main: 210415616 12:56:30 INFO - PROCESS | 25571 | 12:59:33 INFO - PROCESS | 25571 | Cycle 1(1): loaded http://localhost:56185/tests/dromaeo/cssquery-yui.html (next: http://localhost:56185/tests/dromaeo/cssquery-dojo.html) 12:59:33 INFO - PROCESS | 25571 | RSS: Main: 279363584 12:59:33 INFO - PROCESS | 25571 | 12:59:33 INFO - PROCESS | 25571 | __start_tp_report 12:59:33 INFO - PROCESS | 25571 | _x_x_mozilla_page_load 12:59:33 INFO - PROCESS | 25571 | _x_x_mozilla_page_load_details 12:59:33 INFO - PROCESS | 25571 | |i|pagename|runs| 12:59:33 INFO - PROCESS | 25571 | |0;dojo.html;2566.2814411964646;2573.922782316531;2602.7397260273974;2615.2546524240597;2619.3713508757905;4418.050119224366;4481.327800829869;4548.613367863732;4585.610223131033;4595.333676616893;4505.234110201265;4540.477845047821;4555.567221113989;4602.1255961367315;4608.285715714062;4182.35173548099;4216.57834216579;4238.343056826189;4252.38340440636;4265.0403659176745;4244.299690551055;4315.180115778008;4316.589923957219;4335.0679603885155;4343.305071188611;4270.039241170743;4296.849610263657;4308.849190278341;4313.28830742927;4344.761038142901;4096.73371230869;4149.813258403379;4158.438610787535;4176.874693759192;4224.345226489899;4133.772642504661;4147.212029714345;4189.371594260855;4197.454330936989;4233.978830105861;4575.336576196466;4588.449386073693;4593.173228818811;4612.400387949545;4625.306204069383;4660.533946605312;4825.227963525819;4871.537203965618;4885.682430641997;4949.158643030693;4919.655624106314;4955.1080805455;4965.354503914504;4976.601871850244;4981.153203955337;4407.603315701603;4584.083183363341;4588.242939914943;4591.908161836778;4628.514006029386;5019.47295533971;5063.291139240509;5075.416327122354;5080.110980578384;5139.357580302462;4873.975630121827;4888.169011268093;4899.730514821647;4933.28467372229;4934.7285899275885;4744.905101897943;4796.712197268175;4893.657443978887;4925.162722337212;4934.777934992934;4809.254565542346;4820.421549414023;4877.756112194376;4889;4908.901821963612;4871.3667223260745;4881.609471242363;4885.071836351082;4893.437254715682;4936.185529387682;3998.5601583825755;4046.2716710992304;4144.48193975753;4151.709380343347;4192.748435093904;3607.9278414431565;3612.3497770401054;3634.0914771307175;3649.197176621139;3680.190358121209;4204.978975105166;4208.263553878059;4213.810378532973;4227.112306415687;4254.510731265882;4922.507749225048;4925.605951523941;4932;4932.136876046678;4966.8758281043265;4101.22076805412;4169.228692691861;4217.283061879488;4230.5769423057445;4233.428487154257;4406.801693923781;4418.80115394808;4420.359047938031;4441.533638967927;4459.019015816515;4434.201843668171;4647.070585882837;4658.953410465988;4660.813567457264;4664.486906440221;4851.151048566558;4864.36763220779;4883.853484395474;4884.560389564814;4895.290182923385;4615.93076103852;4625.375574297427;4628.213203755437;4629.213033784197;4647.488776234679;4599.310103484504;4771.045790841915;4826.300186473012;4826.32431459603;4864.2460418633755;4871.171900776805;4882.487338829372;4889.462159162418;4910.484399138039;4948.38145231846;4755.334253204624;4762.499937506512;4764.976175119103;4766.332713420194;4802.73584952817;4618.376519169869;4724.078804633064;4765.451973023008;4766.475687674422;4806.47128815837;4683.508231635629;4693;4707.788149533348;4731.526847315241;4741.980474198064;4794.5924596409695;4801.18379875415;4805.567498925114;4837.467878533429;4850;4513.638908887216;4680.438347398334;4685.578297953064;4703.647226458098;4720.079584481131 12:59:33 INFO - PROCESS | 25571 | |1;ext.html;571.382906461022;584.0800738836331;585.259646547118;589.8053642298042;590.6987436407431;27656.17031489056;40469;41778.99999999996;41864.58135418645;45596;42961;43361;43380.132397352034;43423.78288108563;43430;7523.736669216579;7763.689452421896;7896.447248692594;7902.6443810028395;7906.2884340409355;7432.442566807484;7687.5387476751575;7691;9867.457289849055;10209.183265338792;9883.209343252558;9911.553980070878;9912.256580756437;9914.305998580103;9923.454210018446;10169;10175.949120254389;10229.18166546682;10237.590496380137;10245.846312305284;7426.591537465437;7653.808654783619;7656.157822639505;9762.90237097627;10153.4923253837;39417;39861.40207896884;39905.201895961916;39928.20143597112;39942;2857.985710071436;2884.971150288491;2939.1182645206354;3362.9831850840587;3580.5753293009784;30042.84978575115;30129.397412051636;30426;30479.39041219163;31499.527507087412;2659.7074321824775;2673.6665224935177;2692.8788204530647;2765.6681198256138;2776.1532732516575;250568.99999999817;251261;251322;251909;251952;54565.72717136389;54678.72660636671;54735.45264547304;54782.178267326024;54787;59001.70499147477;59284.11073833896;59398;59417.32622032019;59462.70268648716;15113;15528;15620.531384058497;15630.3747850087;15642.68714625701;10299.279050466545;10440.060394564525;10442.321249118782;10460.215483838743;10524.36853788775;17152.313907443564;17611.911940440215;17710.468685939442;18186.181621826785;18221.45335639933;6166.383361663798;6190.566660333823;6278.215223097113;6314.589551679126;6348.238211414659;802.9638666260032;876.3646356391565;882.7087061269766;918.9035151309016;922.6586163119555;28667;28722.9787829704;28783.56824647632;28836.42327153445;28898.42203155925;906.1346414174448;982.4940155819759;992.692265397729;1007.189212683782;1027.198784947742;14063.507777227747;14735.926320368544;14762.409503619734;14847.628809279855;14907.925460372846;363728;363918;368111;368891;371954;49770;50191;50195.239261840215;50268.74865625649;50281;1089.477051015517;1182.597916708306;1210.4794938176492;1225.8957988571071;1227.9447424865903;28027.71972280251;29154;29580.408391832472;29661;30055.549166762954;43366.56633433751;43610.563894360654;43808.342874856266;43939.78030109957;43996.34005489856;302342;302584;304582;304891;305134;22185.667214991467;24942.251732448054;25657.743422565534;25676.614850777627;26959.46081078407;41850.1629967405;41941.95145121274;41959.580404196786;42698.14603707971;42911.356329655704;36130.45804312885;36411.45382819207;36428;36437;37064.07339816526;35959.29550683549;36712.63287367199;36749.81625091964;36805;36844;35467.645323546436;35608.46587301141;36120.27759444744;36188.63811361853;36503.26993460169;31525;32541.674583253865;32552.674473255913;32730;32731.509027365086;25080.74919250857;25572.616410754224;25600;25939;25956 12:59:33 INFO - PROCESS | 25571 | |2;jquery.html;371.12414701304914;373.1790061863899;384.9056981039646;389.9376099824029;391.95884432134625;16846.747298790528;17166.65666686667;17667.293308267686;17723.379681711143;18141.608382880815;16976.236069376875;17169.141542922865;17673.82326176738;17692.20385082671;17731.202095905683;4611.930821037688;4772.665913386064;4800.183968725308;4818.638602104838;4820.469748327681;3436.1237884339457;3622.7645203061716;4838.927416088762;6206.8448288792915;6218.626882387049;6259.937400626004;6272.278687950876;6287.2140982377205;6291.811245662638;6303.495720342362;5488.17677348397;5536.307961504812;5548.639338442988;5597.44025597441;6249.718762655669;5359.169328754049;5382.542483888874;5406.162044883049;5411.485908838694;5415.187721841716;12406;12457.813132803016;12535.93732031334;12716.109872308942;12806.359682015862;1687.7805885234964;1806.4851517317502;1963.4796778853615;2130.7762684918016;2258.7802586603216;8902.86645700315;8977;8980.595873185723;9277.907220927771;9312.813743725155;1703.821098784622;1721.5868191634042;1724.9822604663184;1788.097011009445;1804.4857215693464;64058.35941640617;69077;69387.3061269386;70150;71127.28872711258;10384.636537721144;10389.84415233772;10515.369077855354;10680.57277708883;10716.892831071746;14344.713105737826;14471.204083775494;14471.92764036194;14525.709485810225;14526.709465810625;6812.795616131524;7560.58416787082;7706.499077559941;7712.460127791001;7807.180246074194;6906.896396554056;7032.5077244592385;7053.083099197174;7356.190819009905;7390.150132734697;4244.533101358848;4329.718568293114;4338.327559228324;4358;4580.358749775034;1837.604914943267;1855.1893880770642;1857.1178690122354;1865;1877.2584828992472;787.2127872127872;858.4463021351194;858.9527575983255;916.6287653500343;948.6300342866288;17047;17669.91165044192;17746.733798993024;17850.018248996428;18152.09239538018;800.8798680198017;802.6949759091508;805.8227190018187;832.6669332267021;851.0425771007616;9500.809983800285;9923.60305587783;9959.701208963743;9974.900250997542;10116.696499105039;342550;346612;347063;347094;350554;61360;76815.61592192003;79964;82252;82381;820.5117954816874;834.2533432577809;853.6158728572043;855.6149732620346;863.2575984653193;12273.877261227273;15648.530544083696;15718.13550254747;16343;16856.4556532497;37332.626673734005;37559;38020;38096.61903380931;38557.614423856525;46878.76560617175;47424.7628761854;48037.75981120072;48201.276980846014;48245.5175448241;7277.636118194175;7278.381337586153;7324.5971471567445;7414.369778568882;7663.386929045551;10631.840522392326;11272.098232141245;11286.66140015801;11292.661220163409;11529.423528823692;9824.852627210741;9847.60609575609;9872.851907221253;9897.158741507052;10259.333143345659;9857.802843943224;9862.802743944938;9976.850347244943;9984.850227246743;10217;9648.855267170858;9775.315727898978;9945.353552019096;9976.700698979042;10081.14310283634;7501.6249187539315;7544.170141284564;7553.282438168365;7622.352100071556;7659.463837531319;8945.702873083237;9370.39092458988;9810.509474526103;10072.395656260647;10234.6417875374 12:59:33 INFO - PROCESS | 25571 | |3;mootools.html;1395.337214822959;1405.9367328470219;1491.433255362962;1667.9817420920685;1746.9825301746978;7734.535927844333;8202.466839655426;8214.671413143482;8215.58922053898;8260.256576908077;6665.13353264075;6715.932840671592;7689.961550192241;7946.205379462065;8113.797155071126;2169.208238992772;2205.3383984804573;2209.5801797658473;2211.458192742772;2212.4690074382193;2160.91356345746;2191.857529260601;2248.067052173346;2249.741279752825;2253.876036817974;1869;2163.1779923629;2223.7665045170265;2229;2233.966490502644;2205.4376134085865;2220.766819483947;2231.542533780571;2233.3188377544925;2272.6022945984387;2193.363924461904;2195.1438938813876;2200.526886719363;2202.41635966469;2203.019656252968;8234.876476852853;8285.710000149966;8290.585470726439;8310.376721745833;8317.916820831775;4574.5425457453985;4664.416947881515;4689.9765501172615;4700.1539722849875;4715.174844402216;2621.5281249375107;2669.719679433651;2718.9184324470298;2738.4796888591304;2762.1023167470653;808.3815880851154;817.1950628630794;823.9464434811778;824.674253669797;832.367400775409;11122.054625356936;11129.499172537255;11155.944220278847;11157.944210278978;11169.16231282649;4070.9389359159636;4172.4784401949755;4289.849855255051;4294.806733696991;4355.956440435619;9531.094546018117;9580.0898914603;9585.472798996123;9589.616415343447;9592.95203523978;4800.543948324904;4805.063012712558;4805.399325084364;4811.903761924742;4813.446453657804;4101.446304748821;4349.739015659007;4362.978185109054;4363.956360436418;4364.148990946735;3526.118470382404;3578.3380074686265;3631.219287853124;3651.3975194092677;3670.8164591770305;931.0410277414275;1011.5700827148432;1025.410389026313;1030.0832259289239;1040.8230600797883;621.3599992008238;735.3602365941665;786.7836345005169;792.2394501278721;827.5133224659978;5091.7963281469065;5196.428392876781;5413.539849112791;5573.69344686038;5724.599278050579;857.0272740439522;879.5531350927688;914.8627705844177;928.6007016982762;931.1154403316876;3680.595134535199;3740.625937406292;3753.155540003477;3806.1626442182674;3836.48207491985;9889.75275618101;9987.60049598008;10154.339967902062;10258.384496930208;10262.589496420209;10104.090631843022;10217.948910255402;10222.539985700661;10226.488675566192;10232.64185753495;6405.7117429714845;7322.340989310987;7460.813479663052;7618.466707330434;7621.31408173256;5605.299337582802;5684.8294551163535;5810.738516766671;5844.970775146097;5859.355470898283;9368.063193680577;9395.013523579926;9493.430394176372;9497.715068547679;9502.952485237529;9285.814283714153;9373.25013998892;9465.195458386115;9722.465264410524;9722.562484688064;6418.229812422539;6450.290468048604;6507.869842603216;6561.967190164019;6745.52781305304;7731.304182623591;7747.961260193663;7763.534187948741;7802.453828231969;7805.063392392949;6978.79063628092;7118.537295075804;7154.928450715634;7250;7255.56466612005;6905.37851593359;7007.124109486314;7030.121234845645;7101.715931362688;7153.105861767279;6536.640484773383;6563.507736919654;6597.6701164940605;6672.232693240146;6672.633005184762;4561.794719237576;4598.586127248564;4650.906981860411;4662.160811054043;4711.646626502958;4451.399061126706;4512.029913568598;4535.523770004102;4587.655925805511;4632.073585282889 12:59:33 INFO - PROCESS | 25571 | |4;prototype.html;299.9085278989909;301.7224153778524;305.6423983938792;307.66618219232134;311.2855995490352;4752.192127338352;4785.822450784894;4794.304825800257;4800.543948324908;4837.88779537393;4726.385569875912;4731.834385796497;4742.383490146285;4742.7865746041425;4745.050989802036;3784.621537846207;3798.2593394288124;3799.7530160539613;3816.5229346331707;3829.215010922768;3508.245727168658;3699.6855267302176;3822.7515211511163;3848.2111167210655;3857.498525191735;3659.6706296433317;3697.5562932448142;3704.166562523424;3710.5547334319785;3711.498947642074;3690.1881586051027;3701.463287823264;3705.8517659293598;3706.629337066299;3708.313961917027;3090.134762266569;3171.1913462067027;3187.3943950649536;3260.4457242268873;3274.3123943971796;4638;4666.6500012498855;4667.603253723438;4680.672352935296;4683.320918466803;4240.777009472792;4243.596858298457;4253.787310634456;4256.702030857841;4256.702030857841;4770.260609605517;4853.563179313879;4977.427595826454;4981.427135879348;4990.975045124787;3541.9645803541894;3574.749767516275;3581.928361432757;3584.7669901456584;3589.6410358964154;5212.635115541913;5229.5293423591675;5233.476652334774;5243.213517972296;5262.079341040531;3553.969348888845;3579.982100089483;3647.2705458908326;3649.197176621139;3671.9816400917825;3675.3751862183494;3690.538682664667;3694.242680250542;3704;3704.2221133561716;4243.0241044559925;4246.150769846043;4251.829926802955;4266.5946735060115;4266.722663026893;4855;4947.332110165154;5002.349694539686;5007.9499205008215;5054.519820617035;1260.615512268767;1270.104576273725;1276.3490619783977;1284.81796746993;1289.2715615677112;800.5196881870947;816.603947085663;820.6840366458956;827.9691783729296;831.9792005199919;3976.5029371328583;3979.348570343333;3991.1818077294083;3997.8800635980965;4009.0533457908414;4551.906123183373;4558.9772051139535;4589.5181005994555;4594.264917613233;4614.123316569841;3921.5490218625223;3956.6043395660204;3974.2250261198787;3991.940120898189;4003.679705623543;2278.512172906948;2431.4893872286666;2438.7683170098812;2441.279822452381;2442.072012635187;4343.587359200871;4358.714179317046;4361.9127617447475;4363.672724545671;4396.230659634552;4639.466461356988;4657.208274593328;4669.4396672399525;4672.813087476463;4701.318308845198;4093.643212565727;4290.356446533045;4348.521662617173;4352.477702675699;4372.868813935587;4527.320901864615;4528.637708983208;4557.498675145797;4563.174730107983;4564;4463.419755431773;4467.396901418397;4473.642108631367;4480.759620189905;4519.209138400833;4108.609682080198;4324.51340479232;4329.1341731653165;4333.501647310473;4338.26249537573;3343.5987681478377;3372.4772660237704;3383.7969721815784;3389.203537168714;3391.253924136686;4573.977130114328;4613.607843333354;4625.825861844927;4627.282771170474;4635.629149668086;4495.685302028826;4498.415206023196;4501.482329532015;4510.188166130128;4517.977410112929;4256.787160641894;4425.734455932655;4425.756583387944;4427.29163333865;4467.285234362486;4365.36298887917;4381.518033016302;4386.780660967002;4402.603765661106;4455.532169122195;4481.708688935209;4494.977525112354;4496.168208881237;4810.687305325143;4820.505643250463;4899.902001960012;4901.142300097397;4904.2643603459765;4932.8026878925875;4939.209726443752 12:59:33 INFO - PROCESS | 25571 | |5;yui.html;565.91511273309;619.6189343553716;638.332942075531;652.0935899100249;724.7208676685648;12533.122681412306;14040.578782636512;14053.57839264821;14073.22597257153;14073.4370625175;13203.207807531555;13543.984201184901;13552.18686878788;13580;13583.252921089335;621.2638023941644;744.765398899345;762.0436352377772;784.5528049012065;787.9369650427981;751.3200553499092;752.7929819299709;753.9246075392472;757.8635845547799;765.5024234247728;808.8422757562278;812.7642983534768;814.2142832166958;814.3403842887251;814.6415577146066;641.6054126712086;792.6116203060517;804.1275216390194;809.0493669937799;820.1347578304886;607.6172011632688;607.781198768443;607.7842365960065;607.8055022392836;607.9483243924271;12225.449854756558;12256.325902075383;12265.325407102606;12265.570705025371;12269;12501.874981250163;12813.615591532176;13071.803922941164;13179.275139867303;13255.86744132556;10377.533011014522;10405.375677459377;10496;11632.883671163348;11702.356370399624;3239.1578189670618;3361.37814504315;3482.407990641597;4194.182134483777;4232.957670423287;62098;62551.37448625501;63159.736805264096;63277;63294.68352658207;9856.852147217798;9924.163375753295;10010.849837252446;10105.24210684187;10330.535125919352;18505.629887402178;18561.35035273759;18618.906905465385;18637.254509819457;18645.533861653566;6658.633775142317;6713.160854893138;6820.6930688119155;6880.174379074543;6978.930210697929;3683.8710645127826;3786.6402691744242;3796.6203379662365;3806.3909774435956;3839.462475253467;2722.6868910075596;2811.9156425306865;2813.971860281371;2814.479321325561;2816.5775133729694;481.48481125195707;483.09660934052846;486.192919753208;493.85925011371586;494.15746152809373;417.49900119855687;490.9950900491021;512.4132867866272;521.4316395129251;524.5384062025394;7544.622768861535;7569.243075692387;7688.346490548254;7731.458797884094;7816.374690024784;467.99766001169775;468.2938395649027;468.86637308366954;475.64089112719677;481.87712133405756;3599.9560127562713;3632.8546858125906;3753.3244016076824;3755.4366844973474;3756.624337566276;52687.73656131772;53987;57399;93685;93789.53105234567;77977;83816;83841.58079209564;83867.5806620963;84919.15080849236;46826.06347873092;48572;55580.72209638926;71328;76843.2315676836;7054.047703559953;7070.702056579687;7166.677499512431;7257.963710181415;7265.455090868311;42469.78765106278;42974.570254298305;44685.329720053574;45245;45543.31685024661;58922;63319;65422.018669719044;65597;65682.67158664177;9223.400478968846;9468.100530449596;9550.426974381282;9644.324897257406;9671.468069256258;10768.946155269174;10798.460076996276;10824.891751082389;10839.132869370274;10948.069414099891;8052.900810979414;10605.098566621924;10837.295575787548;11030.889691103308;11157.832632510357;10065.647702330383;11108.166887483309;11142.498587563417;11204.49579768896;11526.654200374001;11281.492332844877;11428.94285528567;11708.180427370002;12472.438740256892;12473.251604903735;9796.363236389612;10030.348027378197;10121.139703125022;10344;10354.482275886327;9607.951960240154;9764.511774411108;9831.262655301025;9853.753656158653;9855.802883942424 12:59:33 INFO - PROCESS | 25571 | __end_tp_report 12:59:33 INFO - PROCESS | 25571 | __start_cc_report 12:59:33 INFO - PROCESS | 25571 | _x_x_mozilla_cycle_collect,983 12:59:33 INFO - PROCESS | 25571 | __end_cc_report 12:59:33 INFO - PROCESS | 25571 | __startTimestamp1468439973713__endTimestamp 12:59:33 INFO - PROCESS | 25571 | 12:59:33 INFO - PROCESS | 25571 | ------- Summary: start ------- 12:59:33 INFO - PROCESS | 25571 | Number of tests: 6 12:59:33 INFO - PROCESS | 25571 | 12:59:33 INFO - PROCESS | 25571 | [#0] dojo.html Cycles:165 Average:4528.46 Median:4629.21 stddev:470.79 (10.2%) stddev-sans-first:446.36 12:59:33 INFO - PROCESS | 25571 | Values: 2566.3 2573.9 2602.7 2615.3 2619.4 4418.1 4481.3 4548.6 4585.6 4595.3 4505.2 4540.5 4555.6 4602.1 4608.3 4182.4 4216.6 4238.3 4252.4 4265.0 4244.3 4315.2 4316.6 4335.1 4343.3 4270.0 4296.8 4308.8 4313.3 4344.8 4096.7 4149.8 4158.4 4176.9 4224.3 4133.8 4147.2 4189.4 4197.5 4234.0 4575.3 4588.4 4593.2 4612.4 4625.3 4660.5 4825.2 4871.5 4885.7 4949.2 4919.7 4955.1 4965.4 4976.6 4981.2 4407.6 4584.1 4588.2 4591.9 4628.5 5019.5 5063.3 5075.4 5080.1 5139.4 4874.0 4888.2 4899.7 4933.3 4934.7 4744.9 4796.7 4893.7 4925.2 4934.8 4809.3 4820.4 4877.8 4889.0 4908.9 4871.4 4881.6 4885.1 4893.4 4936.2 3998.6 4046.3 4144.5 4151.7 4192.7 3607.9 3612.3 3634.1 3649.2 3680.2 4205.0 4208.3 4213.8 4227.1 4254.5 4922.5 4925.6 4932.0 4932.1 4966.9 4101.2 4169.2 4217.3 4230.6 4233.4 4406.8 4418.8 4420.4 4441.5 4459.0 4434.2 4647.1 4659.0 4660.8 4664.5 4851.2 4864.4 4883.9 4884.6 4895.3 4615.9 4625.4 4628.2 4629.2 4647.5 4599.3 4771.0 4826.3 4826.3 4864.2 4871.2 4882.5 4889.5 4910.5 4948.4 4755.3 4762.5 4765.0 4766.3 4802.7 4618.4 4724.1 4765.5 4766.5 4806.5 4683.5 4693.0 4707.8 4731.5 4742.0 4794.6 4801.2 4805.6 4837.5 4850.0 4513.6 4680.4 4685.6 4703.6 4720.1 12:59:33 INFO - PROCESS | 25571 | 12:59:33 INFO - PROCESS | 25571 | [#1] ext.html Cycles:180 Average:47093.19 Median:199958.32 stddev:81658.78 (40.8%) stddev-sans-first:81813.15 12:59:33 INFO - PROCESS | 25571 | Values: 571.4 584.1 585.3 589.8 590.7 27656.2 40469.0 41779.0 41864.6 45596.0 42961.0 43361.0 43380.1 43423.8 43430.0 7523.7 7763.7 7896.4 7902.6 7906.3 7432.4 7687.5 7691.0 9867.5 10209.2 9883.2 9911.6 9912.3 9914.3 9923.5 10169.0 10175.9 10229.2 10237.6 10245.8 7426.6 7653.8 7656.2 9762.9 10153.5 39417.0 39861.4 39905.2 39928.2 39942.0 2858.0 2885.0 2939.1 3363.0 3580.6 30042.8 30129.4 30426.0 30479.4 31499.5 2659.7 2673.7 2692.9 2765.7 2776.2 250569.0 251261.0 251322.0 251909.0 251952.0 54565.7 54678.7 54735.5 54782.2 54787.0 59001.7 59284.1 59398.0 59417.3 59462.7 15113.0 15528.0 15620.5 15630.4 15642.7 10299.3 10440.1 10442.3 10460.2 10524.4 17152.3 17611.9 17710.5 18186.2 18221.5 6166.4 6190.6 6278.2 6314.6 6348.2 803.0 876.4 882.7 918.9 922.7 28667.0 28723.0 28783.6 28836.4 28898.4 906.1 982.5 992.7 1007.2 1027.2 14063.5 14735.9 14762.4 14847.6 14907.9 363728.0 363918.0 368111.0 368891.0 371954.0 49770.0 50191.0 50195.2 50268.7 50281.0 1089.5 1182.6 1210.5 1225.9 1227.9 28027.7 29154.0 29580.4 29661.0 30055.5 43366.6 43610.6 43808.3 43939.8 43996.3 302342.0 302584.0 304582.0 304891.0 305134.0 22185.7 24942.3 25657.7 25676.6 26959.5 41850.2 41942.0 41959.6 42698.1 42911.4 36130.5 36411.5 36428.0 36437.0 37064.1 35959.3 36712.6 36749.8 36805.0 36844.0 35467.6 35608.5 36120.3 36188.6 36503.3 31525.0 32541.7 32552.7 32730.0 32731.5 25080.7 25572.6 25600.0 25939.0 25956.0 12:59:33 INFO - PROCESS | 25571 | 12:59:33 INFO - PROCESS | 25571 | [#2] jquery.html Cycles:180 Average:22848.41 Median:26510.87 stddev:57552.34 (217.1%) stddev-sans-first:57689.04 12:59:33 INFO - PROCESS | 25571 | Values: 371.1 373.2 384.9 389.9 392.0 16846.7 17166.7 17667.3 17723.4 18141.6 16976.2 17169.1 17673.8 17692.2 17731.2 4611.9 4772.7 4800.2 4818.6 4820.5 3436.1 3622.8 4838.9 6206.8 6218.6 6259.9 6272.3 6287.2 6291.8 6303.5 5488.2 5536.3 5548.6 5597.4 6249.7 5359.2 5382.5 5406.2 5411.5 5415.2 12406.0 12457.8 12535.9 12716.1 12806.4 1687.8 1806.5 1963.5 2130.8 2258.8 8902.9 8977.0 8980.6 9277.9 9312.8 1703.8 1721.6 1725.0 1788.1 1804.5 64058.4 69077.0 69387.3 70150.0 71127.3 10384.6 10389.8 10515.4 10680.6 10716.9 14344.7 14471.2 14471.9 14525.7 14526.7 6812.8 7560.6 7706.5 7712.5 7807.2 6906.9 7032.5 7053.1 7356.2 7390.2 4244.5 4329.7 4338.3 4358.0 4580.4 1837.6 1855.2 1857.1 1865.0 1877.3 787.2 858.4 859.0 916.6 948.6 17047.0 17669.9 17746.7 17850.0 18152.1 800.9 802.7 805.8 832.7 851.0 9500.8 9923.6 9959.7 9974.9 10116.7 342550.0 346612.0 347063.0 347094.0 350554.0 61360.0 76815.6 79964.0 82252.0 82381.0 820.5 834.3 853.6 855.6 863.3 12273.9 15648.5 15718.1 16343.0 16856.5 37332.6 37559.0 38020.0 38096.6 38557.6 46878.8 47424.8 48037.8 48201.3 48245.5 7277.6 7278.4 7324.6 7414.4 7663.4 10631.8 11272.1 11286.7 11292.7 11529.4 9824.9 9847.6 9872.9 9897.2 10259.3 9857.8 9862.8 9976.9 9984.9 10217.0 9648.9 9775.3 9945.4 9976.7 10081.1 7501.6 7544.2 7553.3 7622.4 7659.5 8945.7 9370.4 9810.5 10072.4 10234.6 12:59:33 INFO - PROCESS | 25571 | 12:59:33 INFO - PROCESS | 25571 | [#3] mootools.html Cycles:180 Average:5297.08 Median:4812.68 stddev:3051.27 (63.4%) stddev-sans-first:3045.74 12:59:33 INFO - PROCESS | 25571 | Values: 1395.3 1405.9 1491.4 1668.0 1747.0 7734.5 8202.5 8214.7 8215.6 8260.3 6665.1 6715.9 7690.0 7946.2 8113.8 2169.2 2205.3 2209.6 2211.5 2212.5 2160.9 2191.9 2248.1 2249.7 2253.9 1869.0 2163.2 2223.8 2229.0 2234.0 2205.4 2220.8 2231.5 2233.3 2272.6 2193.4 2195.1 2200.5 2202.4 2203.0 8234.9 8285.7 8290.6 8310.4 8317.9 4574.5 4664.4 4690.0 4700.2 4715.2 2621.5 2669.7 2718.9 2738.5 2762.1 808.4 817.2 823.9 824.7 832.4 11122.1 11129.5 11155.9 11157.9 11169.2 4070.9 4172.5 4289.8 4294.8 4356.0 9531.1 9580.1 9585.5 9589.6 9593.0 4800.5 4805.1 4805.4 4811.9 4813.4 4101.4 4349.7 4363.0 4364.0 4364.1 3526.1 3578.3 3631.2 3651.4 3670.8 931.0 1011.6 1025.4 1030.1 1040.8 621.4 735.4 786.8 792.2 827.5 5091.8 5196.4 5413.5 5573.7 5724.6 857.0 879.6 914.9 928.6 931.1 3680.6 3740.6 3753.2 3806.2 3836.5 9889.8 9987.6 10154.3 10258.4 10262.6 10104.1 10217.9 10222.5 10226.5 10232.6 6405.7 7322.3 7460.8 7618.5 7621.3 5605.3 5684.8 5810.7 5845.0 5859.4 9368.1 9395.0 9493.4 9497.7 9503.0 9285.8 9373.3 9465.2 9722.5 9722.6 6418.2 6450.3 6507.9 6562.0 6745.5 7731.3 7748.0 7763.5 7802.5 7805.1 6978.8 7118.5 7154.9 7250.0 7255.6 6905.4 7007.1 7030.1 7101.7 7153.1 6536.6 6563.5 6597.7 6672.2 6672.6 4561.8 4598.6 4650.9 4662.2 4711.6 4451.4 4512.0 4535.5 4587.7 4632.1 12:59:33 INFO - PROCESS | 25571 | 12:59:33 INFO - PROCESS | 25571 | [#4] prototype.html Cycles:180 Average:3935.76 Median:4355.60 stddev:1111.94 (25.5%) stddev-sans-first:1081.05 12:59:33 INFO - PROCESS | 25571 | Values: 299.9 301.7 305.6 307.7 311.3 4752.2 4785.8 4794.3 4800.5 4837.9 4726.4 4731.8 4742.4 4742.8 4745.1 3784.6 3798.3 3799.8 3816.5 3829.2 3508.2 3699.7 3822.8 3848.2 3857.5 3659.7 3697.6 3704.2 3710.6 3711.5 3690.2 3701.5 3705.9 3706.6 3708.3 3090.1 3171.2 3187.4 3260.4 3274.3 4638.0 4666.7 4667.6 4680.7 4683.3 4240.8 4243.6 4253.8 4256.7 4256.7 4770.3 4853.6 4977.4 4981.4 4991.0 3542.0 3574.7 3581.9 3584.8 3589.6 5212.6 5229.5 5233.5 5243.2 5262.1 3554.0 3580.0 3647.3 3649.2 3672.0 3675.4 3690.5 3694.2 3704.0 3704.2 4243.0 4246.2 4251.8 4266.6 4266.7 4855.0 4947.3 5002.3 5007.9 5054.5 1260.6 1270.1 1276.3 1284.8 1289.3 800.5 816.6 820.7 828.0 832.0 3976.5 3979.3 3991.2 3997.9 4009.1 4551.9 4559.0 4589.5 4594.3 4614.1 3921.5 3956.6 3974.2 3991.9 4003.7 2278.5 2431.5 2438.8 2441.3 2442.1 4343.6 4358.7 4361.9 4363.7 4396.2 4639.5 4657.2 4669.4 4672.8 4701.3 4093.6 4290.4 4348.5 4352.5 4372.9 4527.3 4528.6 4557.5 4563.2 4564.0 4463.4 4467.4 4473.6 4480.8 4519.2 4108.6 4324.5 4329.1 4333.5 4338.3 3343.6 3372.5 3383.8 3389.2 3391.3 4574.0 4613.6 4625.8 4627.3 4635.6 4495.7 4498.4 4501.5 4510.2 4518.0 4256.8 4425.7 4425.8 4427.3 4467.3 4365.4 4381.5 4386.8 4402.6 4455.5 4481.7 4495.0 4496.2 4810.7 4820.5 4899.9 4901.1 4904.3 4932.8 4939.2 12:59:33 INFO - PROCESS | 25571 | 12:59:33 INFO - PROCESS | 25571 | [#5] yui.html Cycles:180 Average:16410.80 Median:24529.10 stddev:22699.01 (92.5%) stddev-sans-first:22731.51 12:59:33 INFO - PROCESS | 25571 | Values: 565.9 619.6 638.3 652.1 724.7 12533.1 14040.6 14053.6 14073.2 14073.4 13203.2 13544.0 13552.2 13580.0 13583.3 621.3 744.8 762.0 784.6 787.9 751.3 752.8 753.9 757.9 765.5 808.8 812.8 814.2 814.3 814.6 641.6 792.6 804.1 809.0 820.1 607.6 607.8 607.8 607.8 607.9 12225.4 12256.3 12265.3 12265.6 12269.0 12501.9 12813.6 13071.8 13179.3 13255.9 10377.5 10405.4 10496.0 11632.9 11702.4 3239.2 3361.4 3482.4 4194.2 4233.0 62098.0 62551.4 63159.7 63277.0 63294.7 9856.9 9924.2 10010.8 10105.2 10330.5 18505.6 18561.4 18618.9 18637.3 18645.5 6658.6 6713.2 6820.7 6880.2 6978.9 3683.9 3786.6 3796.6 3806.4 3839.5 2722.7 2811.9 2814.0 2814.5 2816.6 481.5 483.1 486.2 493.9 494.2 417.5 491.0 512.4 521.4 524.5 7544.6 7569.2 7688.3 7731.5 7816.4 468.0 468.3 468.9 475.6 481.9 3600.0 3632.9 3753.3 3755.4 3756.6 52687.7 53987.0 57399.0 93685.0 93789.5 77977.0 83816.0 83841.6 83867.6 84919.2 46826.1 48572.0 55580.7 71328.0 76843.2 7054.0 7070.7 7166.7 7258.0 7265.5 42469.8 42974.6 44685.3 45245.0 45543.3 58922.0 63319.0 65422.0 65597.0 65682.7 9223.4 9468.1 9550.4 9644.3 9671.5 10768.9 10798.5 10824.9 10839.1 10948.1 8052.9 10605.1 10837.3 11030.9 11157.8 10065.6 11108.2 11142.5 11204.5 11526.7 11281.5 11428.9 11708.2 12472.4 12473.3 9796.4 10030.3 10121.1 10344.0 10354.5 9608.0 9764.5 9831.3 9853.8 9855.8 12:59:33 INFO - PROCESS | 25571 | -------- Summary: end -------- 12:59:33 INFO - PROCESS | 25571 | 12:59:34 INFO - TEST-INFO | 25571: exit 0 12:59:34 INFO - TEST-OK | dromaeo_css | took 1118546ms 12:59:34 INFO - TEST-START | kraken 12:59:34 INFO - operating with platform_type : linux_ 12:59:34 INFO - Initialising browser for kraken test... 12:59:34 INFO - TEST-INFO | started process 25757 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1s_9jy/profile http://localhost:56185/getInfo.html) 12:59:38 INFO - PROCESS | 25757 | __metrics Screen width/height:1600/1200 12:59:38 INFO - PROCESS | 25757 | colorDepth:24 12:59:38 INFO - PROCESS | 25757 | Browser inner width/height: 1024/697 12:59:38 INFO - PROCESS | 25757 | __metrics 12:59:39 INFO - PROCESS | 25757 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:59:39 INFO - PROCESS | 25757 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:59:40 INFO - TEST-INFO | 25757: exit 0 12:59:40 INFO - Browser initialized. 12:59:40 INFO - Running cycle 1/1 for kraken test... 12:59:40 INFO - TEST-INFO | started process 25842 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1s_9jy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:59:52 INFO - PROCESS | 25842 | RSS: Main: 159522816 12:59:52 INFO - PROCESS | 25842 | 12:59:54 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56185/tests/kraken/driver.html?testName=audio-beat-detection) 12:59:55 INFO - PROCESS | 25842 | RSS: Main: 217313280 12:59:55 INFO - PROCESS | 25842 | 12:59:58 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56185/tests/kraken/driver.html?testName=audio-dft) 12:59:59 INFO - PROCESS | 25842 | RSS: Main: 204546048 12:59:59 INFO - PROCESS | 25842 | 13:00:02 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56185/tests/kraken/driver.html?testName=audio-fft) 13:00:03 INFO - PROCESS | 25842 | RSS: Main: 270462976 13:00:03 INFO - PROCESS | 25842 | 13:00:05 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56185/tests/kraken/driver.html?testName=audio-oscillator) 13:00:05 INFO - PROCESS | 25842 | RSS: Main: 181846016 13:00:05 INFO - PROCESS | 25842 | 13:00:08 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56185/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:00:08 INFO - PROCESS | 25842 | RSS: Main: 186544128 13:00:08 INFO - PROCESS | 25842 | 13:00:11 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56185/tests/kraken/driver.html?testName=imaging-darkroom) 13:00:12 INFO - PROCESS | 25842 | RSS: Main: 222355456 13:00:12 INFO - PROCESS | 25842 | 13:00:15 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56185/tests/kraken/driver.html?testName=imaging-desaturate) 13:00:16 INFO - PROCESS | 25842 | RSS: Main: 222572544 13:00:16 INFO - PROCESS | 25842 | 13:00:19 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56185/tests/kraken/driver.html?testName=json-parse-financial) 13:00:19 INFO - PROCESS | 25842 | RSS: Main: 222846976 13:00:19 INFO - PROCESS | 25842 | 13:00:21 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56185/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:00:22 INFO - PROCESS | 25842 | RSS: Main: 208859136 13:00:22 INFO - PROCESS | 25842 | 13:00:24 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56185/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:00:24 INFO - PROCESS | 25842 | RSS: Main: 211922944 13:00:24 INFO - PROCESS | 25842 | 13:00:26 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56185/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:00:27 INFO - PROCESS | 25842 | RSS: Main: 210632704 13:00:27 INFO - PROCESS | 25842 | 13:00:30 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56185/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:00:30 INFO - PROCESS | 25842 | RSS: Main: 226770944 13:00:30 INFO - PROCESS | 25842 | 13:00:33 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56185/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:00:34 INFO - PROCESS | 25842 | RSS: Main: 227868672 13:00:34 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | Cycle 1(1): loaded http://localhost:56185/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56185/tests/kraken/driver.html?testName=ai-astar) 13:00:36 INFO - PROCESS | 25842 | RSS: Main: 270622720 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | __start_tp_report 13:00:36 INFO - PROCESS | 25842 | _x_x_mozilla_page_load 13:00:36 INFO - PROCESS | 25842 | _x_x_mozilla_page_load_details 13:00:36 INFO - PROCESS | 25842 | |i|pagename|runs| 13:00:36 INFO - PROCESS | 25842 | |0;ai-astar;104;114;114;113;113;114;112;113;114;114 13:00:36 INFO - PROCESS | 25842 | |1;audio-beat-detection;159;121;127;183;123;124;143;123;182;123 13:00:36 INFO - PROCESS | 25842 | |2;audio-dft;162;161;161;160;162;162;161;162;162;162 13:00:36 INFO - PROCESS | 25842 | |3;audio-fft;75;75;76;132;76;77;75;75;99;77 13:00:36 INFO - PROCESS | 25842 | |4;audio-oscillator;86;85;112;86;86;85;123;85;85;119 13:00:36 INFO - PROCESS | 25842 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;117;117;117 13:00:36 INFO - PROCESS | 25842 | |6;imaging-darkroom;146;146;148;147;146;146;146;146;146;146 13:00:36 INFO - PROCESS | 25842 | |7;imaging-desaturate;95;94;95;95;95;95;94;95;95;95 13:00:36 INFO - PROCESS | 25842 | |8;json-parse-financial;63;63;64;63;65;64;65;65;64;65 13:00:36 INFO - PROCESS | 25842 | |9;json-stringify-tinderbox;66;66;66;66;66;65;65;66;66;68 13:00:36 INFO - PROCESS | 25842 | |10;stanford-crypto-aes;80;81;79;79;79;78;81;80;78;84 13:00:36 INFO - PROCESS | 25842 | |11;stanford-crypto-ccm;127;125;137;129;128;128;127;125;221;143 13:00:36 INFO - PROCESS | 25842 | |12;stanford-crypto-pbkdf2;172;167;166;169;169;170;164;166;171;165 13:00:36 INFO - PROCESS | 25842 | |13;stanford-crypto-sha256-iterative;66;65;66;66;66;65;65;65;66;65 13:00:36 INFO - PROCESS | 25842 | __end_tp_report 13:00:36 INFO - PROCESS | 25842 | __start_cc_report 13:00:36 INFO - PROCESS | 25842 | _x_x_mozilla_cycle_collect,1928 13:00:36 INFO - PROCESS | 25842 | __end_cc_report 13:00:36 INFO - PROCESS | 25842 | __startTimestamp1468440036475__endTimestamp 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | ------- Summary: start ------- 13:00:36 INFO - PROCESS | 25842 | Number of tests: 14 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#0] ai-astar Cycles:10 Average:112.50 Median:114.00 stddev:3.06 (2.7%) stddev-sans-first:0.73 13:00:36 INFO - PROCESS | 25842 | Values: 104.0 114.0 114.0 113.0 113.0 114.0 112.0 113.0 114.0 114.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:135.00 stddev:25.01 (18.5%) stddev-sans-first:25.65 13:00:36 INFO - PROCESS | 25842 | Values: 159.0 121.0 127.0 183.0 123.0 124.0 143.0 123.0 182.0 123.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.71 (0.4%) stddev-sans-first:0.73 13:00:36 INFO - PROCESS | 25842 | Values: 162.0 161.0 161.0 160.0 162.0 162.0 161.0 162.0 162.0 162.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#3] audio-fft Cycles:10 Average:83.70 Median:76.00 stddev:18.49 (24.3%) stddev-sans-first:19.35 13:00:36 INFO - PROCESS | 25842 | Values: 75.0 75.0 76.0 132.0 76.0 77.0 75.0 75.0 99.0 77.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:85.00 stddev:15.96 (18.8%) stddev-sans-first:16.57 13:00:36 INFO - PROCESS | 25842 | Values: 86.0 85.0 112.0 86.0 86.0 85.0 123.0 85.0 85.0 119.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 13:00:36 INFO - PROCESS | 25842 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 13:00:36 INFO - PROCESS | 25842 | Values: 146.0 146.0 148.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:00:36 INFO - PROCESS | 25842 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:64.50 stddev:0.88 (1.4%) stddev-sans-first:0.83 13:00:36 INFO - PROCESS | 25842 | Values: 63.0 63.0 64.0 63.0 65.0 64.0 65.0 65.0 64.0 65.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 13:00:36 INFO - PROCESS | 25842 | Values: 66.0 66.0 66.0 66.0 66.0 65.0 65.0 66.0 66.0 68.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:80.00 stddev:1.79 (2.2%) stddev-sans-first:1.90 13:00:36 INFO - PROCESS | 25842 | Values: 80.0 81.0 79.0 79.0 79.0 78.0 81.0 80.0 78.0 84.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#11] stanford-crypto-ccm Cycles:10 Average:139.00 Median:128.50 stddev:29.37 (22.9%) stddev-sans-first:30.83 13:00:36 INFO - PROCESS | 25842 | Values: 127.0 125.0 137.0 129.0 128.0 128.0 127.0 125.0 221.0 143.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:169.00 stddev:2.69 (1.6%) stddev-sans-first:2.40 13:00:36 INFO - PROCESS | 25842 | Values: 172.0 167.0 166.0 169.0 169.0 170.0 164.0 166.0 171.0 165.0 13:00:36 INFO - PROCESS | 25842 | 13:00:36 INFO - PROCESS | 25842 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 13:00:36 INFO - PROCESS | 25842 | Values: 66.0 65.0 66.0 66.0 66.0 65.0 65.0 65.0 66.0 65.0 13:00:36 INFO - PROCESS | 25842 | -------- Summary: end -------- 13:00:36 INFO - PROCESS | 25842 | 13:00:37 INFO - TEST-INFO | 25842: exit 0 13:00:37 INFO - TEST-OK | kraken | took 63077ms 13:00:37 INFO - SUITE-END | took 1181s 13:00:37 INFO - Completed test suite (00:19:41) 13:00:37 INFO - javascript benchmark 13:00:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2566.2814411964646, 2573.922782316531, 2602.7397260273974, 2615.2546524240597, 2619.3713508757905, 4418.050119224366, 4481.327800829869, 4548.613367863732, 4585.610223131033, 4595.333676616893, 4505.234110201265, 4540.477845047821, 4555.567221113989, 4602.1255961367315, 4608.285715714062, 4182.35173548099, 4216.57834216579, 4238.343056826189, 4252.38340440636, 4265.0403659176745, 4244.299690551055, 4315.180115778008, 4316.589923957219, 4335.0679603885155, 4343.305071188611, 4270.039241170743, 4296.849610263657, 4308.849190278341, 4313.28830742927, 4344.761038142901, 4096.73371230869, 4149.813258403379, 4158.438610787535, 4176.874693759192, 4224.345226489899, 4133.772642504661, 4147.212029714345, 4189.371594260855, 4197.454330936989, 4233.978830105861, 4575.336576196466, 4588.449386073693, 4593.173228818811, 4612.400387949545, 4625.306204069383, 4660.533946605312, 4825.227963525819, 4871.537203965618, 4885.682430641997, 4949.158643030693, 4919.655624106314, 4955.1080805455, 4965.354503914504, 4976.601871850244, 4981.153203955337, 4407.603315701603, 4584.083183363341, 4588.242939914943, 4591.908161836778, 4628.514006029386, 5019.47295533971, 5063.291139240509, 5075.416327122354, 5080.110980578384, 5139.357580302462, 4873.975630121827, 4888.169011268093, 4899.730514821647, 4933.28467372229, 4934.7285899275885, 4744.905101897943, 4796.712197268175, 4893.657443978887, 4925.162722337212, 4934.777934992934, 4809.254565542346, 4820.421549414023, 4877.756112194376, 4889.0, 4908.901821963612, 4871.3667223260745, 4881.609471242363, 4885.071836351082, 4893.437254715682, 4936.185529387682, 3998.5601583825755, 4046.2716710992304, 4144.48193975753, 4151.709380343347, 4192.748435093904, 3607.9278414431565, 3612.3497770401054, 3634.0914771307175, 3649.197176621139, 3680.190358121209, 4204.978975105166, 4208.263553878059, 4213.810378532973, 4227.112306415687, 4254.510731265882, 4922.507749225048, 4925.605951523941, 4932.0, 4932.136876046678, 4966.8758281043265, 4101.22076805412, 4169.228692691861, 4217.283061879488, 4230.5769423057445, 4233.428487154257, 4406.801693923781, 4418.80115394808, 4420.359047938031, 4441.533638967927, 4459.019015816515, 4434.201843668171, 4647.070585882837, 4658.953410465988, 4660.813567457264, 4664.486906440221, 4851.151048566558, 4864.36763220779, 4883.853484395474, 4884.560389564814, 4895.290182923385, 4615.93076103852, 4625.375574297427, 4628.213203755437, 4629.213033784197, 4647.488776234679, 4599.310103484504, 4771.045790841915, 4826.300186473012, 4826.32431459603, 4864.2460418633755, 4871.171900776805, 4882.487338829372, 4889.462159162418, 4910.484399138039, 4948.38145231846, 4755.334253204624, 4762.499937506512, 4764.976175119103, 4766.332713420194, 4802.73584952817, 4618.376519169869, 4724.078804633064, 4765.451973023008, 4766.475687674422, 4806.47128815837, 4683.508231635629, 4693.0, 4707.788149533348, 4731.526847315241, 4741.980474198064, 4794.5924596409695, 4801.18379875415, 4805.567498925114, 4837.467878533429, 4850.0, 4513.638908887216, 4680.438347398334, 4685.578297953064, 4703.647226458098, 4720.079584481131], "value": 4498.953292056543, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.382906461022, 584.0800738836331, 585.259646547118, 589.8053642298042, 590.6987436407431, 27656.17031489056, 40469.0, 41778.99999999996, 41864.58135418645, 45596.0, 42961.0, 43361.0, 43380.132397352034, 43423.78288108563, 43430.0, 7523.736669216579, 7763.689452421896, 7896.447248692594, 7902.6443810028395, 7906.2884340409355, 7432.442566807484, 7687.5387476751575, 7691.0, 9867.457289849055, 10209.183265338792, 9883.209343252558, 9911.553980070878, 9912.256580756437, 9914.305998580103, 9923.454210018446, 10169.0, 10175.949120254389, 10229.18166546682, 10237.590496380137, 10245.846312305284, 7426.591537465437, 7653.808654783619, 7656.157822639505, 9762.90237097627, 10153.4923253837, 39417.0, 39861.40207896884, 39905.201895961916, 39928.20143597112, 39942.0, 2857.985710071436, 2884.971150288491, 2939.1182645206354, 3362.9831850840587, 3580.5753293009784, 30042.84978575115, 30129.397412051636, 30426.0, 30479.39041219163, 31499.527507087412, 2659.7074321824775, 2673.6665224935177, 2692.8788204530647, 2765.6681198256138, 2776.1532732516575, 250568.99999999817, 251261.0, 251322.0, 251909.0, 251952.0, 54565.72717136389, 54678.72660636671, 54735.45264547304, 54782.178267326024, 54787.0, 59001.70499147477, 59284.11073833896, 59398.0, 59417.32622032019, 59462.70268648716, 15113.0, 15528.0, 15620.531384058497, 15630.3747850087, 15642.68714625701, 10299.279050466545, 10440.060394564525, 10442.321249118782, 10460.215483838743, 10524.36853788775, 17152.313907443564, 17611.911940440215, 17710.468685939442, 18186.181621826785, 18221.45335639933, 6166.383361663798, 6190.566660333823, 6278.215223097113, 6314.589551679126, 6348.238211414659, 802.9638666260032, 876.3646356391565, 882.7087061269766, 918.9035151309016, 922.6586163119555, 28667.0, 28722.9787829704, 28783.56824647632, 28836.42327153445, 28898.42203155925, 906.1346414174448, 982.4940155819759, 992.692265397729, 1007.189212683782, 1027.198784947742, 14063.507777227747, 14735.926320368544, 14762.409503619734, 14847.628809279855, 14907.925460372846, 363728.0, 363918.0, 368111.0, 368891.0, 371954.0, 49770.0, 50191.0, 50195.239261840215, 50268.74865625649, 50281.0, 1089.477051015517, 1182.597916708306, 1210.4794938176492, 1225.8957988571071, 1227.9447424865903, 28027.71972280251, 29154.0, 29580.408391832472, 29661.0, 30055.549166762954, 43366.56633433751, 43610.563894360654, 43808.342874856266, 43939.78030109957, 43996.34005489856, 302342.0, 302584.0, 304582.0, 304891.0, 305134.0, 22185.667214991467, 24942.251732448054, 25657.743422565534, 25676.614850777627, 26959.46081078407, 41850.1629967405, 41941.95145121274, 41959.580404196786, 42698.14603707971, 42911.356329655704, 36130.45804312885, 36411.45382819207, 36428.0, 36437.0, 37064.07339816526, 35959.29550683549, 36712.63287367199, 36749.81625091964, 36805.0, 36844.0, 35467.645323546436, 35608.46587301141, 36120.27759444744, 36188.63811361853, 36503.26993460169, 31525.0, 32541.674583253865, 32552.674473255913, 32730.0, 32731.509027365086, 25080.74919250857, 25572.616410754224, 25600.0, 25939.0, 25956.0], "value": 17854.445884340243, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.12414701304914, 373.1790061863899, 384.9056981039646, 389.9376099824029, 391.95884432134625, 16846.747298790528, 17166.65666686667, 17667.293308267686, 17723.379681711143, 18141.608382880815, 16976.236069376875, 17169.141542922865, 17673.82326176738, 17692.20385082671, 17731.202095905683, 4611.930821037688, 4772.665913386064, 4800.183968725308, 4818.638602104838, 4820.469748327681, 3436.1237884339457, 3622.7645203061716, 4838.927416088762, 6206.8448288792915, 6218.626882387049, 6259.937400626004, 6272.278687950876, 6287.2140982377205, 6291.811245662638, 6303.495720342362, 5488.17677348397, 5536.307961504812, 5548.639338442988, 5597.44025597441, 6249.718762655669, 5359.169328754049, 5382.542483888874, 5406.162044883049, 5411.485908838694, 5415.187721841716, 12406.0, 12457.813132803016, 12535.93732031334, 12716.109872308942, 12806.359682015862, 1687.7805885234964, 1806.4851517317502, 1963.4796778853615, 2130.7762684918016, 2258.7802586603216, 8902.86645700315, 8977.0, 8980.595873185723, 9277.907220927771, 9312.813743725155, 1703.821098784622, 1721.5868191634042, 1724.9822604663184, 1788.097011009445, 1804.4857215693464, 64058.35941640617, 69077.0, 69387.3061269386, 70150.0, 71127.28872711258, 10384.636537721144, 10389.84415233772, 10515.369077855354, 10680.57277708883, 10716.892831071746, 14344.713105737826, 14471.204083775494, 14471.92764036194, 14525.709485810225, 14526.709465810625, 6812.795616131524, 7560.58416787082, 7706.499077559941, 7712.460127791001, 7807.180246074194, 6906.896396554056, 7032.5077244592385, 7053.083099197174, 7356.190819009905, 7390.150132734697, 4244.533101358848, 4329.718568293114, 4338.327559228324, 4358.0, 4580.358749775034, 1837.604914943267, 1855.1893880770642, 1857.1178690122354, 1865.0, 1877.2584828992472, 787.2127872127872, 858.4463021351194, 858.9527575983255, 916.6287653500343, 948.6300342866288, 17047.0, 17669.91165044192, 17746.733798993024, 17850.018248996428, 18152.09239538018, 800.8798680198017, 802.6949759091508, 805.8227190018187, 832.6669332267021, 851.0425771007616, 9500.809983800285, 9923.60305587783, 9959.701208963743, 9974.900250997542, 10116.696499105039, 342550.0, 346612.0, 347063.0, 347094.0, 350554.0, 61360.0, 76815.61592192003, 79964.0, 82252.0, 82381.0, 820.5117954816874, 834.2533432577809, 853.6158728572043, 855.6149732620346, 863.2575984653193, 12273.877261227273, 15648.530544083696, 15718.13550254747, 16343.0, 16856.4556532497, 37332.626673734005, 37559.0, 38020.0, 38096.61903380931, 38557.614423856525, 46878.76560617175, 47424.7628761854, 48037.75981120072, 48201.276980846014, 48245.5175448241, 7277.636118194175, 7278.381337586153, 7324.5971471567445, 7414.369778568882, 7663.386929045551, 10631.840522392326, 11272.098232141245, 11286.66140015801, 11292.661220163409, 11529.423528823692, 9824.852627210741, 9847.60609575609, 9872.851907221253, 9897.158741507052, 10259.333143345659, 9857.802843943224, 9862.802743944938, 9976.850347244943, 9984.850227246743, 10217.0, 9648.855267170858, 9775.315727898978, 9945.353552019096, 9976.700698979042, 10081.14310283634, 7501.6249187539315, 7544.170141284564, 7553.282438168365, 7622.352100071556, 7659.463837531319, 8945.702873083237, 9370.39092458988, 9810.509474526103, 10072.395656260647, 10234.6417875374], "value": 8068.479344089936, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.337214822959, 1405.9367328470219, 1491.433255362962, 1667.9817420920685, 1746.9825301746978, 7734.535927844333, 8202.466839655426, 8214.671413143482, 8215.58922053898, 8260.256576908077, 6665.13353264075, 6715.932840671592, 7689.961550192241, 7946.205379462065, 8113.797155071126, 2169.208238992772, 2205.3383984804573, 2209.5801797658473, 2211.458192742772, 2212.4690074382193, 2160.91356345746, 2191.857529260601, 2248.067052173346, 2249.741279752825, 2253.876036817974, 1869.0, 2163.1779923629, 2223.7665045170265, 2229.0, 2233.966490502644, 2205.4376134085865, 2220.766819483947, 2231.542533780571, 2233.3188377544925, 2272.6022945984387, 2193.363924461904, 2195.1438938813876, 2200.526886719363, 2202.41635966469, 2203.019656252968, 8234.876476852853, 8285.710000149966, 8290.585470726439, 8310.376721745833, 8317.916820831775, 4574.5425457453985, 4664.416947881515, 4689.9765501172615, 4700.1539722849875, 4715.174844402216, 2621.5281249375107, 2669.719679433651, 2718.9184324470298, 2738.4796888591304, 2762.1023167470653, 808.3815880851154, 817.1950628630794, 823.9464434811778, 824.674253669797, 832.367400775409, 11122.054625356936, 11129.499172537255, 11155.944220278847, 11157.944210278978, 11169.16231282649, 4070.9389359159636, 4172.4784401949755, 4289.849855255051, 4294.806733696991, 4355.956440435619, 9531.094546018117, 9580.0898914603, 9585.472798996123, 9589.616415343447, 9592.95203523978, 4800.543948324904, 4805.063012712558, 4805.399325084364, 4811.903761924742, 4813.446453657804, 4101.446304748821, 4349.739015659007, 4362.978185109054, 4363.956360436418, 4364.148990946735, 3526.118470382404, 3578.3380074686265, 3631.219287853124, 3651.3975194092677, 3670.8164591770305, 931.0410277414275, 1011.5700827148432, 1025.410389026313, 1030.0832259289239, 1040.8230600797883, 621.3599992008238, 735.3602365941665, 786.7836345005169, 792.2394501278721, 827.5133224659978, 5091.7963281469065, 5196.428392876781, 5413.539849112791, 5573.69344686038, 5724.599278050579, 857.0272740439522, 879.5531350927688, 914.8627705844177, 928.6007016982762, 931.1154403316876, 3680.595134535199, 3740.625937406292, 3753.155540003477, 3806.1626442182674, 3836.48207491985, 9889.75275618101, 9987.60049598008, 10154.339967902062, 10258.384496930208, 10262.589496420209, 10104.090631843022, 10217.948910255402, 10222.539985700661, 10226.488675566192, 10232.64185753495, 6405.7117429714845, 7322.340989310987, 7460.813479663052, 7618.466707330434, 7621.31408173256, 5605.299337582802, 5684.8294551163535, 5810.738516766671, 5844.970775146097, 5859.355470898283, 9368.063193680577, 9395.013523579926, 9493.430394176372, 9497.715068547679, 9502.952485237529, 9285.814283714153, 9373.25013998892, 9465.195458386115, 9722.465264410524, 9722.562484688064, 6418.229812422539, 6450.290468048604, 6507.869842603216, 6561.967190164019, 6745.52781305304, 7731.304182623591, 7747.961260193663, 7763.534187948741, 7802.453828231969, 7805.063392392949, 6978.79063628092, 7118.537295075804, 7154.928450715634, 7250.0, 7255.56466612005, 6905.37851593359, 7007.124109486314, 7030.121234845645, 7101.715931362688, 7153.105861767279, 6536.640484773383, 6563.507736919654, 6597.6701164940605, 6672.232693240146, 6672.633005184762, 4561.794719237576, 4598.586127248564, 4650.906981860411, 4662.160811054043, 4711.646626502958, 4451.399061126706, 4512.029913568598, 4535.523770004102, 4587.655925805511, 4632.073585282889], "value": 4198.041835023896, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.9085278989909, 301.7224153778524, 305.6423983938792, 307.66618219232134, 311.2855995490352, 4752.192127338352, 4785.822450784894, 4794.304825800257, 4800.543948324908, 4837.88779537393, 4726.385569875912, 4731.834385796497, 4742.383490146285, 4742.7865746041425, 4745.050989802036, 3784.621537846207, 3798.2593394288124, 3799.7530160539613, 3816.5229346331707, 3829.215010922768, 3508.245727168658, 3699.6855267302176, 3822.7515211511163, 3848.2111167210655, 3857.498525191735, 3659.6706296433317, 3697.5562932448142, 3704.166562523424, 3710.5547334319785, 3711.498947642074, 3690.1881586051027, 3701.463287823264, 3705.8517659293598, 3706.629337066299, 3708.313961917027, 3090.134762266569, 3171.1913462067027, 3187.3943950649536, 3260.4457242268873, 3274.3123943971796, 4638.0, 4666.6500012498855, 4667.603253723438, 4680.672352935296, 4683.320918466803, 4240.777009472792, 4243.596858298457, 4253.787310634456, 4256.702030857841, 4256.702030857841, 4770.260609605517, 4853.563179313879, 4977.427595826454, 4981.427135879348, 4990.975045124787, 3541.9645803541894, 3574.749767516275, 3581.928361432757, 3584.7669901456584, 3589.6410358964154, 5212.635115541913, 5229.5293423591675, 5233.476652334774, 5243.213517972296, 5262.079341040531, 3553.969348888845, 3579.982100089483, 3647.2705458908326, 3649.197176621139, 3671.9816400917825, 3675.3751862183494, 3690.538682664667, 3694.242680250542, 3704.0, 3704.2221133561716, 4243.0241044559925, 4246.150769846043, 4251.829926802955, 4266.5946735060115, 4266.722663026893, 4855.0, 4947.332110165154, 5002.349694539686, 5007.9499205008215, 5054.519820617035, 1260.615512268767, 1270.104576273725, 1276.3490619783977, 1284.81796746993, 1289.2715615677112, 800.5196881870947, 816.603947085663, 820.6840366458956, 827.9691783729296, 831.9792005199919, 3976.5029371328583, 3979.348570343333, 3991.1818077294083, 3997.8800635980965, 4009.0533457908414, 4551.906123183373, 4558.9772051139535, 4589.5181005994555, 4594.264917613233, 4614.123316569841, 3921.5490218625223, 3956.6043395660204, 3974.2250261198787, 3991.940120898189, 4003.679705623543, 2278.512172906948, 2431.4893872286666, 2438.7683170098812, 2441.279822452381, 2442.072012635187, 4343.587359200871, 4358.714179317046, 4361.9127617447475, 4363.672724545671, 4396.230659634552, 4639.466461356988, 4657.208274593328, 4669.4396672399525, 4672.813087476463, 4701.318308845198, 4093.643212565727, 4290.356446533045, 4348.521662617173, 4352.477702675699, 4372.868813935587, 4527.320901864615, 4528.637708983208, 4557.498675145797, 4563.174730107983, 4564.0, 4463.419755431773, 4467.396901418397, 4473.642108631367, 4480.759620189905, 4519.209138400833, 4108.609682080198, 4324.51340479232, 4329.1341731653165, 4333.501647310473, 4338.26249537573, 3343.5987681478377, 3372.4772660237704, 3383.7969721815784, 3389.203537168714, 3391.253924136686, 4573.977130114328, 4613.607843333354, 4625.825861844927, 4627.282771170474, 4635.629149668086, 4495.685302028826, 4498.415206023196, 4501.482329532015, 4510.188166130128, 4517.977410112929, 4256.787160641894, 4425.734455932655, 4425.756583387944, 4427.29163333865, 4467.285234362486, 4365.36298887917, 4381.518033016302, 4386.780660967002, 4402.603765661106, 4455.532169122195, 4481.708688935209, 4494.977525112354, 4496.168208881237, 4810.687305325143, 4820.505643250463, 4899.902001960012, 4901.142300097397, 4904.2643603459765, 4932.8026878925875, 4939.209726443752], "value": 3590.2785363055746, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.91511273309, 619.6189343553716, 638.332942075531, 652.0935899100249, 724.7208676685648, 12533.122681412306, 14040.578782636512, 14053.57839264821, 14073.22597257153, 14073.4370625175, 13203.207807531555, 13543.984201184901, 13552.18686878788, 13580.0, 13583.252921089335, 621.2638023941644, 744.765398899345, 762.0436352377772, 784.5528049012065, 787.9369650427981, 751.3200553499092, 752.7929819299709, 753.9246075392472, 757.8635845547799, 765.5024234247728, 808.8422757562278, 812.7642983534768, 814.2142832166958, 814.3403842887251, 814.6415577146066, 641.6054126712086, 792.6116203060517, 804.1275216390194, 809.0493669937799, 820.1347578304886, 607.6172011632688, 607.781198768443, 607.7842365960065, 607.8055022392836, 607.9483243924271, 12225.449854756558, 12256.325902075383, 12265.325407102606, 12265.570705025371, 12269.0, 12501.874981250163, 12813.615591532176, 13071.803922941164, 13179.275139867303, 13255.86744132556, 10377.533011014522, 10405.375677459377, 10496.0, 11632.883671163348, 11702.356370399624, 3239.1578189670618, 3361.37814504315, 3482.407990641597, 4194.182134483777, 4232.957670423287, 62098.0, 62551.37448625501, 63159.736805264096, 63277.0, 63294.68352658207, 9856.852147217798, 9924.163375753295, 10010.849837252446, 10105.24210684187, 10330.535125919352, 18505.629887402178, 18561.35035273759, 18618.906905465385, 18637.254509819457, 18645.533861653566, 6658.633775142317, 6713.160854893138, 6820.6930688119155, 6880.174379074543, 6978.930210697929, 3683.8710645127826, 3786.6402691744242, 3796.6203379662365, 3806.3909774435956, 3839.462475253467, 2722.6868910075596, 2811.9156425306865, 2813.971860281371, 2814.479321325561, 2816.5775133729694, 481.48481125195707, 483.09660934052846, 486.192919753208, 493.85925011371586, 494.15746152809373, 417.49900119855687, 490.9950900491021, 512.4132867866272, 521.4316395129251, 524.5384062025394, 7544.622768861535, 7569.243075692387, 7688.346490548254, 7731.458797884094, 7816.374690024784, 467.99766001169775, 468.2938395649027, 468.86637308366954, 475.64089112719677, 481.87712133405756, 3599.9560127562713, 3632.8546858125906, 3753.3244016076824, 3755.4366844973474, 3756.624337566276, 52687.73656131772, 53987.0, 57399.0, 93685.0, 93789.53105234567, 77977.0, 83816.0, 83841.58079209564, 83867.5806620963, 84919.15080849236, 46826.06347873092, 48572.0, 55580.72209638926, 71328.0, 76843.2315676836, 7054.047703559953, 7070.702056579687, 7166.677499512431, 7257.963710181415, 7265.455090868311, 42469.78765106278, 42974.570254298305, 44685.329720053574, 45245.0, 45543.31685024661, 58922.0, 63319.0, 65422.018669719044, 65597.0, 65682.67158664177, 9223.400478968846, 9468.100530449596, 9550.426974381282, 9644.324897257406, 9671.468069256258, 10768.946155269174, 10798.460076996276, 10824.891751082389, 10839.132869370274, 10948.069414099891, 8052.900810979414, 10605.098566621924, 10837.295575787548, 11030.889691103308, 11157.832632510357, 10065.647702330383, 11108.166887483309, 11142.498587563417, 11204.49579768896, 11526.654200374001, 11281.492332844877, 11428.94285528567, 11708.180427370002, 12472.438740256892, 12473.251604903735, 9796.363236389612, 10030.348027378197, 10121.139703125022, 10344.0, 10354.482275886327, 9607.951960240154, 9764.511774411108, 9831.262655301025, 9853.753656158653, 9855.802883942424], "value": 6245.934221272225, "unit": "score"}], "name": "dromaeo_css", "value": 6274.501061979634, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 113.0, 113.0, 114.0, 112.0, 113.0, 114.0, 114.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 121.0, 127.0, 183.0, 123.0, 124.0, 143.0, 123.0, 182.0, 123.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 160.0, 162.0, 162.0, 161.0, 162.0, 162.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 132.0, 76.0, 77.0, 75.0, 75.0, 99.0, 77.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 112.0, 86.0, 86.0, 85.0, 123.0, 85.0, 85.0, 119.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 148.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 63.0, 65.0, 64.0, 65.0, 65.0, 64.0, 65.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0, 66.0, 68.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 79.0, 79.0, 79.0, 78.0, 81.0, 80.0, 78.0, 84.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 125.0, 137.0, 129.0, 128.0, 128.0, 127.0, 125.0, 221.0, 143.0], "value": 139.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 167.0, 166.0, 169.0, 169.0, 170.0, 164.0, 166.0, 171.0, 165.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 66.0, 65.0, 65.0, 65.0, 66.0, 65.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1534.3000000000002, "alertThreshold": 2.0}]} 13:00:37 INFO - Return code: 0 13:00:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:00:37 INFO - # TBPL SUCCESS # 13:00:37 INFO - Running post-action listener: _resource_record_post_action 13:00:37 INFO - ##### 13:00:37 INFO - ##### Finished run-tests step (success) 13:00:37 INFO - ##### 13:00:37 INFO - Running post-run listener: _resource_record_post_run 13:00:37 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 547385344; Read time: 4; Write time: 449968 13:00:37 INFO - TinderboxPrint: CPU usage
13.1% 13:00:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 13:00:37 INFO - TinderboxPrint: I/O write bytes / time
547,385,344 / 449,968 13:00:37 INFO - TinderboxPrint: CPU idle
8,310.9 (86.9%) 13:00:37 INFO - TinderboxPrint: CPU user
1,208.4 (12.6%) 13:00:37 INFO - TinderboxPrint: Swap in / out
0 / 0 13:00:37 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 258256896; Read time: 0; Write time: 237888 13:00:37 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 289128448; Read time: 0; Write time: 212080 13:00:38 INFO - Running post-run listener: _upload_blobber_files 13:00:38 INFO - Blob upload gear active. 13:00:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:00:38 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 13:00:38 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:00:38 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:00:38 INFO - (blobuploader) - INFO - Open directory for files ... 13:00:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:00:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:00:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:00:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:00:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:00:39 INFO - (blobuploader) - INFO - Done attempting. 13:00:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:00:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:00:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:00:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:00:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:00:43 INFO - (blobuploader) - INFO - Done attempting. 13:00:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:00:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:00:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:00:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:00:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:00:44 INFO - (blobuploader) - INFO - Done attempting. 13:00:44 INFO - (blobuploader) - INFO - Iteration through files over. 13:00:44 INFO - Return code: 0 13:00:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:00:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:00:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b50de5930a8225d74e2e1efeedd4defd535a7c170c19333574bf16528a4a23f591f8b39deaf9250917ccda34130ad14598939fb2dc273543aa4af14079c2337c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a58e6209532100e3a95525b163bcd856ef5a21c19df84e1f4bfe6bc7cd3026e0e073bc0479c943c0aadf0a507a7da5c14fda618bc9bcf4f4b2f7fe03a04a3c38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9fe570e0aee3e0cb0526a1ef5be6a07fba121a897c289e3fab68c4a7bca9fa4673f38bd1200b2754e73efee8312c4a1044f073a44f6f844872095440aece92fc"} 13:00:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:00:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:00:44 INFO - Contents: 13:00:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b50de5930a8225d74e2e1efeedd4defd535a7c170c19333574bf16528a4a23f591f8b39deaf9250917ccda34130ad14598939fb2dc273543aa4af14079c2337c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a58e6209532100e3a95525b163bcd856ef5a21c19df84e1f4bfe6bc7cd3026e0e073bc0479c943c0aadf0a507a7da5c14fda618bc9bcf4f4b2f7fe03a04a3c38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9fe570e0aee3e0cb0526a1ef5be6a07fba121a897c289e3fab68c4a7bca9fa4673f38bd1200b2754e73efee8312c4a1044f073a44f6f844872095440aece92fc"} 13:00:44 INFO - Running post-run listener: copy_logs_to_upload_dir 13:00:44 INFO - Copying logs to upload dir... 13:00:44 INFO - mkdir: /builds/slave/test/build/upload/logs 13:00:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.020760 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-13 13:00:43.934263) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 13:00:43.937957) ========= 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=ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b50de5930a8225d74e2e1efeedd4defd535a7c170c19333574bf16528a4a23f591f8b39deaf9250917ccda34130ad14598939fb2dc273543aa4af14079c2337c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a58e6209532100e3a95525b163bcd856ef5a21c19df84e1f4bfe6bc7cd3026e0e073bc0479c943c0aadf0a507a7da5c14fda618bc9bcf4f4b2f7fe03a04a3c38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9fe570e0aee3e0cb0526a1ef5be6a07fba121a897c289e3fab68c4a7bca9fa4673f38bd1200b2754e73efee8312c4a1044f073a44f6f844872095440aece92fc"} build_url:https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011462 build_url: 'https://queue.taskcluster.net/v1/task/C-H7HE6BSxe3vnRzWgC0Zw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b50de5930a8225d74e2e1efeedd4defd535a7c170c19333574bf16528a4a23f591f8b39deaf9250917ccda34130ad14598939fb2dc273543aa4af14079c2337c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a58e6209532100e3a95525b163bcd856ef5a21c19df84e1f4bfe6bc7cd3026e0e073bc0479c943c0aadf0a507a7da5c14fda618bc9bcf4f4b2f7fe03a04a3c38", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9fe570e0aee3e0cb0526a1ef5be6a07fba121a897c289e3fab68c4a7bca9fa4673f38bd1200b2754e73efee8312c4a1044f073a44f6f844872095440aece92fc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 13:00:43.964039) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 13:00:43.964450) ========= 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=ed14279e38b05397cf4aa391000001a4-1468438716.639723-982591449 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004415 ========= master_lag: 0.99 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 13:00:44.954002) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 13:00:44.954327) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 13:00:44.958069) ========= ========= Total master_lag: 1.08 =========