builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-036 starttime: 1470502693.03 results: success (0) buildid: 20160806072421 builduid: e567f4ce31f04d1cb928bf26dce163de revision: fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:13.033788) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:13.034334) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:13.034646) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:13.049569) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:13.049861) ========= 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=14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-06 09:58:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.2M=0s 2016-08-06 09:58:13 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131766 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:13.195906) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:13.196279) ========= 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=14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020540 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:13.245982) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:13.246350) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 --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 fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 --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=14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-06 09:58:13,337 truncating revision to first 12 chars 2016-08-06 09:58:13,337 Setting DEBUG logging. 2016-08-06 09:58:13,337 attempt 1/10 2016-08-06 09:58:13,337 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fa0934fbb4d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-06 09:58:13,808 unpacking tar archive at: fx-team-fa0934fbb4d5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.798171 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:14.058950) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:14.059330) ========= script_repo_revision: fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:14.059761) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:14.060059) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-06 09:58:14.104985) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-06 09:58:14.105329) ========= /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=14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:58:14 INFO - MultiFileLogger online at 20160806 09:58:14 in /builds/slave/test 09:58:14 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 09:58:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:58:14 INFO - {'append_to_log': False, 09:58:14 INFO - 'base_work_dir': '/builds/slave/test', 09:58:14 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:58:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:58:14 INFO - 'branch': 'Fx-Team-Non-PGO', 09:58:14 INFO - 'buildbot_json_path': 'buildprops.json', 09:58:14 INFO - 'config_files': ('talos/linux_config.py',), 09:58:14 INFO - 'default_actions': ('clobber', 09:58:14 INFO - 'read-buildbot-config', 09:58:14 INFO - 'download-and-extract', 09:58:14 INFO - 'populate-webroot', 09:58:14 INFO - 'create-virtualenv', 09:58:14 INFO - 'install', 09:58:14 INFO - 'run-tests'), 09:58:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:58:14 INFO - 'download_minidump_stackwalk': True, 09:58:14 INFO - 'download_symbols': 'ondemand', 09:58:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:58:14 INFO - 'tooltool.py': '/tools/tooltool.py', 09:58:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:58:14 INFO - '/tools/misc-python/virtualenv.py')}, 09:58:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:58:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:58:14 INFO - 'installer_path': 'installer.exe', 09:58:14 INFO - 'log_level': 'info', 09:58:14 INFO - 'log_name': 'talos', 09:58:14 INFO - 'log_to_console': True, 09:58:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:58:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:58:14 INFO - 'opt_config_files': (), 09:58:14 INFO - 'pip_index': False, 09:58:14 INFO - 'sps_profile': False, 09:58:14 INFO - 'sps_profile_interval': 0, 09:58:14 INFO - 'suite': 'dromaeojs', 09:58:14 INFO - 'system_bits': '32', 09:58:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:58:14 INFO - 'title': 'talos-linux64-ix-036', 09:58:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:58:14 INFO - 'use_talos_json': True, 09:58:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:58:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:58:14 INFO - 'work_dir': 'build'} 09:58:14 INFO - [mozharness: 2016-08-06 16:58:14.288348Z] Running clobber step. 09:58:14 INFO - Running pre-action listener: _resource_record_pre_action 09:58:14 INFO - Running main action method: clobber 09:58:14 INFO - rmtree: /builds/slave/test/build 09:58:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:58:15 INFO - Running post-action listener: _resource_record_post_action 09:58:15 INFO - [mozharness: 2016-08-06 16:58:15.250945Z] Finished clobber step (success) 09:58:15 INFO - [mozharness: 2016-08-06 16:58:15.251046Z] Running read-buildbot-config step. 09:58:15 INFO - Running pre-action listener: _resource_record_pre_action 09:58:15 INFO - Running main action method: read_buildbot_config 09:58:15 INFO - Using buildbot properties: 09:58:15 INFO - { 09:58:15 INFO - "project": "", 09:58:15 INFO - "product": "firefox", 09:58:15 INFO - "script_repo_revision": "production", 09:58:15 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:58:15 INFO - "repository": "", 09:58:15 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:58:15 INFO - "buildid": "20160806072421", 09:58:15 INFO - "pgo_build": "False", 09:58:15 INFO - "basedir": "/builds/slave/test", 09:58:15 INFO - "buildnumber": 2072, 09:58:15 INFO - "slavename": "talos-linux64-ix-036", 09:58:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:58:15 INFO - "platform": "ubuntu64_hw", 09:58:15 INFO - "scheduler": "tests-fx-team-linux64-talos-7-3600", 09:58:15 INFO - "branch": "fx-team", 09:58:15 INFO - "repo_path": "integration/fx-team", 09:58:15 INFO - "revision": "fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96", 09:58:15 INFO - "stage_platform": "linux64", 09:58:15 INFO - "builduid": "e567f4ce31f04d1cb928bf26dce163de", 09:58:15 INFO - "slavebuilddir": "test" 09:58:15 INFO - } 09:58:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:58:15 INFO - Running post-action listener: _resource_record_post_action 09:58:15 INFO - [mozharness: 2016-08-06 16:58:15.253457Z] Finished read-buildbot-config step (success) 09:58:15 INFO - [mozharness: 2016-08-06 16:58:15.253547Z] Running download-and-extract step. 09:58:15 INFO - Running pre-action listener: _resource_record_pre_action 09:58:15 INFO - Running main action method: download_and_extract 09:58:15 INFO - mkdir: /builds/slave/test/build/tests 09:58:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:15 INFO - trying https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:58:15 INFO - Downloading https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:58:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:58:15 INFO - Downloaded 1361 bytes. 09:58:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:58:15 INFO - Using the following test package requirements: 09:58:15 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:58:15 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:58:15 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:58:15 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:15 INFO - u'jsshell-linux-x86_64.zip'], 09:58:15 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:58:15 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:58:15 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:58:15 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:58:15 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:58:15 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:58:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:58:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:15 INFO - trying https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:58:15 INFO - Downloading https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:58:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:58:16 INFO - Downloaded 20254079 bytes. 09:58:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:58:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:58:17 INFO - Return code: 0 09:58:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:58:17 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:17 INFO - trying https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:58:17 INFO - Downloading https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:58:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:58:18 INFO - Downloaded 20254079 bytes. 09:58:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:58:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:58:20 INFO - Return code: 0 09:58: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:20 INFO - trying https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:58:20 INFO - Downloading https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:58:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:58:21 INFO - Downloaded 14413723 bytes. 09:58:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:58:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:58:21 INFO - Return code: 0 09:58:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:21 INFO - trying https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:58:21 INFO - Downloading https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:58:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:58:22 INFO - Downloaded 56127609 bytes. 09:58:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:58:22 INFO - mkdir: /builds/slave/test/properties 09:58:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:58:22 INFO - Writing to file /builds/slave/test/properties/build_url 09:58:22 INFO - Contents: 09:58:22 INFO - build_url:https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:58:23 INFO - Running post-action listener: _resource_record_post_action 09:58:23 INFO - Running post-action listener: set_extra_try_arguments 09:58:23 INFO - [mozharness: 2016-08-06 16:58:23.324712Z] Finished download-and-extract step (success) 09:58:23 INFO - [mozharness: 2016-08-06 16:58:23.324947Z] Running populate-webroot step. 09:58:23 INFO - Running pre-action listener: _resource_record_pre_action 09:58:23 INFO - Running main action method: populate_webroot 09:58:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:58:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:58:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:58:23 INFO - '--tppagecycles', 09:58:23 INFO - '7'], 09:58:23 INFO - 'tests': ['tsvgm']}}, 09:58:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:58:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:58:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:58:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:58:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:23 INFO - 'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:23 INFO - 'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['damp', 'tps']}, 09:58:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:23 INFO - 'tests': ['damp', 'tps']}, 09:58:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['dromaeo_dom']}, 09:58:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:58:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['basic_compositor_video']}, 09:58:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:58:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['a11yr', 09:58:23 INFO - 'ts_paint', 09:58:23 INFO - 'tpaint', 09:58:23 INFO - 'sessionrestore', 09:58:23 INFO - 'sessionrestore_no_auto_restore', 09:58:23 INFO - 'tabpaint']}, 09:58:23 INFO - 'other-e10s': {'tests': ['a11yr', 09:58:23 INFO - 'ts_paint', 09:58:23 INFO - 'tpaint', 09:58:23 INFO - 'sessionrestore', 09:58:23 INFO - 'sessionrestore_no_auto_restore', 09:58:23 INFO - 'tabpaint']}, 09:58:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:58:23 INFO - 'ts_paint', 09:58:23 INFO - 'tpaint', 09:58:23 INFO - 'sessionrestore', 09:58:23 INFO - 'sessionrestore_no_auto_restore', 09:58:23 INFO - 'tabpaint']}, 09:58:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:58:23 INFO - 'ts_paint', 09:58:23 INFO - 'tpaint', 09:58:23 INFO - 'sessionrestore', 09:58:23 INFO - 'sessionrestore_no_auto_restore', 09:58:23 INFO - 'tabpaint']}, 09:58:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['a11yr', 09:58:23 INFO - 'ts_paint', 09:58:23 INFO - 'tpaint', 09:58:23 INFO - 'sessionrestore', 09:58:23 INFO - 'sessionrestore_no_auto_restore', 09:58:23 INFO - 'tabpaint']}, 09:58:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['a11yr', 09:58:23 INFO - 'ts_paint', 09:58:23 INFO - 'tpaint', 09:58:23 INFO - 'sessionrestore', 09:58:23 INFO - 'sessionrestore_no_auto_restore', 09:58:23 INFO - 'tabpaint']}, 09:58:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['tsvgx', 09:58:23 INFO - 'tsvgr_opacity', 09:58:23 INFO - 'tart', 09:58:23 INFO - 'tscrollx', 09:58:23 INFO - 'cart']}, 09:58:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:58:23 INFO - 'tsvgr_opacity', 09:58:23 INFO - 'tart', 09:58:23 INFO - 'tscrollx', 09:58:23 INFO - 'cart']}, 09:58:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:23 INFO - 'talos_options': ['--disable-e10s'], 09:58:23 INFO - 'tests': ['tp5o']}, 09:58:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:23 INFO - 'tests': ['tp5o']}, 09:58:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:23 INFO - 'talos_options': ['--disable-e10s', 09:58:23 INFO - '--xperf_path', 09:58:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:23 INFO - 'tests': ['tp5n']}, 09:58:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:23 INFO - 'talos_options': ['--xperf_path', 09:58:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:23 INFO - 'tests': ['tp5n']}}, 09:58:23 INFO - 'talos.zip': {'path': '', 09:58:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:58:23 INFO - Running post-action listener: _resource_record_post_action 09:58:23 INFO - [mozharness: 2016-08-06 16:58:23.344132Z] Finished populate-webroot step (success) 09:58:23 INFO - [mozharness: 2016-08-06 16:58:23.344221Z] Running create-virtualenv step. 09:58:23 INFO - Running pre-action listener: _resource_record_pre_action 09:58:23 INFO - Running main action method: create_virtualenv 09:58:23 INFO - Creating virtualenv /builds/slave/test/build/venv 09:58:23 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 09:58:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:58:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:58:23 INFO - Using real prefix '/usr' 09:58:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:58:23 INFO - Installing distribute.............................................................................................................................................................................................done. 09:58:25 INFO - Installing pip.................done. 09:58:25 INFO - Return code: 0 09:58:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:58: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58: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', 'pip>=1.5'] in /builds/slave/test/build 09:58: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 pip>=1.5 09:58:26 INFO - Using env: {'DISPLAY': ':0', 09:58:26 INFO - 'HOME': '/home/cltbld', 09:58:26 INFO - 'LANG': 'en_US.UTF-8', 09:58:26 INFO - 'LANGUAGE': 'en_US:en', 09:58:26 INFO - 'LOGNAME': 'cltbld', 09:58:26 INFO - 'MAIL': '/var/mail/cltbld', 09:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:26 INFO - 'MOZ_NO_REMOTE': '1', 09:58:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:26 INFO - 'NO_EM_RESTART': '1', 09:58:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:26 INFO - 'PWD': '/builds/slave/test', 09:58:26 INFO - 'SHELL': '/bin/bash', 09:58:26 INFO - 'SHLVL': '1', 09:58:26 INFO - 'TERM': 'linux', 09:58:26 INFO - 'TMOUT': '86400', 09:58:26 INFO - 'USER': 'cltbld', 09:58:26 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:26 INFO - '_': '/tools/buildbot/bin/python'} 09:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:26 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 09:58:26 INFO - Cleaning up... 09:58:26 INFO - Return code: 0 09:58:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:26 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 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58:26 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 09:58:26 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 09:58:26 INFO - Using env: {'DISPLAY': ':0', 09:58:26 INFO - 'HOME': '/home/cltbld', 09:58:26 INFO - 'LANG': 'en_US.UTF-8', 09:58:26 INFO - 'LANGUAGE': 'en_US:en', 09:58:26 INFO - 'LOGNAME': 'cltbld', 09:58:26 INFO - 'MAIL': '/var/mail/cltbld', 09:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:26 INFO - 'MOZ_NO_REMOTE': '1', 09:58:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:26 INFO - 'NO_EM_RESTART': '1', 09:58:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:26 INFO - 'PWD': '/builds/slave/test', 09:58:26 INFO - 'SHELL': '/bin/bash', 09:58:26 INFO - 'SHLVL': '1', 09:58:26 INFO - 'TERM': 'linux', 09:58:26 INFO - 'TMOUT': '86400', 09:58:26 INFO - 'USER': 'cltbld', 09:58:26 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:26 INFO - '_': '/tools/buildbot/bin/python'} 09:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:26 INFO - Downloading/unpacking psutil>=3.1.1 09:58:26 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 09:58:26 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 09:58:26 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 09:58:26 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 09:58:26 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 09:58:26 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 09:58:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:58:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:28 INFO - Installing collected packages: psutil 09:58:28 INFO - Running setup.py install for psutil 09:58:28 INFO - building 'psutil._psutil_linux' extension 09:58:28 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 09:58:28 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 09:58:28 INFO - building 'psutil._psutil_posix' extension 09:58:28 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 09:58:28 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 09:58:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:28 INFO - Successfully installed psutil 09:58:28 INFO - Cleaning up... 09:58:28 INFO - Return code: 0 09:58:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:28 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 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58:28 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 09:58:28 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 09:58:28 INFO - Using env: {'DISPLAY': ':0', 09:58:28 INFO - 'HOME': '/home/cltbld', 09:58:28 INFO - 'LANG': 'en_US.UTF-8', 09:58:28 INFO - 'LANGUAGE': 'en_US:en', 09:58:28 INFO - 'LOGNAME': 'cltbld', 09:58:28 INFO - 'MAIL': '/var/mail/cltbld', 09:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:28 INFO - 'MOZ_NO_REMOTE': '1', 09:58:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:28 INFO - 'NO_EM_RESTART': '1', 09:58:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:28 INFO - 'PWD': '/builds/slave/test', 09:58:28 INFO - 'SHELL': '/bin/bash', 09:58:28 INFO - 'SHLVL': '1', 09:58:28 INFO - 'TERM': 'linux', 09:58:28 INFO - 'TMOUT': '86400', 09:58:28 INFO - 'USER': 'cltbld', 09:58:28 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:28 INFO - '_': '/tools/buildbot/bin/python'} 09:58:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:58:29 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 09:58:29 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 09:58:29 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 09:58:29 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 09:58:29 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 09:58:29 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 09:58:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:58:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:58:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:58:31 INFO - Installing collected packages: mozsystemmonitor 09:58:31 INFO - Running setup.py install for mozsystemmonitor 09:58:31 INFO - Successfully installed mozsystemmonitor 09:58:31 INFO - Cleaning up... 09:58:31 INFO - Return code: 0 09:58:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:58: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:31 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 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58:31 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 09:58:31 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 09:58:31 INFO - Using env: {'DISPLAY': ':0', 09:58:31 INFO - 'HOME': '/home/cltbld', 09:58:31 INFO - 'LANG': 'en_US.UTF-8', 09:58:31 INFO - 'LANGUAGE': 'en_US:en', 09:58:31 INFO - 'LOGNAME': 'cltbld', 09:58:31 INFO - 'MAIL': '/var/mail/cltbld', 09:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:31 INFO - 'MOZ_NO_REMOTE': '1', 09:58:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:31 INFO - 'NO_EM_RESTART': '1', 09:58:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:31 INFO - 'PWD': '/builds/slave/test', 09:58:31 INFO - 'SHELL': '/bin/bash', 09:58:31 INFO - 'SHLVL': '1', 09:58:31 INFO - 'TERM': 'linux', 09:58:31 INFO - 'TMOUT': '86400', 09:58:31 INFO - 'USER': 'cltbld', 09:58:31 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:31 INFO - '_': '/tools/buildbot/bin/python'} 09:58:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:31 INFO - Downloading/unpacking jsonschema==2.5.1 09:58:31 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 09:58:31 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 09:58:31 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 09:58:31 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 09:58:31 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 09:58:31 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 09:58:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:58:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:58:33 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 09:58:33 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 09:58:33 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 09:58:33 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 09:58:33 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 09:58:33 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 09:58:33 INFO - Downloading functools32-3.2.3-2.tar.gz 09:58:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:58:33 INFO - warning: no files found matching '*.txt' 09:58:33 INFO - no previously-included directories found matching 'build' 09:58:33 INFO - no previously-included directories found matching 'dist' 09:58:33 INFO - no previously-included directories found matching '.git*' 09:58:33 INFO - Installing collected packages: jsonschema, functools32 09:58:33 INFO - Running setup.py install for functools32 09:58:33 INFO - warning: no files found matching '*.txt' 09:58:33 INFO - no previously-included directories found matching 'build' 09:58:33 INFO - no previously-included directories found matching 'dist' 09:58:33 INFO - no previously-included directories found matching '.git*' 09:58:33 INFO - Successfully installed jsonschema functools32 09:58:33 INFO - Cleaning up... 09:58:33 INFO - Return code: 0 09:58:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:33 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58:33 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:58:33 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 functools32==3.2.3-2 09:58:33 INFO - Using env: {'DISPLAY': ':0', 09:58:33 INFO - 'HOME': '/home/cltbld', 09:58:33 INFO - 'LANG': 'en_US.UTF-8', 09:58:33 INFO - 'LANGUAGE': 'en_US:en', 09:58:33 INFO - 'LOGNAME': 'cltbld', 09:58:33 INFO - 'MAIL': '/var/mail/cltbld', 09:58:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:33 INFO - 'MOZ_NO_REMOTE': '1', 09:58:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:33 INFO - 'NO_EM_RESTART': '1', 09:58:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:33 INFO - 'PWD': '/builds/slave/test', 09:58:33 INFO - 'SHELL': '/bin/bash', 09:58:33 INFO - 'SHLVL': '1', 09:58:33 INFO - 'TERM': 'linux', 09:58:33 INFO - 'TMOUT': '86400', 09:58:33 INFO - 'USER': 'cltbld', 09:58:33 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:33 INFO - '_': '/tools/buildbot/bin/python'} 09:58:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:58:33 INFO - Cleaning up... 09:58:33 INFO - Return code: 0 09:58:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:34 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 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58:34 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 09:58:34 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 09:58:34 INFO - Using env: {'DISPLAY': ':0', 09:58:34 INFO - 'HOME': '/home/cltbld', 09:58:34 INFO - 'LANG': 'en_US.UTF-8', 09:58:34 INFO - 'LANGUAGE': 'en_US:en', 09:58:34 INFO - 'LOGNAME': 'cltbld', 09:58:34 INFO - 'MAIL': '/var/mail/cltbld', 09:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:34 INFO - 'MOZ_NO_REMOTE': '1', 09:58:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:34 INFO - 'NO_EM_RESTART': '1', 09:58:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:34 INFO - 'PWD': '/builds/slave/test', 09:58:34 INFO - 'SHELL': '/bin/bash', 09:58:34 INFO - 'SHLVL': '1', 09:58:34 INFO - 'TERM': 'linux', 09:58:34 INFO - 'TMOUT': '86400', 09:58:34 INFO - 'USER': 'cltbld', 09:58:34 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:34 INFO - '_': '/tools/buildbot/bin/python'} 09:58:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:34 INFO - Downloading/unpacking blobuploader==1.2.4 09:58:34 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 09:58:34 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 09:58:34 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 09:58:34 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 09:58:34 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 09:58:34 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 09:58:36 INFO - Downloading blobuploader-1.2.4.tar.gz 09:58:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:58:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:58:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58:36 INFO - Downloading docopt-0.6.1.tar.gz 09:58:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:58:36 INFO - Installing collected packages: blobuploader, requests, docopt 09:58:36 INFO - Running setup.py install for blobuploader 09:58:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:58:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:58:36 INFO - Running setup.py install for requests 09:58:37 INFO - Running setup.py install for docopt 09:58:37 INFO - Successfully installed blobuploader requests docopt 09:58:37 INFO - Cleaning up... 09:58:37 INFO - Return code: 0 09:58:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:37 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:37 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:37 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 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58:37 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 09:58:37 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 09:58:37 INFO - Using env: {'DISPLAY': ':0', 09:58:37 INFO - 'HOME': '/home/cltbld', 09:58:37 INFO - 'LANG': 'en_US.UTF-8', 09:58:37 INFO - 'LANGUAGE': 'en_US:en', 09:58:37 INFO - 'LOGNAME': 'cltbld', 09:58:37 INFO - 'MAIL': '/var/mail/cltbld', 09:58:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:37 INFO - 'MOZ_NO_REMOTE': '1', 09:58:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:37 INFO - 'NO_EM_RESTART': '1', 09:58:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:37 INFO - 'PWD': '/builds/slave/test', 09:58:37 INFO - 'SHELL': '/bin/bash', 09:58:37 INFO - 'SHLVL': '1', 09:58:37 INFO - 'TERM': 'linux', 09:58:37 INFO - 'TMOUT': '86400', 09:58:37 INFO - 'USER': 'cltbld', 09:58:37 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:37 INFO - '_': '/tools/buildbot/bin/python'} 09:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:37 INFO - Running setup.py (path:/tmp/pip-RHjk6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:37 INFO - Running setup.py (path:/tmp/pip-oBDlVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:37 INFO - Running setup.py (path:/tmp/pip-xOY3EA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:37 INFO - Running setup.py (path:/tmp/pip-2GFGiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:37 INFO - Running setup.py (path:/tmp/pip-3bfk8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:38 INFO - Running setup.py (path:/tmp/pip-btePWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:38 INFO - Running setup.py (path:/tmp/pip-ufLMMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:38 INFO - Running setup.py (path:/tmp/pip-y7d_la-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:38 INFO - Running setup.py (path:/tmp/pip-06KUyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:38 INFO - Running setup.py (path:/tmp/pip-fryT4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:38 INFO - Running setup.py (path:/tmp/pip-kNl686-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:38 INFO - Running setup.py (path:/tmp/pip-l8KtG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:38 INFO - Running setup.py (path:/tmp/pip-xB0M5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:38 INFO - Running setup.py (path:/tmp/pip-PSVXb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:38 INFO - Running setup.py (path:/tmp/pip-zFuMxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:38 INFO - Running setup.py (path:/tmp/pip-xcDDpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:38 INFO - Running setup.py (path:/tmp/pip-ekljbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:58:38 INFO - Running setup.py install for manifestparser 09:58:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:58:38 INFO - Running setup.py install for mozcrash 09:58:39 INFO - Running setup.py install for mozdebug 09:58:39 INFO - Running setup.py install for mozdevice 09:58:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:58:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:58:39 INFO - Running setup.py install for mozfile 09:58:39 INFO - Running setup.py install for mozhttpd 09:58:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:58:39 INFO - Running setup.py install for mozinfo 09:58:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:58:39 INFO - Running setup.py install for mozInstall 09:58:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:58:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:58:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:58:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:58:39 INFO - Running setup.py install for mozleak 09:58:39 INFO - Running setup.py install for mozlog 09:58:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:58:39 INFO - Running setup.py install for moznetwork 09:58:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Running setup.py install for mozprocess 09:58:40 INFO - Running setup.py install for mozprofile 09:58:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Running setup.py install for mozrunner 09:58:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Running setup.py install for mozscreenshot 09:58:40 INFO - Running setup.py install for moztest 09:58:40 INFO - Running setup.py install for mozversion 09:58:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:58:40 INFO - Cleaning up... 09:58:40 INFO - Return code: 0 09:58:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:40 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 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58:40 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 09:58:40 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 09:58:40 INFO - Using env: {'DISPLAY': ':0', 09:58:40 INFO - 'HOME': '/home/cltbld', 09:58:40 INFO - 'LANG': 'en_US.UTF-8', 09:58:40 INFO - 'LANGUAGE': 'en_US:en', 09:58:40 INFO - 'LOGNAME': 'cltbld', 09:58:40 INFO - 'MAIL': '/var/mail/cltbld', 09:58:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:40 INFO - 'MOZ_NO_REMOTE': '1', 09:58:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:40 INFO - 'NO_EM_RESTART': '1', 09:58:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:40 INFO - 'PWD': '/builds/slave/test', 09:58:40 INFO - 'SHELL': '/bin/bash', 09:58:40 INFO - 'SHLVL': '1', 09:58:40 INFO - 'TERM': 'linux', 09:58:40 INFO - 'TMOUT': '86400', 09:58:40 INFO - 'USER': 'cltbld', 09:58:40 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:40 INFO - '_': '/tools/buildbot/bin/python'} 09:58:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:40 INFO - Running setup.py (path:/tmp/pip-0QZRHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:40 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)) 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:40 INFO - Running setup.py (path:/tmp/pip-ZUHfAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:41 INFO - Running setup.py (path:/tmp/pip-VxawLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:41 INFO - Running setup.py (path:/tmp/pip-wWgCW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:41 INFO - Running setup.py (path:/tmp/pip-4GLkkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:41 INFO - Running setup.py (path:/tmp/pip-QsRP4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:41 INFO - Running setup.py (path:/tmp/pip-Tm79f1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:41 INFO - Running setup.py (path:/tmp/pip-zSYPLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:41 INFO - Running setup.py (path:/tmp/pip-bz55YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:41 INFO - Running setup.py (path:/tmp/pip-nbObK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:41 INFO - Running setup.py (path:/tmp/pip-KOKWeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:41 INFO - Running setup.py (path:/tmp/pip-Tw3OKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:41 INFO - Running setup.py (path:/tmp/pip-7zMWrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:41 INFO - Running setup.py (path:/tmp/pip-lPq88a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:41 INFO - Running setup.py (path:/tmp/pip-CGZvQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:41 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)) 09:58:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:41 INFO - Running setup.py (path:/tmp/pip-wGcvoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:42 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)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:42 INFO - Running setup.py (path:/tmp/pip-n4Slo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:42 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)) 09:58:42 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)) 09:58:42 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)) 09:58:42 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)) 09:58:42 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)) 09:58:42 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)) 09:58:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:42 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 09:58:42 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 09:58:42 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 09:58:42 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 09:58:42 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 09:58:42 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 09:58:44 INFO - Downloading blessings-1.6.tar.gz 09:58:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:58:44 INFO - Installing collected packages: blessings 09:58:44 INFO - Running setup.py install for blessings 09:58:44 INFO - Successfully installed blessings 09:58:44 INFO - Cleaning up... 09:58:44 INFO - Return code: 0 09:58:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:58:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:44 INFO - Reading from file tmpfile_stdout 09:58:44 INFO - Current package versions: 09:58:44 INFO - argparse == 1.2.1 09:58:44 INFO - blessings == 1.6 09:58:44 INFO - blobuploader == 1.2.4 09:58:44 INFO - docopt == 0.6.1 09:58:44 INFO - functools32 == 3.2.3-2 09:58:44 INFO - jsonschema == 2.5.1 09:58:44 INFO - manifestparser == 1.1 09:58:44 INFO - mozInstall == 1.12 09:58:44 INFO - mozcrash == 0.17 09:58:44 INFO - mozdebug == 0.1 09:58:44 INFO - mozdevice == 0.48 09:58:44 INFO - mozfile == 1.2 09:58:44 INFO - mozhttpd == 0.7 09:58:44 INFO - mozinfo == 0.9 09:58:44 INFO - mozleak == 0.1 09:58:44 INFO - mozlog == 3.3 09:58:44 INFO - moznetwork == 0.27 09:58:44 INFO - mozprocess == 0.23 09:58:44 INFO - mozprofile == 0.28 09:58:44 INFO - mozrunner == 6.12 09:58:44 INFO - mozscreenshot == 0.1 09:58:44 INFO - mozsystemmonitor == 0.3 09:58:44 INFO - moztest == 0.7 09:58:44 INFO - mozversion == 1.4 09:58:44 INFO - psutil == 3.1.1 09:58:44 INFO - requests == 1.2.3 09:58:44 INFO - wsgiref == 0.1.2 09:58:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:44 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 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58:44 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 09:58:44 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 09:58:44 INFO - Using env: {'DISPLAY': ':0', 09:58:44 INFO - 'HOME': '/home/cltbld', 09:58:44 INFO - 'LANG': 'en_US.UTF-8', 09:58:44 INFO - 'LANGUAGE': 'en_US:en', 09:58:44 INFO - 'LOGNAME': 'cltbld', 09:58:44 INFO - 'MAIL': '/var/mail/cltbld', 09:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:44 INFO - 'MOZ_NO_REMOTE': '1', 09:58:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:44 INFO - 'NO_EM_RESTART': '1', 09:58:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:44 INFO - 'PWD': '/builds/slave/test', 09:58:44 INFO - 'SHELL': '/bin/bash', 09:58:44 INFO - 'SHLVL': '1', 09:58:44 INFO - 'TERM': 'linux', 09:58:44 INFO - 'TMOUT': '86400', 09:58:44 INFO - 'USER': 'cltbld', 09:58:44 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:44 INFO - '_': '/tools/buildbot/bin/python'} 09:58:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:44 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)) 09:58:44 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)) 09:58:44 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)) 09:58:44 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)) 09:58:44 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)) 09:58:44 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)) 09:58:44 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)) 09:58:44 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)) 09:58:44 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)) 09:58:44 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)) 09:58:44 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)) 09:58:44 INFO - Cleaning up... 09:58:44 INFO - Return code: 0 09:58:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:58:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11918a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ef180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, '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': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', '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 09:58:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:58:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:58:44 INFO - Using env: {'DISPLAY': ':0', 09:58:44 INFO - 'HOME': '/home/cltbld', 09:58:44 INFO - 'LANG': 'en_US.UTF-8', 09:58:44 INFO - 'LANGUAGE': 'en_US:en', 09:58:44 INFO - 'LOGNAME': 'cltbld', 09:58:44 INFO - 'MAIL': '/var/mail/cltbld', 09:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:44 INFO - 'MOZ_NO_REMOTE': '1', 09:58:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:44 INFO - 'NO_EM_RESTART': '1', 09:58:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:44 INFO - 'PWD': '/builds/slave/test', 09:58:44 INFO - 'SHELL': '/bin/bash', 09:58:44 INFO - 'SHLVL': '1', 09:58:44 INFO - 'TERM': 'linux', 09:58:44 INFO - 'TMOUT': '86400', 09:58:44 INFO - 'USER': 'cltbld', 09:58:44 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:44 INFO - '_': '/tools/buildbot/bin/python'} 09:58:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:58:44 INFO - Cleaning up... 09:58:44 INFO - Return code: 0 09:58:44 INFO - Running post-action listener: _resource_record_post_action 09:58:44 INFO - Running post-action listener: _start_resource_monitoring 09:58:44 INFO - Starting resource monitoring. 09:58:44 INFO - [mozharness: 2016-08-06 16:58:44.971075Z] Finished create-virtualenv step (success) 09:58:44 INFO - [mozharness: 2016-08-06 16:58:44.971563Z] Running install step. 09:58:44 INFO - Running pre-action listener: _resource_record_pre_action 09:58:44 INFO - Running main action method: install 09:58:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:45 INFO - Reading from file tmpfile_stdout 09:58:45 INFO - Detecting whether we're running mozinstall >=1.0... 09:58:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:58:45 INFO - Reading from file tmpfile_stdout 09:58:45 INFO - Output received: 09:58:45 INFO - Usage: mozinstall [options] installer 09:58:45 INFO - Options: 09:58:45 INFO - -h, --help show this help message and exit 09:58:45 INFO - -d DEST, --destination=DEST 09:58:45 INFO - Directory to install application into. [default: 09:58:45 INFO - "/builds/slave/test"] 09:58:45 INFO - --app=APP Application being installed. [default: firefox] 09:58:45 INFO - mkdir: /builds/slave/test/build/application 09:58:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:59:01 INFO - Reading from file tmpfile_stdout 09:59:01 INFO - Output received: 09:59:01 INFO - /builds/slave/test/build/application/firefox/firefox 09:59:01 INFO - Running post-action listener: _resource_record_post_action 09:59:01 INFO - [mozharness: 2016-08-06 16:59:01.456693Z] Finished install step (success) 09:59:01 INFO - [mozharness: 2016-08-06 16:59:01.456943Z] Running run-tests step. 09:59:01 INFO - Running pre-action listener: _resource_record_pre_action 09:59:01 INFO - Running main action method: run_tests 09:59:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:59:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:59:01 INFO - Python 2.7.3 09:59:01 INFO - Return code: 0 09:59:01 INFO - grabbing minidump binary from tooltool 09:59:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:01 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 0x12ed3b0>, '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 0x12ee2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12ee740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:59:01 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 09:59:01 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 09:59:01 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 09:59:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:59:01 INFO - Return code: 0 09:59:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:59:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:59:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:59:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:59:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:59:01 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/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 09:59:01 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/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 09:59:01 INFO - Using env: {'DISPLAY': ':0', 09:59:01 INFO - 'HOME': '/home/cltbld', 09:59:01 INFO - 'LANG': 'en_US.UTF-8', 09:59:01 INFO - 'LANGUAGE': 'en_US:en', 09:59:01 INFO - 'LOGNAME': 'cltbld', 09:59:01 INFO - 'MAIL': '/var/mail/cltbld', 09:59:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:59:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:59:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:01 INFO - 'MOZ_NO_REMOTE': '1', 09:59:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:59:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:01 INFO - 'NO_EM_RESTART': '1', 09:59:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:01 INFO - 'PWD': '/builds/slave/test', 09:59:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:59:01 INFO - 'SHELL': '/bin/bash', 09:59:01 INFO - 'SHLVL': '1', 09:59:01 INFO - 'TERM': 'linux', 09:59:01 INFO - 'TMOUT': '86400', 09:59:01 INFO - 'USER': 'cltbld', 09:59:01 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776', 09:59:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:01 INFO - '_': '/tools/buildbot/bin/python'} 09:59:01 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/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 09:59:01 INFO - mozversion application_buildid: 20160806072421 09:59:01 INFO - mozversion application_changeset: fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 09:59:01 INFO - mozversion application_display_name: Nightly 09:59:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:59:01 INFO - mozversion application_name: Firefox 09:59:01 INFO - mozversion application_remotingname: firefox 09:59:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:59:01 INFO - mozversion application_vendor: Mozilla 09:59:01 INFO - mozversion application_version: 51.0a1 09:59:01 INFO - mozversion platform_buildid: 20160806072421 09:59:01 INFO - mozversion platform_changeset: fa0934fbb4d5e3a189aba0b82b4f3ebb9cb5ee96 09:59:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:59:01 INFO - mozversion platform_version: 51.0a1 09:59:01 INFO - using testdate: 1470502741 09:59:01 INFO - actual date: 1470502741 09:59:01 INFO - starting webserver on 'localhost:47388' 09:59:01 INFO - SUITE-START | Running 2 tests 09:59:01 INFO - TEST-START | dromaeo_css 09:59:01 INFO - operating with platform_type : linux_ 09:59:01 INFO - Initialising browser for dromaeo_css test... 09:59:01 INFO - TEST-INFO | started process 19885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPbHubR/profile http://localhost:47388/getInfo.html) 09:59:06 INFO - PROCESS | 19885 | __metrics Screen width/height:1600/1200 09:59:06 INFO - PROCESS | 19885 | colorDepth:24 09:59:06 INFO - PROCESS | 19885 | Browser inner width/height: 1024/697 09:59:06 INFO - PROCESS | 19885 | __metrics 09:59:07 INFO - PROCESS | 19885 | 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" 09:59:07 INFO - PROCESS | 19885 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:59:08 INFO - TEST-INFO | 19885: exit 0 09:59:08 INFO - Browser initialized. 09:59:08 INFO - Running cycle 1/1 for dromaeo_css test... 09:59:08 INFO - TEST-INFO | started process 19966 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPbHubR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:20 INFO - PROCESS | 19966 | RSS: Main: 158502912 09:59:20 INFO - PROCESS | 19966 | 10:00:11 INFO - PROCESS | 19966 | 1470502811461 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] 10:02:24 INFO - PROCESS | 19966 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47388/tests/dromaeo/cssquery-ext.html) 10:02:25 INFO - PROCESS | 19966 | RSS: Main: 270512128 10:02:25 INFO - PROCESS | 19966 | 10:05:28 INFO - PROCESS | 19966 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-ext.html (next: http://localhost:47388/tests/dromaeo/cssquery-jquery.html) 10:05:28 INFO - PROCESS | 19966 | RSS: Main: 244174848 10:05:28 INFO - PROCESS | 19966 | 10:08:31 INFO - PROCESS | 19966 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47388/tests/dromaeo/cssquery-mootools.html) 10:08:31 INFO - PROCESS | 19966 | RSS: Main: 200577024 10:08:31 INFO - PROCESS | 19966 | 10:11:33 INFO - PROCESS | 19966 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47388/tests/dromaeo/cssquery-prototype.html) 10:11:34 INFO - PROCESS | 19966 | RSS: Main: 200052736 10:11:34 INFO - PROCESS | 19966 | 10:14:37 INFO - PROCESS | 19966 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47388/tests/dromaeo/cssquery-yui.html) 10:14:38 INFO - PROCESS | 19966 | RSS: Main: 184578048 10:14:38 INFO - PROCESS | 19966 | 10:17:41 INFO - PROCESS | 19966 | Cycle 1(1): loaded http://localhost:47388/tests/dromaeo/cssquery-yui.html (next: http://localhost:47388/tests/dromaeo/cssquery-dojo.html) 10:17:41 INFO - PROCESS | 19966 | RSS: Main: 257097728 10:17:41 INFO - PROCESS | 19966 | 10:17:41 INFO - PROCESS | 19966 | __start_tp_report 10:17:41 INFO - PROCESS | 19966 | _x_x_mozilla_page_load 10:17:41 INFO - PROCESS | 19966 | _x_x_mozilla_page_load_details 10:17:41 INFO - PROCESS | 19966 | |i|pagename|runs| 10:17:41 INFO - PROCESS | 19966 | |0;dojo.html;2569.7944164466844;2573.0736934703505;2611.464649746802;2618.633391325214;2619.6201550775127;4486.237339652259;4549.681522293441;4561.794719237634;4583.587477127058;4599.034202817408;4595.701279416836;4620.491745907956;4681.297805329194;4681.742504162278;4745.027269409771;4240.957590424103;4252.893677658068;4269.765162916038;4278.080212754257;4367.650587952956;4314.590113939171;4342.131573685259;4350.2387082260475;4361.433013708213;4382.101669157815;4125.752454852718;4145.461090058303;4154.750714957112;4342.891427714301;4387.714798538085;4169.624733773944;4177.164567086595;4194.454720886296;4204.432401625772;4210.642095421893;4215.325547912319;4219.38818871265;4219.493660760729;4284.914301713949;4333.696641235115;4634.026854360588;4669.299605059235;4671.5795578397765;4674.182018146811;4714.504976977437;4758.833440829589;5088.6946783193125;5089.389273287192;5090.160123579642;5097.260897169889;4954.677945933503;4991.950080499185;5041.798328066873;5094;5099.286099946011;4643.233866412072;4679.578837904605;4711.505291944331;4726.669133160645;4728.40894888139;4873.049755297683;5042.747862606929;5176.171812510056;5181.818636347785;5182.740862956837;4891.2174052152195;4943.332650092191;4959.975200124049;4963.57809586182;4969.204927211629;4997.675151115166;4999.125153098194;5039.244113383022;5039.924401133986;5040.042391945519;4764.8332308369045;4889.511048895153;4945.505449455026;4959.826406075842;5008.023435430056;4889.3888263967;4910.312556242057;4922.212446008693;4935.925961110586;4958.9008219835405;4189.2668782962855;4191.790410479463;4191.916161676811;4228.133232687292;4229.154169166179;3638.5451818522683;3639.6724294813594;3686.502322186524;3695.6489133532273;3739.9065023374633;4237.809298581509;4240.639545638655;4295.162443323585;4303.031817841023;4334.956650433518;4992.825251116266;4995.825146119941;5000.099982003201;5009.474005229471;5041.747912604355;4282.036541778075;4285.442892423965;4290.528041915387;4324.048709283952;4330.805113769888;4289.442372491619;4440.222960981815;4468.776561171929;4483.2826747720865;4483.596476317147;4728.101660684459;4731.810727570996;4748.216544270295;4758.738269395217;4776;5022.121128802518;5023.246513023076;5025.924611130763;5035.219540971156;5067.13858644024;4526.909461810679;4655.11552804966;4688.671792974459;4755.144074066701;4758.5241475852135;4930.630202734737;4936.950630493793;4961.3798275215595;4963.057019166346;4983.75081245929;4990.476000019946;4998.4251811042195;5000.7999680013845;5002.499750025114;5040.84877453677;4651.2557990721325;4802.27965805118;4854.732989685601;4858.125537403302;4905.828296009622;4773.355596994361;4859.781309840995;4872.561469467765;4907.607391408607;4916.410030796327;4779.545943135397;4825.252085926687;4829.806807727651;4835.274708793709;4845.466998630219;4649.604783593297;4842.249451335048;4876.073546026244;4884.291777692286;4889.462159162418;4641.976790116163;4775.594074503706;4796.3285140079715;4835.806567737392;4842.249451335048 10:17:41 INFO - PROCESS | 19966 | |1;ext.html;543.7471390855247;552.7070652554142;554.4150920778579;585.274259917702;593.2613895699853;29606.851965740167;40030.39954400686;41285.17429651405;41304.79347603265;41335.79332103343;41977.79011104948;42706.57293427065;42726.35910461345;42800.35799463003;42816.357754633704;7325.304096110887;7727.999999999986;7867.213278672116;7873.291403773659;7880.054393472791;7362.116546014486;7607.733729319475;7610.429217808659;9735.56189971453;10112.140468060186;9603.80792384152;9621.134097931184;9668.033196680346;9696.75758106046;9737.51312434379;10092.697219083439;10125.797484050314;10149.797004059952;10209.846852297222;10211.999999999964;9919.80160396788;9996;10039;10100.64647737333;10108.140808031327;39708.60291397078;39776.40335394971;40042.599574004176;40092.59907400918;40110.59889401127;2962.5852380666724;3076.753859691242;3168;3291.226561757985;3424.8630054797777;30015.69984300151;30467.542986855216;30607.62265698049;30660.38679226403;31919;3112.579801726783;3140.356226973465;3173.3177366866003;3214.324991751735;3262.9836850815595;265963;267343;267429;267600.66199669073;268154;55465;55849;55857;55885;55891.44108558862;33218.50172247418;34111.31777364439;34218.82890585531;35864.82067589645;36035.459468108;13747.175169489863;13857.930710346383;13863.584092477242;13979.930100349433;13999.720005599831;11602.6519204424;11760.05919526453;11770.3526306054;11773.234739741889;11779.469923853447;17034.574135646708;17540.122993850255;17560.824391756174;17574.297028118734;18389.5402614933;6570.605763654196;6930.306969303029;6994.93005069953;7000.439964802804;7385.150707668687;746.4028776978396;795.0538858758059;854.525738215284;856.8757530158217;964.3105179796479;27431.314217144733;29122.12633620995;29207.123786286444;29527.704722952923;29910.25224369408;953.5565961827645;955.4362925873768;963.1716723617683;975.6731494949272;1003.2575893838507;14868.776968345483;15453.822969305409;15680.451184208498;15713.21433928299;15782.131982741059;417012;422124;422832;423497;423566;43950.340744888854;48616.27075593869;50188.498115019116;51880;52365.47634523606;1156.3235507228198;1159.0901142603016;1164.55746816211;1169.818678104895;1179.7345597240553;28453.573196401656;29816.10551683453;30075.3984920296;30234.54648180235;30724.84637576798;44473.33290000588;47092;49171;49207.50792492172;53765.46234537604;389231;391674;403316;404831;591995;38775.806120970345;39193.2161356777;39245.21509569736;39248.41127383149;39812.40281395723;42611.573884262;42812;43001;43047.78476107599;43416;36934.63065369312;36971.68405365127;37326.06684832901;38262.80868595639;38591;36953;37103.07242318964;37375.2524949494;37500.87497375083;37828.621713783614;36344;37273.627263727016;37510.06224844401;37623.81188094042;37633;29283;29761.10716678503;32257.838710806296;33735.66264337325;33972.66027339695;25295.042490099586;25602.23193304204;25954.26692572127;26032.479350413265;26095.869520652275 10:17:41 INFO - PROCESS | 19966 | |2;jquery.html;343.169192358766;365.7165696585148;368.7584632065997;372.83222549852564;391.98628048018327;12655.493780248791;16389.098599577017;16497;16579.595958585996;17470.737938930924;16867.156642167905;17035.48893533196;17247;17829.7325540117;17983.910080449612;4866.343043689109;4913.238448040551;5075.5178258065425;5099.566536844372;5101.030804147219;3737.8131093445354;4954.603631709455;5030;5554.361248456418;5825.388334224909;5836.212111364954;5859.970700146493;5864.76540938362;5865.178874957509;5868.2371291732015;5773;5843;6018.638881667114;6053.364396738367;6057.666828324441;5305.098133317345;5305.4164041955355;5306.628536002481;5316.548093412064;5317.521423071942;19260.036998150037;19801.801981980283;19863.503412414662;20423.183072677075;20968.370948871558;1770.0707128757506;1770.6724256012678;1774.946751597454;1819.299569665675;1873.784514780804;9066.456012639197;9159.679411220643;9462.195713364372;9631.903680963242;9812.969638187958;1716.8025677047049;1734.479656103164;1741.547197728587;1776.3072401763322;1840.2546968477654;63917.68041159811;69229.96155057728;70058;71304.2869571308;71647;9721.951390243003;10124.949375253223;10133.847992280122;10143.49282535885;10258.28192026566;13988.860111398755;14009.85990140106;14010.089344192595;14011.229382384066;14014.229217392938;6990.335918087773;7058.964705176441;7140.9285907140265;7228.855422891618;7268.709251629982;6499.480041596661;6563.638999855054;6566.901496477557;6728.586996730742;6740.090087838177;4368.257396242647;4369.60673539383;4390.473143222834;4414.9845535526365;4511.729296242236;1727.965440691179;1761.4451447793904;1777.4667599720033;1809.5028860172877;1834.7982770066053;794.3446656508404;798.856205882935;820.8030072782609;826.574314228173;975.073680003999;16324.183790810412;16399.590010249838;17558.209880555405;17832.286708531512;17838.91080544589;810.2383759266271;811.0713233347901;812.6383759227206;853.2619284319019;871.1767379826131;9403.57683904226;9636.46999415039;9856.457894815854;9857.162141218078;9999;409879;411669;424837;427411;429236;53034;70373.64813175901;74210;74379.5372277665;80538.59730701309;858.5406807357908;871.5903525343078;883.439016224702;887.1173182683272;889.7642124836794;15370;15445.691086178438;15812.76280855765;15941.282642281358;16351.34594616202;37272;38143.618563815115;38371.6162838368;38508.614913850506;38718.61281387263;47980.280295794895;48258.5174148254;49524.75237623789;51469.742651286506;52096.73951630369;4730.7871145797835;4855.198892182751;6516.771912983023;6723.899141512783;6729.2934241903895;7031;9656.468894210604;10432.843507347548;10986.395748233925;11050.668479945614;5133.743312834418;5235.240890070994;9201.171894529525;9476.810463790824;9963.800723985625;9639.518024098907;9697.806043879224;9720.465374404475;9855.408675479493;9962.601495940082;9384.76538086526;9894.208463322773;10067.647632332833;10070.144037756872;10400.427976461368;7313.414926805949;7325.267473252632;7474.738384156529;7561;7652.579108148882;9229.400088994194;9648.614055437703;9733.513324333613;9892.763404574429;10126.79746405053 10:17:41 INFO - PROCESS | 19966 | |3;mootools.html;1364.338295926476;1429.5425463851568;1501.8948673592854;1678.6606621882434;1726.369874995627;7339.376153026992;7884.724034658788;7896.960515197416;7911.802204944879;7935.087464941541;6817.522773405851;7552.395808335334;7747.264009919063;7794.532328060321;7832.490888092284;2142.2716276466035;2165.902534385956;2168.241115609532;2169.7287839020123;2169.9566008679817;2067.069818581637;2114.397396741929;2169.761326254111;2176.009915488457;2178.9128434862587;1775.8756887017914;2076.885771282579;2137.3587923622927;2143.8928053597338;2171.0447403142643;2092.686097085434;2098.7061811346425;2113.482196861779;2115.428834214769;2117.883516406597;2135.7970992755663;2137.262644387684;2139.775323591032;2142.1645558232303;2143.3248526714037;7983.96008019962;8079.313258373046;8116.8782468263025;8118.147594502551;8119.309858662021;4288.656907447396;4416.80124394403;4425.557444255581;4425.690201685883;4433.024734558392;2733.658292713411;2835.376217232206;2859.6997315281806;2868.899588514392;2872.569114632801;857.1342943626919;897.241830653097;915.16719784996;915.6932427636683;928.8142371525722;10926.562937482491;10943.288686235448;10973.725656858562;10976.067034302094;10997.890021099767;3860.3051450739135;3988.780617066031;4106.691998100155;4109.479492587702;4264.317709166518;9280.164785169367;9290.256779457628;9293.349465537483;9296.209822165056;9302.116298951589;4572.337011133367;4574.290984897346;4574.5425457453985;4577.679562430664;4582.839600614029;3986.920261594752;4114.485689288839;4115.176964607031;4123.0516981094515;4124.608162224584;3558.1994051338243;3565.6790888820133;3653.4702468141973;3654.908627284339;3660.633936606371;1120.3501968858043;1142.2118738070706;1142.9714257143637;1143.2854465958776;1150.3155622404656;575.0257837280359;719.1406269507993;858.4849090545623;879.4064006795516;881.6297155194834;5135.12702840518;5240.371155461293;5254.894902101936;5408.972955135199;5446.645968012067;866.1054488338872;867.5271976772674;903.7876099116702;904.968326108583;908.3459908865606;3680.429533422324;3747.775133491999;3781.6029316921877;3785.280796648628;3853.0560012797046;9344.2524598032;9749.463779492055;9795.118439340493;9843.114119729258;9975.950120249352;9760.853587196198;9774.657886974064;9777.266704997153;9778.462184579916;9787.755306117404;7386.113666359964;7393.81515462107;7507.887381689279;7580.734674286374;7581.469297149257;5241.790328386975;5297.761600728054;5338.199270109515;5398.13629819227;5405.86485337854;9188.310876684409;9227.077292270718;9228.815423691354;9253.444793312154;9258.305627077862;8907.153820387053;9290.303227258119;9327.486988215442;9333.020032896447;9338.159565639126;6823.112995310578;6932.965335173292;6939.930600693929;6941.444684425132;6944.583325000516;7395.075615548057;7404.777856664308;7441.330280274801;7468.290512401313;7469.626518674153;6626.966865165643;6679.131712877295;6698.698558564779;6756.324367563205;6854.760083397057;6583.275839657729;6703.798886033427;6705.8994115087335;6717.992301154866;6768.56004359715;6195;6205.81382558524;6240.625562466266;6243.282022567281;6280.7487700491465;4460.955390446054;4591.678582499193;4593.471750748707;4600.079984003146;4672.112298663243;4621.884452888705;4700.8119675213975;4736.313234580897;4795.736234507136;4841.709497430165 10:17:41 INFO - PROCESS | 19966 | |4;prototype.html;301.86717844148575;306.7791190342953;313.9340738444927;315.3267773303995;315.9636641786195;4710.563278200148;4818.903621927564;4850.587700045491;4855.344528488653;4867.513248675131;4671.836485723001;4766.641507170457;4772.498887616803;4787.760611969405;4808.807647694097;3815.294170578445;3816.3893776995687;3822.69418446525;3826.559945606249;3831.731778775487;3628.1473853644375;3667.0564600761504;3805.7906815125157;3811.714121440903;3854.7879866607327;3776.150366167618;3786.8106594670294;3787.280416720828;3788.4317352397084;3825.005498570364;3767.077066118791;3790.1472168762084;3791.4881490998637;3802.315583195024;3809.609492535232;3394.4059789536977;3400.25194457219;3406.3357645259184;3406.45496720526;3418.4017796885446;4536.274196128604;4674.205385084544;4674.228752255874;4714.457837348715;4768.8554746860855;4296.5273819879785;4301.548337424588;4313.956860431387;4340.782960851945;4343.283358245885;5019.415001274347;5033.521815427565;5066.468020857794;5079.263506791532;5083.11045567028;3567.9108022299392;3625.9456108158397;3629.491871138043;3682.797446140461;3710.647488488616;5196.142636464979;5206.219067139921;5212.843614691527;5213.608979326527;5218.530332270076;3752.512173417438;3757.1922036762226;3757.7933213672964;3762.6237376262156;3764.661180493769;3822.503074600284;3827.8086095695103;3843.3466310727244;3843.9231215375535;3848.942265866014;4195.811188496525;4238.809253583596;4275.593818587229;4276.786160691953;4306.246406878784;4974.32846565709;4993.625478089158;5005.27423523587;5009.849704508871;5048.16705243638;1251.843519560055;1284.4990453723055;1307.6142537951328;1308.30005946818;1326.4561529772748;884.1732979664034;898.0929261445987;912.2656261709387;915.6337465014047;918.2699753695828;4381.335092664767;4384.912301753947;4385.342198670161;4390.056137930296;4403.8458653946955;5114.718690471986;5115.386153661584;5116.513931176532;5126.794928202905;5140.768665410065;4369.868903932887;4370.557715953728;4392.582704643054;4404.295312749945;4409.33859921008;2291.793738563504;2409.662647229389;2432.2581612608324;2432.452698142939;2437.2566367258155;4279.422277992493;4312.978435107804;4314.676399270067;4316.417283666665;4399.296112621965;4691.272852707836;4691.765411729468;4721.433427988594;4734.147853386355;4757.452892917289;4330.761808100585;4535.523770004102;4932.7286999215385;4984.183936076105;4984.252362145708;5209.374875015023;5237.764300606558;5244.449332819999;5258.921116183337;5267.261024267436;5138.922916156335;5153.5361817436615;5159.291372029715;5159.664621799571;5163.8192663256605;4707;4910.238912968352;4967.528084831935;4968.900621987612;4975.467418133166;3787.905302367463;3849.4995650565247;3866.149447121629;3892.8832135035996;3908.8241029153187;5068.504791086564;5286.709230992333;5292;5295.232191332312;5296.311479507639;4733.290006499053;5053.67151135175;5081.8475445736685;5083.5678967288195;5101.872453188552;5072.873178170576;5074.213496907985;5077.7715002824225;5080.364954380702;5090.516400941905;5005.34930459038;5022.949770502394;5025.698458092526;5032.414789341346;5040.6471546992825;4848.860517778248;5054.873628159326;5110.770015349244;5113.233015047773;5113.823820521376;4944.53026962438;4968.751562421792;4987.226979818134;4992.80028798844;5000.4749501303295 10:17:41 INFO - PROCESS | 19966 | |5;yui.html;617.5214208988034;642.3640595810148;653.1182903080844;699.9475039372048;757.1746795992369;12139.453724582392;13273.469061237563;13291.136076155055;13328;13349.132306400077;13198;13351.799723004162;13367.39846706898;13370.799438008438;13374.598762037154;632.4181752787424;747.9513831600956;763.053813271542;764.5298141642877;787.2875048081467;777.8794286885542;779.4465929190253;780.1574299756278;780.4809801482007;780.9336206422461;824.2499325613693;824.3693574415577;825.2696363718093;825.5170725125809;825.859603867344;659.3900641906265;665.3280187011154;670.4267850987433;681.9838440723336;822.5311572403732;587.2688502813982;587.9382664820175;599.3018133874032;604.2115039872964;604.3925854516183;12635.241885486901;12668.619941401772;12683.175593586388;12696.17474864131;12702.745945081047;11000.779984400348;11301.604443844508;11456.828147577793;11933.701657458547;12939.611811645666;12102;12203.816942745865;12251.917174080429;12357.93821030889;12487.25076495404;2926.9707302926913;3056.3734434440885;3085.1670049086847;3118.8284644344553;4074.714769966074;56104;61390.38609613936;61884.690576546825;62861;62873.99999999954;10065.748356291151;10125.696229113139;10212.795744085226;10226.437545935045;10385.428801415843;19525.707114393295;19825.900870495843;19830.70253946192;19844.90077549603;19874.10566524481;6541.869162616721;6625.3374662534325;6657.3342665733035;6669;6870.896936545956;3388.0852169913987;3679.264147170523;3708.758930669552;3718.2749363874327;3806.4861243731743;2995.715407036328;3103.984480077585;3120.953185702216;3132.7023932726356;3138.3723255349023;488.8411266338454;494.29069285575315;497.8182963218472;507.47476361965187;512.3672264753027;577.9075347944392;589.0310439327283;591.9881602367929;604.5465900574569;605.6517502436117;8067.233612806891;8300.916990830136;8474.703385381483;8526.74419767408;8544.871826922477;517.2344929504354;524.3183860980786;550.3011175806703;566.4165909113538;587.9000569903042;3829.6936245100324;4073.796310184479;4082.8366865325647;4137.834486620562;4181.707280490336;54464.727676362156;59476.702616487506;102350;106199.46900265604;107821;87381;88473.55763221142;89325;90513;91234.54382728045;46368.536314637095;63124;75364;75740.07689500165;76057;7894.1316455188735;8092.838143236984;8178.7955301117945;8220;8371.121032291765;45493.31760023536;45697.54302456933;46503.76748116373;46830;46959.53040469551;65866.34133658602;67466.32533674796;67655.32344676687;68644;68712.3128768706;9796.51017449139;9874.703758887244;9884.973580416141;10754;11200.663980080612;13696.178229306273;13789.586312410644;14071.929640351733;14122.858771412155;14572.05281656689;11943.82084268754;12623.621291361274;13013.349332533524;13065.542706005244;13179.868201318248;12295;12370.13409061357;12521.311327877054;13056.804147937599;13076.553971649684;12178.817317740064;12396.442160102997;12639.851225291906;12738.108332416641;12977;9603.135717785433;10112.191024717858;10131;10426.113780328757;10644.361338319726;9185.632574696938;9584.185344245818;9734.805303893741;10008.449535275338;10016.449095299828 10:17:41 INFO - PROCESS | 19966 | __end_tp_report 10:17:41 INFO - PROCESS | 19966 | __start_cc_report 10:17:41 INFO - PROCESS | 19966 | _x_x_mozilla_cycle_collect,1135 10:17:41 INFO - PROCESS | 19966 | __end_cc_report 10:17:41 INFO - PROCESS | 19966 | __startTimestamp1470503861552__endTimestamp 10:17:41 INFO - PROCESS | 19966 | 10:17:41 INFO - PROCESS | 19966 | ------- Summary: start ------- 10:17:41 INFO - PROCESS | 19966 | Number of tests: 6 10:17:41 INFO - PROCESS | 19966 | 10:17:41 INFO - PROCESS | 19966 | [#0] dojo.html Cycles:165 Average:4603.07 Median:4745.03 stddev:493.26 (10.4%) stddev-sans-first:468.28 10:17:41 INFO - PROCESS | 19966 | Values: 2569.8 2573.1 2611.5 2618.6 2619.6 4486.2 4549.7 4561.8 4583.6 4599.0 4595.7 4620.5 4681.3 4681.7 4745.0 4241.0 4252.9 4269.8 4278.1 4367.7 4314.6 4342.1 4350.2 4361.4 4382.1 4125.8 4145.5 4154.8 4342.9 4387.7 4169.6 4177.2 4194.5 4204.4 4210.6 4215.3 4219.4 4219.5 4284.9 4333.7 4634.0 4669.3 4671.6 4674.2 4714.5 4758.8 5088.7 5089.4 5090.2 5097.3 4954.7 4992.0 5041.8 5094.0 5099.3 4643.2 4679.6 4711.5 4726.7 4728.4 4873.0 5042.7 5176.2 5181.8 5182.7 4891.2 4943.3 4960.0 4963.6 4969.2 4997.7 4999.1 5039.2 5039.9 5040.0 4764.8 4889.5 4945.5 4959.8 5008.0 4889.4 4910.3 4922.2 4935.9 4958.9 4189.3 4191.8 4191.9 4228.1 4229.2 3638.5 3639.7 3686.5 3695.6 3739.9 4237.8 4240.6 4295.2 4303.0 4335.0 4992.8 4995.8 5000.1 5009.5 5041.7 4282.0 4285.4 4290.5 4324.0 4330.8 4289.4 4440.2 4468.8 4483.3 4483.6 4728.1 4731.8 4748.2 4758.7 4776.0 5022.1 5023.2 5025.9 5035.2 5067.1 4526.9 4655.1 4688.7 4755.1 4758.5 4930.6 4937.0 4961.4 4963.1 4983.8 4990.5 4998.4 5000.8 5002.5 5040.8 4651.3 4802.3 4854.7 4858.1 4905.8 4773.4 4859.8 4872.6 4907.6 4916.4 4779.5 4825.3 4829.8 4835.3 4845.5 4649.6 4842.2 4876.1 4884.3 4889.5 4642.0 4775.6 4796.3 4835.8 4842.2 10:17:41 INFO - PROCESS | 19966 | 10:17:41 INFO - PROCESS | 19966 | [#1] ext.html Cycles:180 Average:52739.47 Median:37566.94 stddev:102079.58 (271.7%) stddev-sans-first:102290.72 10:17:41 INFO - PROCESS | 19966 | Values: 543.7 552.7 554.4 585.3 593.3 29606.9 40030.4 41285.2 41304.8 41335.8 41977.8 42706.6 42726.4 42800.4 42816.4 7325.3 7728.0 7867.2 7873.3 7880.1 7362.1 7607.7 7610.4 9735.6 10112.1 9603.8 9621.1 9668.0 9696.8 9737.5 10092.7 10125.8 10149.8 10209.8 10212.0 9919.8 9996.0 10039.0 10100.6 10108.1 39708.6 39776.4 40042.6 40092.6 40110.6 2962.6 3076.8 3168.0 3291.2 3424.9 30015.7 30467.5 30607.6 30660.4 31919.0 3112.6 3140.4 3173.3 3214.3 3263.0 265963.0 267343.0 267429.0 267600.7 268154.0 55465.0 55849.0 55857.0 55885.0 55891.4 33218.5 34111.3 34218.8 35864.8 36035.5 13747.2 13857.9 13863.6 13979.9 13999.7 11602.7 11760.1 11770.4 11773.2 11779.5 17034.6 17540.1 17560.8 17574.3 18389.5 6570.6 6930.3 6994.9 7000.4 7385.2 746.4 795.1 854.5 856.9 964.3 27431.3 29122.1 29207.1 29527.7 29910.3 953.6 955.4 963.2 975.7 1003.3 14868.8 15453.8 15680.5 15713.2 15782.1 417012.0 422124.0 422832.0 423497.0 423566.0 43950.3 48616.3 50188.5 51880.0 52365.5 1156.3 1159.1 1164.6 1169.8 1179.7 28453.6 29816.1 30075.4 30234.5 30724.8 44473.3 47092.0 49171.0 49207.5 53765.5 389231.0 391674.0 403316.0 404831.0 591995.0 38775.8 39193.2 39245.2 39248.4 39812.4 42611.6 42812.0 43001.0 43047.8 43416.0 36934.6 36971.7 37326.1 38262.8 38591.0 36953.0 37103.1 37375.3 37500.9 37828.6 36344.0 37273.6 37510.1 37623.8 37633.0 29283.0 29761.1 32257.8 33735.7 33972.7 25295.0 25602.2 25954.3 26032.5 26095.9 10:17:41 INFO - PROCESS | 19966 | 10:17:41 INFO - PROCESS | 19966 | [#2] jquery.html Cycles:180 Average:24780.11 Median:51783.24 stddev:69211.01 (133.7%) stddev-sans-first:69380.84 10:17:41 INFO - PROCESS | 19966 | Values: 343.2 365.7 368.8 372.8 392.0 12655.5 16389.1 16497.0 16579.6 17470.7 16867.2 17035.5 17247.0 17829.7 17983.9 4866.3 4913.2 5075.5 5099.6 5101.0 3737.8 4954.6 5030.0 5554.4 5825.4 5836.2 5860.0 5864.8 5865.2 5868.2 5773.0 5843.0 6018.6 6053.4 6057.7 5305.1 5305.4 5306.6 5316.5 5317.5 19260.0 19801.8 19863.5 20423.2 20968.4 1770.1 1770.7 1774.9 1819.3 1873.8 9066.5 9159.7 9462.2 9631.9 9813.0 1716.8 1734.5 1741.5 1776.3 1840.3 63917.7 69230.0 70058.0 71304.3 71647.0 9722.0 10124.9 10133.8 10143.5 10258.3 13988.9 14009.9 14010.1 14011.2 14014.2 6990.3 7059.0 7140.9 7228.9 7268.7 6499.5 6563.6 6566.9 6728.6 6740.1 4368.3 4369.6 4390.5 4415.0 4511.7 1728.0 1761.4 1777.5 1809.5 1834.8 794.3 798.9 820.8 826.6 975.1 16324.2 16399.6 17558.2 17832.3 17838.9 810.2 811.1 812.6 853.3 871.2 9403.6 9636.5 9856.5 9857.2 9999.0 409879.0 411669.0 424837.0 427411.0 429236.0 53034.0 70373.6 74210.0 74379.5 80538.6 858.5 871.6 883.4 887.1 889.8 15370.0 15445.7 15812.8 15941.3 16351.3 37272.0 38143.6 38371.6 38508.6 38718.6 47980.3 48258.5 49524.8 51469.7 52096.7 4730.8 4855.2 6516.8 6723.9 6729.3 7031.0 9656.5 10432.8 10986.4 11050.7 5133.7 5235.2 9201.2 9476.8 9963.8 9639.5 9697.8 9720.5 9855.4 9962.6 9384.8 9894.2 10067.6 10070.1 10400.4 7313.4 7325.3 7474.7 7561.0 7652.6 9229.4 9648.6 9733.5 9892.8 10126.8 10:17:41 INFO - PROCESS | 19966 | 10:17:41 INFO - PROCESS | 19966 | [#3] mootools.html Cycles:180 Average:5176.23 Median:5368.17 stddev:2951.94 (55.0%) stddev-sans-first:2946.33 10:17:41 INFO - PROCESS | 19966 | Values: 1364.3 1429.5 1501.9 1678.7 1726.4 7339.4 7884.7 7897.0 7911.8 7935.1 6817.5 7552.4 7747.3 7794.5 7832.5 2142.3 2165.9 2168.2 2169.7 2170.0 2067.1 2114.4 2169.8 2176.0 2178.9 1775.9 2076.9 2137.4 2143.9 2171.0 2092.7 2098.7 2113.5 2115.4 2117.9 2135.8 2137.3 2139.8 2142.2 2143.3 7984.0 8079.3 8116.9 8118.1 8119.3 4288.7 4416.8 4425.6 4425.7 4433.0 2733.7 2835.4 2859.7 2868.9 2872.6 857.1 897.2 915.2 915.7 928.8 10926.6 10943.3 10973.7 10976.1 10997.9 3860.3 3988.8 4106.7 4109.5 4264.3 9280.2 9290.3 9293.3 9296.2 9302.1 4572.3 4574.3 4574.5 4577.7 4582.8 3986.9 4114.5 4115.2 4123.1 4124.6 3558.2 3565.7 3653.5 3654.9 3660.6 1120.4 1142.2 1143.0 1143.3 1150.3 575.0 719.1 858.5 879.4 881.6 5135.1 5240.4 5254.9 5409.0 5446.6 866.1 867.5 903.8 905.0 908.3 3680.4 3747.8 3781.6 3785.3 3853.1 9344.3 9749.5 9795.1 9843.1 9976.0 9760.9 9774.7 9777.3 9778.5 9787.8 7386.1 7393.8 7507.9 7580.7 7581.5 5241.8 5297.8 5338.2 5398.1 5405.9 9188.3 9227.1 9228.8 9253.4 9258.3 8907.2 9290.3 9327.5 9333.0 9338.2 6823.1 6933.0 6939.9 6941.4 6944.6 7395.1 7404.8 7441.3 7468.3 7469.6 6627.0 6679.1 6698.7 6756.3 6854.8 6583.3 6703.8 6705.9 6718.0 6768.6 6195.0 6205.8 6240.6 6243.3 6280.7 4461.0 4591.7 4593.5 4600.1 4672.1 4621.9 4700.8 4736.3 4795.7 4841.7 10:17:41 INFO - PROCESS | 19966 | 10:17:41 INFO - PROCESS | 19966 | [#4] prototype.html Cycles:180 Average:4168.85 Median:4682.75 stddev:1201.99 (25.7%) stddev-sans-first:1169.80 10:17:41 INFO - PROCESS | 19966 | Values: 301.9 306.8 313.9 315.3 316.0 4710.6 4818.9 4850.6 4855.3 4867.5 4671.8 4766.6 4772.5 4787.8 4808.8 3815.3 3816.4 3822.7 3826.6 3831.7 3628.1 3667.1 3805.8 3811.7 3854.8 3776.2 3786.8 3787.3 3788.4 3825.0 3767.1 3790.1 3791.5 3802.3 3809.6 3394.4 3400.3 3406.3 3406.5 3418.4 4536.3 4674.2 4674.2 4714.5 4768.9 4296.5 4301.5 4314.0 4340.8 4343.3 5019.4 5033.5 5066.5 5079.3 5083.1 3567.9 3625.9 3629.5 3682.8 3710.6 5196.1 5206.2 5212.8 5213.6 5218.5 3752.5 3757.2 3757.8 3762.6 3764.7 3822.5 3827.8 3843.3 3843.9 3848.9 4195.8 4238.8 4275.6 4276.8 4306.2 4974.3 4993.6 5005.3 5009.8 5048.2 1251.8 1284.5 1307.6 1308.3 1326.5 884.2 898.1 912.3 915.6 918.3 4381.3 4384.9 4385.3 4390.1 4403.8 5114.7 5115.4 5116.5 5126.8 5140.8 4369.9 4370.6 4392.6 4404.3 4409.3 2291.8 2409.7 2432.3 2432.5 2437.3 4279.4 4313.0 4314.7 4316.4 4399.3 4691.3 4691.8 4721.4 4734.1 4757.5 4330.8 4535.5 4932.7 4984.2 4984.3 5209.4 5237.8 5244.4 5258.9 5267.3 5138.9 5153.5 5159.3 5159.7 5163.8 4707.0 4910.2 4967.5 4968.9 4975.5 3787.9 3849.5 3866.1 3892.9 3908.8 5068.5 5286.7 5292.0 5295.2 5296.3 4733.3 5053.7 5081.8 5083.6 5101.9 5072.9 5074.2 5077.8 5080.4 5090.5 5005.3 5022.9 5025.7 5032.4 5040.6 4848.9 5054.9 5110.8 5113.2 5113.8 4944.5 4968.8 4987.2 4992.8 5000.5 10:17:41 INFO - PROCESS | 19966 | 10:17:41 INFO - PROCESS | 19966 | [#5] yui.html Cycles:180 Average:17676.05 Median:46894.77 stddev:24988.97 (53.3%) stddev-sans-first:25026.25 10:17:41 INFO - PROCESS | 19966 | Values: 617.5 642.4 653.1 699.9 757.2 12139.5 13273.5 13291.1 13328.0 13349.1 13198.0 13351.8 13367.4 13370.8 13374.6 632.4 748.0 763.1 764.5 787.3 777.9 779.4 780.2 780.5 780.9 824.2 824.4 825.3 825.5 825.9 659.4 665.3 670.4 682.0 822.5 587.3 587.9 599.3 604.2 604.4 12635.2 12668.6 12683.2 12696.2 12702.7 11000.8 11301.6 11456.8 11933.7 12939.6 12102.0 12203.8 12251.9 12357.9 12487.3 2927.0 3056.4 3085.2 3118.8 4074.7 56104.0 61390.4 61884.7 62861.0 62874.0 10065.7 10125.7 10212.8 10226.4 10385.4 19525.7 19825.9 19830.7 19844.9 19874.1 6541.9 6625.3 6657.3 6669.0 6870.9 3388.1 3679.3 3708.8 3718.3 3806.5 2995.7 3104.0 3121.0 3132.7 3138.4 488.8 494.3 497.8 507.5 512.4 577.9 589.0 592.0 604.5 605.7 8067.2 8300.9 8474.7 8526.7 8544.9 517.2 524.3 550.3 566.4 587.9 3829.7 4073.8 4082.8 4137.8 4181.7 54464.7 59476.7 102350.0 106199.5 107821.0 87381.0 88473.6 89325.0 90513.0 91234.5 46368.5 63124.0 75364.0 75740.1 76057.0 7894.1 8092.8 8178.8 8220.0 8371.1 45493.3 45697.5 46503.8 46830.0 46959.5 65866.3 67466.3 67655.3 68644.0 68712.3 9796.5 9874.7 9885.0 10754.0 11200.7 13696.2 13789.6 14071.9 14122.9 14572.1 11943.8 12623.6 13013.3 13065.5 13179.9 12295.0 12370.1 12521.3 13056.8 13076.6 12178.8 12396.4 12639.9 12738.1 12977.0 9603.1 10112.2 10131.0 10426.1 10644.4 9185.6 9584.2 9734.8 10008.4 10016.4 10:17:41 INFO - PROCESS | 19966 | -------- Summary: end -------- 10:17:41 INFO - PROCESS | 19966 | 10:17:42 INFO - TEST-INFO | 19966: exit 0 10:17:42 INFO - TEST-OK | dromaeo_css | took 1120614ms 10:17:42 INFO - TEST-START | kraken 10:17:42 INFO - operating with platform_type : linux_ 10:17:42 INFO - Initialising browser for kraken test... 10:17:42 INFO - TEST-INFO | started process 20160 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwXhkPi/profile http://localhost:47388/getInfo.html) 10:17:46 INFO - PROCESS | 20160 | __metrics Screen width/height:1600/1200 10:17:46 INFO - PROCESS | 20160 | colorDepth:24 10:17:46 INFO - PROCESS | 20160 | Browser inner width/height: 1024/697 10:17:46 INFO - PROCESS | 20160 | __metrics 10:17:47 INFO - PROCESS | 20160 | 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" 10:17:47 INFO - PROCESS | 20160 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:17:48 INFO - PROCESS | 20160 | console.error: PushService: 10:17:48 INFO - PROCESS | 20160 | stateChangeProcessEnqueue: Error transitioning state 10:17:48 INFO - PROCESS | 20160 | UnknownError 10:17:48 INFO - TEST-INFO | 20160: exit 0 10:17:48 INFO - Browser initialized. 10:17:48 INFO - Running cycle 1/1 for kraken test... 10:17:48 INFO - TEST-INFO | started process 20243 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwXhkPi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:18:00 INFO - PROCESS | 20243 | RSS: Main: 158478336 10:18:00 INFO - PROCESS | 20243 | 10:18:03 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47388/tests/kraken/driver.html?testName=audio-beat-detection) 10:18:04 INFO - PROCESS | 20243 | RSS: Main: 214392832 10:18:04 INFO - PROCESS | 20243 | 10:18:07 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47388/tests/kraken/driver.html?testName=audio-dft) 10:18:07 INFO - PROCESS | 20243 | RSS: Main: 192835584 10:18:07 INFO - PROCESS | 20243 | 10:18:11 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47388/tests/kraken/driver.html?testName=audio-fft) 10:18:11 INFO - PROCESS | 20243 | RSS: Main: 271503360 10:18:11 INFO - PROCESS | 20243 | 10:18:13 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47388/tests/kraken/driver.html?testName=audio-oscillator) 10:18:14 INFO - PROCESS | 20243 | RSS: Main: 180883456 10:18:14 INFO - PROCESS | 20243 | 10:18:16 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47388/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:18:17 INFO - PROCESS | 20243 | RSS: Main: 185466880 10:18:17 INFO - PROCESS | 20243 | 10:18:20 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47388/tests/kraken/driver.html?testName=imaging-darkroom) 10:18:20 INFO - PROCESS | 20243 | RSS: Main: 222240768 10:18:20 INFO - PROCESS | 20243 | 10:18:24 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47388/tests/kraken/driver.html?testName=imaging-desaturate) 10:18:25 INFO - PROCESS | 20243 | RSS: Main: 221331456 10:18:25 INFO - PROCESS | 20243 | 10:18:27 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47388/tests/kraken/driver.html?testName=json-parse-financial) 10:18:28 INFO - PROCESS | 20243 | RSS: Main: 220684288 10:18:28 INFO - PROCESS | 20243 | 10:18:30 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47388/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:18:30 INFO - PROCESS | 20243 | RSS: Main: 185376768 10:18:30 INFO - PROCESS | 20243 | 10:18:32 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:18:33 INFO - PROCESS | 20243 | RSS: Main: 191115264 10:18:33 INFO - PROCESS | 20243 | 10:18:35 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:18:36 INFO - PROCESS | 20243 | RSS: Main: 195887104 10:18:36 INFO - PROCESS | 20243 | 10:18:39 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:18:39 INFO - PROCESS | 20243 | RSS: Main: 190595072 10:18:39 INFO - PROCESS | 20243 | 10:18:42 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:18:43 INFO - PROCESS | 20243 | RSS: Main: 194007040 10:18:43 INFO - PROCESS | 20243 | 10:18:44 INFO - PROCESS | 20243 | Cycle 1(1): loaded http://localhost:47388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47388/tests/kraken/driver.html?testName=ai-astar) 10:18:45 INFO - PROCESS | 20243 | RSS: Main: 243462144 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | __start_tp_report 10:18:45 INFO - PROCESS | 20243 | _x_x_mozilla_page_load 10:18:45 INFO - PROCESS | 20243 | _x_x_mozilla_page_load_details 10:18:45 INFO - PROCESS | 20243 | |i|pagename|runs| 10:18:45 INFO - PROCESS | 20243 | |0;ai-astar;103;114;114;115;116;115;114;114;113;113 10:18:45 INFO - PROCESS | 20243 | |1;audio-beat-detection;150;128;125;192;128;130;150;123;191;122 10:18:45 INFO - PROCESS | 20243 | |2;audio-dft;162;161;160;161;161;160;162;162;162;163 10:18:45 INFO - PROCESS | 20243 | |3;audio-fft;74;76;77;106;77;78;75;75;98;76 10:18:45 INFO - PROCESS | 20243 | |4;audio-oscillator;86;85;126;86;86;86;119;85;87;133 10:18:45 INFO - PROCESS | 20243 | |5;imaging-gaussian-blur;117;117;117;117;118;117;117;117;117;116 10:18:45 INFO - PROCESS | 20243 | |6;imaging-darkroom;146;146;146;147;146;145;146;145;145;145 10:18:45 INFO - PROCESS | 20243 | |7;imaging-desaturate;95;95;95;96;95;96;95;95;96;95 10:18:45 INFO - PROCESS | 20243 | |8;json-parse-financial;64;62;63;63;64;63;65;63;63;64 10:18:45 INFO - PROCESS | 20243 | |9;json-stringify-tinderbox;65;67;66;66;66;65;67;66;65;66 10:18:45 INFO - PROCESS | 20243 | |10;stanford-crypto-aes;80;79;79;88;79;80;79;79;79;82 10:18:45 INFO - PROCESS | 20243 | |11;stanford-crypto-ccm;147;137;134;129;129;126;129;136;159;132 10:18:45 INFO - PROCESS | 20243 | |12;stanford-crypto-pbkdf2;164;166;166;168;167;166;168;167;169;165 10:18:45 INFO - PROCESS | 20243 | |13;stanford-crypto-sha256-iterative;67;72;68;67;76;66;67;66;66;68 10:18:45 INFO - PROCESS | 20243 | __end_tp_report 10:18:45 INFO - PROCESS | 20243 | __start_cc_report 10:18:45 INFO - PROCESS | 20243 | _x_x_mozilla_cycle_collect,1915 10:18:45 INFO - PROCESS | 20243 | __end_cc_report 10:18:45 INFO - PROCESS | 20243 | __startTimestamp1470503925102__endTimestamp 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | ------- Summary: start ------- 10:18:45 INFO - PROCESS | 20243 | Number of tests: 14 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.67 (3.2%) stddev-sans-first:0.97 10:18:45 INFO - PROCESS | 20243 | Values: 103.0 114.0 114.0 115.0 116.0 115.0 114.0 114.0 113.0 113.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#1] audio-beat-detection Cycles:10 Average:143.90 Median:140.00 stddev:27.04 (19.3%) stddev-sans-first:28.59 10:18:45 INFO - PROCESS | 20243 | Values: 150.0 128.0 125.0 192.0 128.0 130.0 150.0 123.0 191.0 122.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 10:18:45 INFO - PROCESS | 20243 | Values: 162.0 161.0 160.0 161.0 161.0 160.0 162.0 162.0 162.0 163.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.50 stddev:11.18 (14.6%) stddev-sans-first:11.55 10:18:45 INFO - PROCESS | 20243 | Values: 74.0 76.0 77.0 106.0 77.0 78.0 75.0 75.0 98.0 76.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:86.00 stddev:19.68 (22.9%) stddev-sans-first:20.39 10:18:45 INFO - PROCESS | 20243 | Values: 86.0 85.0 126.0 86.0 86.0 86.0 119.0 85.0 87.0 133.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 10:18:45 INFO - PROCESS | 20243 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 116.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 10:18:45 INFO - PROCESS | 20243 | Values: 146.0 146.0 146.0 147.0 146.0 145.0 146.0 145.0 145.0 145.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:18:45 INFO - PROCESS | 20243 | Values: 95.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 95.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:63.50 stddev:0.84 (1.3%) stddev-sans-first:0.87 10:18:45 INFO - PROCESS | 20243 | Values: 64.0 62.0 63.0 63.0 64.0 63.0 65.0 63.0 63.0 64.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 10:18:45 INFO - PROCESS | 20243 | Values: 65.0 67.0 66.0 66.0 66.0 65.0 67.0 66.0 65.0 66.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:79.50 stddev:2.84 (3.6%) stddev-sans-first:3.00 10:18:45 INFO - PROCESS | 20243 | Values: 80.0 79.0 79.0 88.0 79.0 80.0 79.0 79.0 79.0 82.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#11] stanford-crypto-ccm Cycles:10 Average:135.80 Median:135.00 stddev:10.10 (7.5%) stddev-sans-first:9.86 10:18:45 INFO - PROCESS | 20243 | Values: 147.0 137.0 134.0 129.0 129.0 126.0 129.0 136.0 159.0 132.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.00 stddev:1.51 (0.9%) stddev-sans-first:1.27 10:18:45 INFO - PROCESS | 20243 | Values: 164.0 166.0 166.0 168.0 167.0 166.0 168.0 167.0 169.0 165.0 10:18:45 INFO - PROCESS | 20243 | 10:18:45 INFO - PROCESS | 20243 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.50 stddev:3.23 (4.8%) stddev-sans-first:3.40 10:18:45 INFO - PROCESS | 20243 | Values: 67.0 72.0 68.0 67.0 76.0 66.0 67.0 66.0 66.0 68.0 10:18:45 INFO - PROCESS | 20243 | -------- Summary: end -------- 10:18:45 INFO - PROCESS | 20243 | 10:18:46 INFO - TEST-INFO | 20243: exit 0 10:18:46 INFO - TEST-OK | kraken | took 63896ms 10:18:46 INFO - SUITE-END | took 1184s 10:18:46 INFO - Completed test suite (00:19:44) 10:18:46 INFO - javascript benchmark 10:18:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2569.7944164466844, 2573.0736934703505, 2611.464649746802, 2618.633391325214, 2619.6201550775127, 4486.237339652259, 4549.681522293441, 4561.794719237634, 4583.587477127058, 4599.034202817408, 4595.701279416836, 4620.491745907956, 4681.297805329194, 4681.742504162278, 4745.027269409771, 4240.957590424103, 4252.893677658068, 4269.765162916038, 4278.080212754257, 4367.650587952956, 4314.590113939171, 4342.131573685259, 4350.2387082260475, 4361.433013708213, 4382.101669157815, 4125.752454852718, 4145.461090058303, 4154.750714957112, 4342.891427714301, 4387.714798538085, 4169.624733773944, 4177.164567086595, 4194.454720886296, 4204.432401625772, 4210.642095421893, 4215.325547912319, 4219.38818871265, 4219.493660760729, 4284.914301713949, 4333.696641235115, 4634.026854360588, 4669.299605059235, 4671.5795578397765, 4674.182018146811, 4714.504976977437, 4758.833440829589, 5088.6946783193125, 5089.389273287192, 5090.160123579642, 5097.260897169889, 4954.677945933503, 4991.950080499185, 5041.798328066873, 5094.0, 5099.286099946011, 4643.233866412072, 4679.578837904605, 4711.505291944331, 4726.669133160645, 4728.40894888139, 4873.049755297683, 5042.747862606929, 5176.171812510056, 5181.818636347785, 5182.740862956837, 4891.2174052152195, 4943.332650092191, 4959.975200124049, 4963.57809586182, 4969.204927211629, 4997.675151115166, 4999.125153098194, 5039.244113383022, 5039.924401133986, 5040.042391945519, 4764.8332308369045, 4889.511048895153, 4945.505449455026, 4959.826406075842, 5008.023435430056, 4889.3888263967, 4910.312556242057, 4922.212446008693, 4935.925961110586, 4958.9008219835405, 4189.2668782962855, 4191.790410479463, 4191.916161676811, 4228.133232687292, 4229.154169166179, 3638.5451818522683, 3639.6724294813594, 3686.502322186524, 3695.6489133532273, 3739.9065023374633, 4237.809298581509, 4240.639545638655, 4295.162443323585, 4303.031817841023, 4334.956650433518, 4992.825251116266, 4995.825146119941, 5000.099982003201, 5009.474005229471, 5041.747912604355, 4282.036541778075, 4285.442892423965, 4290.528041915387, 4324.048709283952, 4330.805113769888, 4289.442372491619, 4440.222960981815, 4468.776561171929, 4483.2826747720865, 4483.596476317147, 4728.101660684459, 4731.810727570996, 4748.216544270295, 4758.738269395217, 4776.0, 5022.121128802518, 5023.246513023076, 5025.924611130763, 5035.219540971156, 5067.13858644024, 4526.909461810679, 4655.11552804966, 4688.671792974459, 4755.144074066701, 4758.5241475852135, 4930.630202734737, 4936.950630493793, 4961.3798275215595, 4963.057019166346, 4983.75081245929, 4990.476000019946, 4998.4251811042195, 5000.7999680013845, 5002.499750025114, 5040.84877453677, 4651.2557990721325, 4802.27965805118, 4854.732989685601, 4858.125537403302, 4905.828296009622, 4773.355596994361, 4859.781309840995, 4872.561469467765, 4907.607391408607, 4916.410030796327, 4779.545943135397, 4825.252085926687, 4829.806807727651, 4835.274708793709, 4845.466998630219, 4649.604783593297, 4842.249451335048, 4876.073546026244, 4884.291777692286, 4889.462159162418, 4641.976790116163, 4775.594074503706, 4796.3285140079715, 4835.806567737392, 4842.249451335048], "value": 4571.228955841844, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.7471390855247, 552.7070652554142, 554.4150920778579, 585.274259917702, 593.2613895699853, 29606.851965740167, 40030.39954400686, 41285.17429651405, 41304.79347603265, 41335.79332103343, 41977.79011104948, 42706.57293427065, 42726.35910461345, 42800.35799463003, 42816.357754633704, 7325.304096110887, 7727.999999999986, 7867.213278672116, 7873.291403773659, 7880.054393472791, 7362.116546014486, 7607.733729319475, 7610.429217808659, 9735.56189971453, 10112.140468060186, 9603.80792384152, 9621.134097931184, 9668.033196680346, 9696.75758106046, 9737.51312434379, 10092.697219083439, 10125.797484050314, 10149.797004059952, 10209.846852297222, 10211.999999999964, 9919.80160396788, 9996.0, 10039.0, 10100.64647737333, 10108.140808031327, 39708.60291397078, 39776.40335394971, 40042.599574004176, 40092.59907400918, 40110.59889401127, 2962.5852380666724, 3076.753859691242, 3168.0, 3291.226561757985, 3424.8630054797777, 30015.69984300151, 30467.542986855216, 30607.62265698049, 30660.38679226403, 31919.0, 3112.579801726783, 3140.356226973465, 3173.3177366866003, 3214.324991751735, 3262.9836850815595, 265963.0, 267343.0, 267429.0, 267600.66199669073, 268154.0, 55465.0, 55849.0, 55857.0, 55885.0, 55891.44108558862, 33218.50172247418, 34111.31777364439, 34218.82890585531, 35864.82067589645, 36035.459468108, 13747.175169489863, 13857.930710346383, 13863.584092477242, 13979.930100349433, 13999.720005599831, 11602.6519204424, 11760.05919526453, 11770.3526306054, 11773.234739741889, 11779.469923853447, 17034.574135646708, 17540.122993850255, 17560.824391756174, 17574.297028118734, 18389.5402614933, 6570.605763654196, 6930.306969303029, 6994.93005069953, 7000.439964802804, 7385.150707668687, 746.4028776978396, 795.0538858758059, 854.525738215284, 856.8757530158217, 964.3105179796479, 27431.314217144733, 29122.12633620995, 29207.123786286444, 29527.704722952923, 29910.25224369408, 953.5565961827645, 955.4362925873768, 963.1716723617683, 975.6731494949272, 1003.2575893838507, 14868.776968345483, 15453.822969305409, 15680.451184208498, 15713.21433928299, 15782.131982741059, 417012.0, 422124.0, 422832.0, 423497.0, 423566.0, 43950.340744888854, 48616.27075593869, 50188.498115019116, 51880.0, 52365.47634523606, 1156.3235507228198, 1159.0901142603016, 1164.55746816211, 1169.818678104895, 1179.7345597240553, 28453.573196401656, 29816.10551683453, 30075.3984920296, 30234.54648180235, 30724.84637576798, 44473.33290000588, 47092.0, 49171.0, 49207.50792492172, 53765.46234537604, 389231.0, 391674.0, 403316.0, 404831.0, 591995.0, 38775.806120970345, 39193.2161356777, 39245.21509569736, 39248.41127383149, 39812.40281395723, 42611.573884262, 42812.0, 43001.0, 43047.78476107599, 43416.0, 36934.63065369312, 36971.68405365127, 37326.06684832901, 38262.80868595639, 38591.0, 36953.0, 37103.07242318964, 37375.2524949494, 37500.87497375083, 37828.621713783614, 36344.0, 37273.627263727016, 37510.06224844401, 37623.81188094042, 37633.0, 29283.0, 29761.10716678503, 32257.838710806296, 33735.66264337325, 33972.66027339695, 25295.042490099586, 25602.23193304204, 25954.26692572127, 26032.479350413265, 26095.869520652275], "value": 18369.866309960475, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.169192358766, 365.7165696585148, 368.7584632065997, 372.83222549852564, 391.98628048018327, 12655.493780248791, 16389.098599577017, 16497.0, 16579.595958585996, 17470.737938930924, 16867.156642167905, 17035.48893533196, 17247.0, 17829.7325540117, 17983.910080449612, 4866.343043689109, 4913.238448040551, 5075.5178258065425, 5099.566536844372, 5101.030804147219, 3737.8131093445354, 4954.603631709455, 5030.0, 5554.361248456418, 5825.388334224909, 5836.212111364954, 5859.970700146493, 5864.76540938362, 5865.178874957509, 5868.2371291732015, 5773.0, 5843.0, 6018.638881667114, 6053.364396738367, 6057.666828324441, 5305.098133317345, 5305.4164041955355, 5306.628536002481, 5316.548093412064, 5317.521423071942, 19260.036998150037, 19801.801981980283, 19863.503412414662, 20423.183072677075, 20968.370948871558, 1770.0707128757506, 1770.6724256012678, 1774.946751597454, 1819.299569665675, 1873.784514780804, 9066.456012639197, 9159.679411220643, 9462.195713364372, 9631.903680963242, 9812.969638187958, 1716.8025677047049, 1734.479656103164, 1741.547197728587, 1776.3072401763322, 1840.2546968477654, 63917.68041159811, 69229.96155057728, 70058.0, 71304.2869571308, 71647.0, 9721.951390243003, 10124.949375253223, 10133.847992280122, 10143.49282535885, 10258.28192026566, 13988.860111398755, 14009.85990140106, 14010.089344192595, 14011.229382384066, 14014.229217392938, 6990.335918087773, 7058.964705176441, 7140.9285907140265, 7228.855422891618, 7268.709251629982, 6499.480041596661, 6563.638999855054, 6566.901496477557, 6728.586996730742, 6740.090087838177, 4368.257396242647, 4369.60673539383, 4390.473143222834, 4414.9845535526365, 4511.729296242236, 1727.965440691179, 1761.4451447793904, 1777.4667599720033, 1809.5028860172877, 1834.7982770066053, 794.3446656508404, 798.856205882935, 820.8030072782609, 826.574314228173, 975.073680003999, 16324.183790810412, 16399.590010249838, 17558.209880555405, 17832.286708531512, 17838.91080544589, 810.2383759266271, 811.0713233347901, 812.6383759227206, 853.2619284319019, 871.1767379826131, 9403.57683904226, 9636.46999415039, 9856.457894815854, 9857.162141218078, 9999.0, 409879.0, 411669.0, 424837.0, 427411.0, 429236.0, 53034.0, 70373.64813175901, 74210.0, 74379.5372277665, 80538.59730701309, 858.5406807357908, 871.5903525343078, 883.439016224702, 887.1173182683272, 889.7642124836794, 15370.0, 15445.691086178438, 15812.76280855765, 15941.282642281358, 16351.34594616202, 37272.0, 38143.618563815115, 38371.6162838368, 38508.614913850506, 38718.61281387263, 47980.280295794895, 48258.5174148254, 49524.75237623789, 51469.742651286506, 52096.73951630369, 4730.7871145797835, 4855.198892182751, 6516.771912983023, 6723.899141512783, 6729.2934241903895, 7031.0, 9656.468894210604, 10432.843507347548, 10986.395748233925, 11050.668479945614, 5133.743312834418, 5235.240890070994, 9201.171894529525, 9476.810463790824, 9963.800723985625, 9639.518024098907, 9697.806043879224, 9720.465374404475, 9855.408675479493, 9962.601495940082, 9384.76538086526, 9894.208463322773, 10067.647632332833, 10070.144037756872, 10400.427976461368, 7313.414926805949, 7325.267473252632, 7474.738384156529, 7561.0, 7652.579108148882, 9229.400088994194, 9648.614055437703, 9733.513324333613, 9892.763404574429, 10126.79746405053], "value": 7988.021272839128, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.338295926476, 1429.5425463851568, 1501.8948673592854, 1678.6606621882434, 1726.369874995627, 7339.376153026992, 7884.724034658788, 7896.960515197416, 7911.802204944879, 7935.087464941541, 6817.522773405851, 7552.395808335334, 7747.264009919063, 7794.532328060321, 7832.490888092284, 2142.2716276466035, 2165.902534385956, 2168.241115609532, 2169.7287839020123, 2169.9566008679817, 2067.069818581637, 2114.397396741929, 2169.761326254111, 2176.009915488457, 2178.9128434862587, 1775.8756887017914, 2076.885771282579, 2137.3587923622927, 2143.8928053597338, 2171.0447403142643, 2092.686097085434, 2098.7061811346425, 2113.482196861779, 2115.428834214769, 2117.883516406597, 2135.7970992755663, 2137.262644387684, 2139.775323591032, 2142.1645558232303, 2143.3248526714037, 7983.96008019962, 8079.313258373046, 8116.8782468263025, 8118.147594502551, 8119.309858662021, 4288.656907447396, 4416.80124394403, 4425.557444255581, 4425.690201685883, 4433.024734558392, 2733.658292713411, 2835.376217232206, 2859.6997315281806, 2868.899588514392, 2872.569114632801, 857.1342943626919, 897.241830653097, 915.16719784996, 915.6932427636683, 928.8142371525722, 10926.562937482491, 10943.288686235448, 10973.725656858562, 10976.067034302094, 10997.890021099767, 3860.3051450739135, 3988.780617066031, 4106.691998100155, 4109.479492587702, 4264.317709166518, 9280.164785169367, 9290.256779457628, 9293.349465537483, 9296.209822165056, 9302.116298951589, 4572.337011133367, 4574.290984897346, 4574.5425457453985, 4577.679562430664, 4582.839600614029, 3986.920261594752, 4114.485689288839, 4115.176964607031, 4123.0516981094515, 4124.608162224584, 3558.1994051338243, 3565.6790888820133, 3653.4702468141973, 3654.908627284339, 3660.633936606371, 1120.3501968858043, 1142.2118738070706, 1142.9714257143637, 1143.2854465958776, 1150.3155622404656, 575.0257837280359, 719.1406269507993, 858.4849090545623, 879.4064006795516, 881.6297155194834, 5135.12702840518, 5240.371155461293, 5254.894902101936, 5408.972955135199, 5446.645968012067, 866.1054488338872, 867.5271976772674, 903.7876099116702, 904.968326108583, 908.3459908865606, 3680.429533422324, 3747.775133491999, 3781.6029316921877, 3785.280796648628, 3853.0560012797046, 9344.2524598032, 9749.463779492055, 9795.118439340493, 9843.114119729258, 9975.950120249352, 9760.853587196198, 9774.657886974064, 9777.266704997153, 9778.462184579916, 9787.755306117404, 7386.113666359964, 7393.81515462107, 7507.887381689279, 7580.734674286374, 7581.469297149257, 5241.790328386975, 5297.761600728054, 5338.199270109515, 5398.13629819227, 5405.86485337854, 9188.310876684409, 9227.077292270718, 9228.815423691354, 9253.444793312154, 9258.305627077862, 8907.153820387053, 9290.303227258119, 9327.486988215442, 9333.020032896447, 9338.159565639126, 6823.112995310578, 6932.965335173292, 6939.930600693929, 6941.444684425132, 6944.583325000516, 7395.075615548057, 7404.777856664308, 7441.330280274801, 7468.290512401313, 7469.626518674153, 6626.966865165643, 6679.131712877295, 6698.698558564779, 6756.324367563205, 6854.760083397057, 6583.275839657729, 6703.798886033427, 6705.8994115087335, 6717.992301154866, 6768.56004359715, 6195.0, 6205.81382558524, 6240.625562466266, 6243.282022567281, 6280.7487700491465, 4460.955390446054, 4591.678582499193, 4593.471750748707, 4600.079984003146, 4672.112298663243, 4621.884452888705, 4700.8119675213975, 4736.313234580897, 4795.736234507136, 4841.709497430165], "value": 4138.836668185499, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.86717844148575, 306.7791190342953, 313.9340738444927, 315.3267773303995, 315.9636641786195, 4710.563278200148, 4818.903621927564, 4850.587700045491, 4855.344528488653, 4867.513248675131, 4671.836485723001, 4766.641507170457, 4772.498887616803, 4787.760611969405, 4808.807647694097, 3815.294170578445, 3816.3893776995687, 3822.69418446525, 3826.559945606249, 3831.731778775487, 3628.1473853644375, 3667.0564600761504, 3805.7906815125157, 3811.714121440903, 3854.7879866607327, 3776.150366167618, 3786.8106594670294, 3787.280416720828, 3788.4317352397084, 3825.005498570364, 3767.077066118791, 3790.1472168762084, 3791.4881490998637, 3802.315583195024, 3809.609492535232, 3394.4059789536977, 3400.25194457219, 3406.3357645259184, 3406.45496720526, 3418.4017796885446, 4536.274196128604, 4674.205385084544, 4674.228752255874, 4714.457837348715, 4768.8554746860855, 4296.5273819879785, 4301.548337424588, 4313.956860431387, 4340.782960851945, 4343.283358245885, 5019.415001274347, 5033.521815427565, 5066.468020857794, 5079.263506791532, 5083.11045567028, 3567.9108022299392, 3625.9456108158397, 3629.491871138043, 3682.797446140461, 3710.647488488616, 5196.142636464979, 5206.219067139921, 5212.843614691527, 5213.608979326527, 5218.530332270076, 3752.512173417438, 3757.1922036762226, 3757.7933213672964, 3762.6237376262156, 3764.661180493769, 3822.503074600284, 3827.8086095695103, 3843.3466310727244, 3843.9231215375535, 3848.942265866014, 4195.811188496525, 4238.809253583596, 4275.593818587229, 4276.786160691953, 4306.246406878784, 4974.32846565709, 4993.625478089158, 5005.27423523587, 5009.849704508871, 5048.16705243638, 1251.843519560055, 1284.4990453723055, 1307.6142537951328, 1308.30005946818, 1326.4561529772748, 884.1732979664034, 898.0929261445987, 912.2656261709387, 915.6337465014047, 918.2699753695828, 4381.335092664767, 4384.912301753947, 4385.342198670161, 4390.056137930296, 4403.8458653946955, 5114.718690471986, 5115.386153661584, 5116.513931176532, 5126.794928202905, 5140.768665410065, 4369.868903932887, 4370.557715953728, 4392.582704643054, 4404.295312749945, 4409.33859921008, 2291.793738563504, 2409.662647229389, 2432.2581612608324, 2432.452698142939, 2437.2566367258155, 4279.422277992493, 4312.978435107804, 4314.676399270067, 4316.417283666665, 4399.296112621965, 4691.272852707836, 4691.765411729468, 4721.433427988594, 4734.147853386355, 4757.452892917289, 4330.761808100585, 4535.523770004102, 4932.7286999215385, 4984.183936076105, 4984.252362145708, 5209.374875015023, 5237.764300606558, 5244.449332819999, 5258.921116183337, 5267.261024267436, 5138.922916156335, 5153.5361817436615, 5159.291372029715, 5159.664621799571, 5163.8192663256605, 4707.0, 4910.238912968352, 4967.528084831935, 4968.900621987612, 4975.467418133166, 3787.905302367463, 3849.4995650565247, 3866.149447121629, 3892.8832135035996, 3908.8241029153187, 5068.504791086564, 5286.709230992333, 5292.0, 5295.232191332312, 5296.311479507639, 4733.290006499053, 5053.67151135175, 5081.8475445736685, 5083.5678967288195, 5101.872453188552, 5072.873178170576, 5074.213496907985, 5077.7715002824225, 5080.364954380702, 5090.516400941905, 5005.34930459038, 5022.949770502394, 5025.698458092526, 5032.414789341346, 5040.6471546992825, 4848.860517778248, 5054.873628159326, 5110.770015349244, 5113.233015047773, 5113.823820521376, 4944.53026962438, 4968.751562421792, 4987.226979818134, 4992.80028798844, 5000.4749501303295], "value": 3793.109644754588, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.5214208988034, 642.3640595810148, 653.1182903080844, 699.9475039372048, 757.1746795992369, 12139.453724582392, 13273.469061237563, 13291.136076155055, 13328.0, 13349.132306400077, 13198.0, 13351.799723004162, 13367.39846706898, 13370.799438008438, 13374.598762037154, 632.4181752787424, 747.9513831600956, 763.053813271542, 764.5298141642877, 787.2875048081467, 777.8794286885542, 779.4465929190253, 780.1574299756278, 780.4809801482007, 780.9336206422461, 824.2499325613693, 824.3693574415577, 825.2696363718093, 825.5170725125809, 825.859603867344, 659.3900641906265, 665.3280187011154, 670.4267850987433, 681.9838440723336, 822.5311572403732, 587.2688502813982, 587.9382664820175, 599.3018133874032, 604.2115039872964, 604.3925854516183, 12635.241885486901, 12668.619941401772, 12683.175593586388, 12696.17474864131, 12702.745945081047, 11000.779984400348, 11301.604443844508, 11456.828147577793, 11933.701657458547, 12939.611811645666, 12102.0, 12203.816942745865, 12251.917174080429, 12357.93821030889, 12487.25076495404, 2926.9707302926913, 3056.3734434440885, 3085.1670049086847, 3118.8284644344553, 4074.714769966074, 56104.0, 61390.38609613936, 61884.690576546825, 62861.0, 62873.99999999954, 10065.748356291151, 10125.696229113139, 10212.795744085226, 10226.437545935045, 10385.428801415843, 19525.707114393295, 19825.900870495843, 19830.70253946192, 19844.90077549603, 19874.10566524481, 6541.869162616721, 6625.3374662534325, 6657.3342665733035, 6669.0, 6870.896936545956, 3388.0852169913987, 3679.264147170523, 3708.758930669552, 3718.2749363874327, 3806.4861243731743, 2995.715407036328, 3103.984480077585, 3120.953185702216, 3132.7023932726356, 3138.3723255349023, 488.8411266338454, 494.29069285575315, 497.8182963218472, 507.47476361965187, 512.3672264753027, 577.9075347944392, 589.0310439327283, 591.9881602367929, 604.5465900574569, 605.6517502436117, 8067.233612806891, 8300.916990830136, 8474.703385381483, 8526.74419767408, 8544.871826922477, 517.2344929504354, 524.3183860980786, 550.3011175806703, 566.4165909113538, 587.9000569903042, 3829.6936245100324, 4073.796310184479, 4082.8366865325647, 4137.834486620562, 4181.707280490336, 54464.727676362156, 59476.702616487506, 102350.0, 106199.46900265604, 107821.0, 87381.0, 88473.55763221142, 89325.0, 90513.0, 91234.54382728045, 46368.536314637095, 63124.0, 75364.0, 75740.07689500165, 76057.0, 7894.1316455188735, 8092.838143236984, 8178.7955301117945, 8220.0, 8371.121032291765, 45493.31760023536, 45697.54302456933, 46503.76748116373, 46830.0, 46959.53040469551, 65866.34133658602, 67466.32533674796, 67655.32344676687, 68644.0, 68712.3128768706, 9796.51017449139, 9874.703758887244, 9884.973580416141, 10754.0, 11200.663980080612, 13696.178229306273, 13789.586312410644, 14071.929640351733, 14122.858771412155, 14572.05281656689, 11943.82084268754, 12623.621291361274, 13013.349332533524, 13065.542706005244, 13179.868201318248, 12295.0, 12370.13409061357, 12521.311327877054, 13056.804147937599, 13076.553971649684, 12178.817317740064, 12396.442160102997, 12639.851225291906, 12738.108332416641, 12977.0, 9603.135717785433, 10112.191024717858, 10131.0, 10426.113780328757, 10644.361338319726, 9185.632574696938, 9584.185344245818, 9734.805303893741, 10008.449535275338, 10016.449095299828], "value": 6575.706302992585, "unit": "score"}], "name": "dromaeo_css", "value": 6408.273228335508, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 115.0, 116.0, 115.0, 114.0, 114.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 128.0, 125.0, 192.0, 128.0, 130.0, 150.0, 123.0, 191.0, 122.0], "value": 143.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 161.0, 161.0, 160.0, 162.0, 162.0, 162.0, 163.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 77.0, 106.0, 77.0, 78.0, 75.0, 75.0, 98.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 126.0, 86.0, 86.0, 86.0, 119.0, 85.0, 87.0, 133.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 63.0, 64.0, 63.0, 65.0, 63.0, 63.0, 64.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 66.0, 66.0, 65.0, 67.0, 66.0, 65.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 88.0, 79.0, 80.0, 79.0, 79.0, 79.0, 82.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 137.0, 134.0, 129.0, 129.0, 126.0, 129.0, 136.0, 159.0, 132.0], "value": 135.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 166.0, 168.0, 167.0, 166.0, 168.0, 167.0, 169.0, 165.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 68.0, 67.0, 76.0, 66.0, 67.0, 66.0, 66.0, 68.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1535.8999999999999, "alertThreshold": 2.0}]} 10:18:46 INFO - Return code: 0 10:18:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:18:46 INFO - # TBPL SUCCESS # 10:18:46 INFO - Running post-action listener: _resource_record_post_action 10:18:46 INFO - [mozharness: 2016-08-06 17:18:46.400582Z] Finished run-tests step (success) 10:18:46 INFO - Running post-run listener: _resource_record_post_run 10:18:46 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 147456; Write bytes: 529383424; Read time: 320; Write time: 506152 10:18:46 INFO - TinderboxPrint: CPU usage
13.0% 10:18:46 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 320 10:18:46 INFO - TinderboxPrint: I/O write bytes / time
529,383,424 / 506,152 10:18:46 INFO - TinderboxPrint: CPU idle
8,338.5 (87.0%) 10:18:46 INFO - TinderboxPrint: CPU user
1,200.2 (12.5%) 10:18:46 INFO - TinderboxPrint: Swap in / out
0 / 0 10:18:46 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 242130944; Read time: 92; Write time: 291484 10:18:46 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 139264; Write bytes: 287252480; Read time: 208; Write time: 214668 10:18:47 INFO - Running post-run listener: _upload_blobber_files 10:18:47 INFO - Blob upload gear active. 10:18:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:18:47 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 10:18:47 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'] 10:18:47 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 10:18:47 INFO - (blobuploader) - INFO - Open directory for files ... 10:18:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:18:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:18:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:48 INFO - (blobuploader) - INFO - Done attempting. 10:18:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:18:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:18:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:49 INFO - (blobuploader) - INFO - Done attempting. 10:18:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:18:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:18:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:52 INFO - (blobuploader) - INFO - Done attempting. 10:18:52 INFO - (blobuploader) - INFO - Iteration through files over. 10:18:52 INFO - Return code: 0 10:18:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:18:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:18:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b87cbf992df26cff60258c2044501a2878592c2321d1dc8e28c0904552858869d55fca5849c2557cbf144ccfd9e55bac908d622a1df674907603ae055129697b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5352a5ca8d68e8944902b2f754e3ba28254baa0aea21bd4cb8fdbc92e3b46aef2257d27a4223f3359dd7049045bd61b836454c9dbdcf4cef0caf2e03083a9d66", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1cd483d27d3242d86d4a60ce44b6ec3ce47d9d7c7bfff2e91328a6ed68648ed3f094469f2c9edd83cfce85f43139bb48f95a7219f9cae380d2a51f2e6c5fb3d8"} 10:18:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:18:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:18:52 INFO - Contents: 10:18:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b87cbf992df26cff60258c2044501a2878592c2321d1dc8e28c0904552858869d55fca5849c2557cbf144ccfd9e55bac908d622a1df674907603ae055129697b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5352a5ca8d68e8944902b2f754e3ba28254baa0aea21bd4cb8fdbc92e3b46aef2257d27a4223f3359dd7049045bd61b836454c9dbdcf4cef0caf2e03083a9d66", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1cd483d27d3242d86d4a60ce44b6ec3ce47d9d7c7bfff2e91328a6ed68648ed3f094469f2c9edd83cfce85f43139bb48f95a7219f9cae380d2a51f2e6c5fb3d8"} 10:18:52 INFO - Running post-run listener: copy_logs_to_upload_dir 10:18:52 INFO - Copying logs to upload dir... 10:18:52 INFO - mkdir: /builds/slave/test/build/upload/logs 10:18:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.278658 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-06 10:18:52.403458) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-06 10:18:52.406602) ========= 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=14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776 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/b87cbf992df26cff60258c2044501a2878592c2321d1dc8e28c0904552858869d55fca5849c2557cbf144ccfd9e55bac908d622a1df674907603ae055129697b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5352a5ca8d68e8944902b2f754e3ba28254baa0aea21bd4cb8fdbc92e3b46aef2257d27a4223f3359dd7049045bd61b836454c9dbdcf4cef0caf2e03083a9d66", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1cd483d27d3242d86d4a60ce44b6ec3ce47d9d7c7bfff2e91328a6ed68648ed3f094469f2c9edd83cfce85f43139bb48f95a7219f9cae380d2a51f2e6c5fb3d8"} build_url:https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/dNixYkVIQP6JN5IBMADjRA/artifacts/public/build/firefox-51.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/b87cbf992df26cff60258c2044501a2878592c2321d1dc8e28c0904552858869d55fca5849c2557cbf144ccfd9e55bac908d622a1df674907603ae055129697b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5352a5ca8d68e8944902b2f754e3ba28254baa0aea21bd4cb8fdbc92e3b46aef2257d27a4223f3359dd7049045bd61b836454c9dbdcf4cef0caf2e03083a9d66", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1cd483d27d3242d86d4a60ce44b6ec3ce47d9d7c7bfff2e91328a6ed68648ed3f094469f2c9edd83cfce85f43139bb48f95a7219f9cae380d2a51f2e6c5fb3d8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-06 10:18:52.432296) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 10:18:52.432624) ========= 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=14ef55db36f7623abb31b9cf000001d5-1470502692.424668-214121776 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004435 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-06 10:18:52.516193) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-06 10:18:52.516521) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-06 10:18:52.516814) ========= ========= Total master_lag: 0.17 =========