builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-001 starttime: 1468434026.96 results: success (0) buildid: 20160713100643 builduid: 71e95f1dfe83449698140019cfff11d5 revision: b8b27cb2e148119595af75ff7ee3c2af1b7e833a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:26.956176) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:26.956672) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:26.956962) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:26.971555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:26.971820) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-13 11:20:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.3M=0s 2016-07-13 11:20:27 (43.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.079182 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:27.064866) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:27.065181) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020338 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:27.114793) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:27.115134) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b8b27cb2e148119595af75ff7ee3c2af1b7e833a --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 b8b27cb2e148119595af75ff7ee3c2af1b7e833a --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-13 11:20:27,518 truncating revision to first 12 chars 2016-07-13 11:20:27,518 Setting DEBUG logging. 2016-07-13 11:20:27,519 attempt 1/10 2016-07-13 11:20:27,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b8b27cb2e148?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 11:20:27,814 unpacking tar archive at: fx-team-b8b27cb2e148/testing/mozharness/ program finished with exit code 0 elapsedTime=0.624006 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:27.755204) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:27.755490) ========= script_repo_revision: b8b27cb2e148119595af75ff7ee3c2af1b7e833a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:27.755948) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:27.756335) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 11:20:27.774454) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-07-13 11:20:27.774719) ========= /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=8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:20:28 INFO - MultiFileLogger online at 20160713 11:20:28 in /builds/slave/test 11:20:28 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 11:20:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:20:28 INFO - {'append_to_log': False, 11:20:28 INFO - 'base_work_dir': '/builds/slave/test', 11:20:28 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 11:20:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:20:28 INFO - 'branch': 'Fx-Team-Non-PGO', 11:20:28 INFO - 'buildbot_json_path': 'buildprops.json', 11:20:28 INFO - 'config_files': ('talos/linux_config.py',), 11:20:28 INFO - 'default_actions': ('clobber', 11:20:28 INFO - 'read-buildbot-config', 11:20:28 INFO - 'download-and-extract', 11:20:28 INFO - 'populate-webroot', 11:20:28 INFO - 'create-virtualenv', 11:20:28 INFO - 'install', 11:20:28 INFO - 'run-tests'), 11:20:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:20:28 INFO - 'download_minidump_stackwalk': True, 11:20:28 INFO - 'download_symbols': 'ondemand', 11:20:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:20:28 INFO - 'tooltool.py': '/tools/tooltool.py', 11:20:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:20:28 INFO - '/tools/misc-python/virtualenv.py')}, 11:20:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:20:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:20:28 INFO - 'installer_path': 'installer.exe', 11:20:28 INFO - 'log_level': 'info', 11:20:28 INFO - 'log_name': 'talos', 11:20:28 INFO - 'log_to_console': True, 11:20:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:20:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:20:28 INFO - 'opt_config_files': (), 11:20:28 INFO - 'pip_index': False, 11:20:28 INFO - 'sps_profile': False, 11:20:28 INFO - 'sps_profile_interval': 0, 11:20:28 INFO - 'suite': 'dromaeojs', 11:20:28 INFO - 'system_bits': '32', 11:20:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:20:28 INFO - 'title': 'talos-linux64-ix-001', 11:20:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:20:28 INFO - 'use_talos_json': True, 11:20:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:20:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:20:28 INFO - 'work_dir': 'build'} 11:20:28 INFO - ##### 11:20:28 INFO - ##### Running clobber step. 11:20:28 INFO - ##### 11:20:28 INFO - Running pre-action listener: _resource_record_pre_action 11:20:28 INFO - Running main action method: clobber 11:20:28 INFO - rmtree: /builds/slave/test/build 11:20:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:20:28 INFO - Running post-action listener: _resource_record_post_action 11:20:28 INFO - ##### 11:20:28 INFO - ##### Finished clobber step (success) 11:20:28 INFO - ##### 11:20:28 INFO - ##### 11:20:28 INFO - ##### Running read-buildbot-config step. 11:20:28 INFO - ##### 11:20:28 INFO - Running pre-action listener: _resource_record_pre_action 11:20:28 INFO - Running main action method: read_buildbot_config 11:20:28 INFO - Using buildbot properties: 11:20:28 INFO - { 11:20:28 INFO - "project": "", 11:20:28 INFO - "product": "firefox", 11:20:28 INFO - "script_repo_revision": "production", 11:20:28 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 11:20:28 INFO - "repository": "", 11:20:28 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 11:20:28 INFO - "buildid": "20160713100643", 11:20:28 INFO - "pgo_build": "False", 11:20:28 INFO - "basedir": "/builds/slave/test", 11:20:28 INFO - "buildnumber": 1805, 11:20:28 INFO - "slavename": "talos-linux64-ix-001", 11:20:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:20:28 INFO - "platform": "ubuntu64_hw", 11:20:28 INFO - "scheduler": "tests-fx-team-linux64-talos", 11:20:28 INFO - "branch": "fx-team", 11:20:28 INFO - "repo_path": "integration/fx-team", 11:20:28 INFO - "revision": "b8b27cb2e148119595af75ff7ee3c2af1b7e833a", 11:20:28 INFO - "stage_platform": "linux64", 11:20:28 INFO - "builduid": "71e95f1dfe83449698140019cfff11d5", 11:20:28 INFO - "slavebuilddir": "test" 11:20:28 INFO - } 11:20:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 11:20:28 INFO - Running post-action listener: _resource_record_post_action 11:20:28 INFO - ##### 11:20:28 INFO - ##### Finished read-buildbot-config step (success) 11:20:28 INFO - ##### 11:20:28 INFO - ##### 11:20:28 INFO - ##### Running download-and-extract step. 11:20:28 INFO - ##### 11:20:28 INFO - Running pre-action listener: _resource_record_pre_action 11:20:28 INFO - Running main action method: download_and_extract 11:20:28 INFO - mkdir: /builds/slave/test/build/tests 11:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:20:28 INFO - https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:20:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:20:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:20:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:20:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:20:32 INFO - Downloaded 1361 bytes. 11:20:32 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:20:32 INFO - Using the following test package requirements: 11:20:32 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:20:32 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:20:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:20:32 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:20:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:20:32 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:20:32 INFO - u'jsshell-linux-x86_64.zip'], 11:20:32 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:20:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:20:32 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:20:32 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:20:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:20:32 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:20:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:20:32 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:20:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:20:32 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:20:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:20:32 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:20:32 INFO - https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:20:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:20:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:20:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:20:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:20:35 INFO - Downloaded 22755434 bytes. 11:20:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:20:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:20:36 INFO - Return code: 0 11:20:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:20:36 INFO - https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:20:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:20:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:20:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:20:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:20:54 INFO - Downloaded 22755434 bytes. 11:20:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:20:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:20:56 INFO - Return code: 0 11:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:20:56 INFO - https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:20:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:20:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:20:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:20:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:20:57 INFO - Downloaded 14403317 bytes. 11:20:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:20:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 11:20:57 INFO - Return code: 0 11:20:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:20:57 INFO - https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:20:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:20:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:20:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:20:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:20:58 INFO - Downloaded 57052075 bytes. 11:20:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:20:58 INFO - mkdir: /builds/slave/test/properties 11:20:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:20:58 INFO - Writing to file /builds/slave/test/properties/build_url 11:20:58 INFO - Contents: 11:20:58 INFO - build_url:https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:20:59 INFO - Running post-action listener: _resource_record_post_action 11:20:59 INFO - Running post-action listener: set_extra_try_arguments 11:20:59 INFO - ##### 11:20:59 INFO - ##### Finished download-and-extract step (success) 11:20:59 INFO - ##### 11:20:59 INFO - ##### 11:20:59 INFO - ##### Running populate-webroot step. 11:20:59 INFO - ##### 11:20:59 INFO - Running pre-action listener: _resource_record_pre_action 11:20:59 INFO - Running main action method: populate_webroot 11:20:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:20:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:20:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:20:59 INFO - '--tppagecycles', 11:20:59 INFO - '7'], 11:20:59 INFO - 'tests': ['tsvgm']}}, 11:20:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:20:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:20:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:20:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:20:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:20:59 INFO - 'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:20:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:20:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:20:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:20:59 INFO - 'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['damp', 'tps']}, 11:20:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:20:59 INFO - 'tests': ['damp', 'tps']}, 11:20:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['dromaeo_dom']}, 11:20:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:20:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['basic_compositor_video']}, 11:20:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:20:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['a11yr', 11:20:59 INFO - 'ts_paint', 11:20:59 INFO - 'tpaint', 11:20:59 INFO - 'sessionrestore', 11:20:59 INFO - 'sessionrestore_no_auto_restore', 11:20:59 INFO - 'tabpaint']}, 11:20:59 INFO - 'other-e10s': {'tests': ['a11yr', 11:20:59 INFO - 'ts_paint', 11:20:59 INFO - 'tpaint', 11:20:59 INFO - 'sessionrestore', 11:20:59 INFO - 'sessionrestore_no_auto_restore', 11:20:59 INFO - 'tabpaint']}, 11:20:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:20:59 INFO - 'ts_paint', 11:20:59 INFO - 'tpaint', 11:20:59 INFO - 'sessionrestore', 11:20:59 INFO - 'sessionrestore_no_auto_restore', 11:20:59 INFO - 'tabpaint']}, 11:20:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:20:59 INFO - 'ts_paint', 11:20:59 INFO - 'tpaint', 11:20:59 INFO - 'sessionrestore', 11:20:59 INFO - 'sessionrestore_no_auto_restore', 11:20:59 INFO - 'tabpaint']}, 11:20:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['a11yr', 11:20:59 INFO - 'ts_paint', 11:20:59 INFO - 'tpaint', 11:20:59 INFO - 'sessionrestore', 11:20:59 INFO - 'sessionrestore_no_auto_restore', 11:20:59 INFO - 'tabpaint']}, 11:20:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['a11yr', 11:20:59 INFO - 'ts_paint', 11:20:59 INFO - 'tpaint', 11:20:59 INFO - 'sessionrestore', 11:20:59 INFO - 'sessionrestore_no_auto_restore', 11:20:59 INFO - 'tabpaint']}, 11:20:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['tsvgx', 11:20:59 INFO - 'tsvgr_opacity', 11:20:59 INFO - 'tart', 11:20:59 INFO - 'tscrollx', 11:20:59 INFO - 'cart']}, 11:20:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:20:59 INFO - 'tsvgr_opacity', 11:20:59 INFO - 'tart', 11:20:59 INFO - 'tscrollx', 11:20:59 INFO - 'cart']}, 11:20:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:20:59 INFO - 'talos_options': ['--disable-e10s'], 11:20:59 INFO - 'tests': ['tp5o']}, 11:20:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:20:59 INFO - 'tests': ['tp5o']}, 11:20:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:20:59 INFO - 'talos_options': ['--disable-e10s', 11:20:59 INFO - '--xperf_path', 11:20:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:20:59 INFO - 'tests': ['tp5n']}, 11:20:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:20:59 INFO - 'talos_options': ['--xperf_path', 11:20:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:20:59 INFO - 'tests': ['tp5n']}}, 11:20:59 INFO - 'talos.zip': {'path': '', 11:20:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:20:59 INFO - Running post-action listener: _resource_record_post_action 11:20:59 INFO - ##### 11:20:59 INFO - ##### Finished populate-webroot step (success) 11:20:59 INFO - ##### 11:20:59 INFO - ##### 11:20:59 INFO - ##### Running create-virtualenv step. 11:20:59 INFO - ##### 11:20:59 INFO - Running pre-action listener: _resource_record_pre_action 11:20:59 INFO - Running main action method: create_virtualenv 11:20:59 INFO - Creating virtualenv /builds/slave/test/build/venv 11:20:59 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 11:20:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:20:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:20:59 INFO - Using real prefix '/usr' 11:20:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:21:00 INFO - Installing distribute.............................................................................................................................................................................................done. 11:21:02 INFO - Installing pip.................done. 11:21:02 INFO - Return code: 0 11:21:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:02 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 0xb56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', '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 11:21:02 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 11:21:02 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 11:21:02 INFO - Using env: {'DISPLAY': ':0', 11:21:02 INFO - 'HOME': '/home/cltbld', 11:21:02 INFO - 'LANG': 'en_US.UTF-8', 11:21:02 INFO - 'LANGUAGE': 'en_US:en', 11:21:02 INFO - 'LOGNAME': 'cltbld', 11:21:02 INFO - 'MAIL': '/var/mail/cltbld', 11:21:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:02 INFO - 'MOZ_NO_REMOTE': '1', 11:21:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:02 INFO - 'NO_EM_RESTART': '1', 11:21:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:02 INFO - 'PWD': '/builds/slave/test', 11:21:02 INFO - 'SHELL': '/bin/bash', 11:21:02 INFO - 'SHLVL': '1', 11:21:02 INFO - 'TERM': 'linux', 11:21:02 INFO - 'TMOUT': '86400', 11:21:02 INFO - 'USER': 'cltbld', 11:21:02 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:02 INFO - '_': '/tools/buildbot/bin/python'} 11:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:02 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 11:21:02 INFO - Cleaning up... 11:21:02 INFO - Return code: 0 11:21:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:02 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 0xb56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', '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 11:21:02 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 11:21:02 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 11:21:02 INFO - Using env: {'DISPLAY': ':0', 11:21:02 INFO - 'HOME': '/home/cltbld', 11:21:02 INFO - 'LANG': 'en_US.UTF-8', 11:21:02 INFO - 'LANGUAGE': 'en_US:en', 11:21:02 INFO - 'LOGNAME': 'cltbld', 11:21:02 INFO - 'MAIL': '/var/mail/cltbld', 11:21:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:02 INFO - 'MOZ_NO_REMOTE': '1', 11:21:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:02 INFO - 'NO_EM_RESTART': '1', 11:21:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:02 INFO - 'PWD': '/builds/slave/test', 11:21:02 INFO - 'SHELL': '/bin/bash', 11:21:02 INFO - 'SHLVL': '1', 11:21:02 INFO - 'TERM': 'linux', 11:21:02 INFO - 'TMOUT': '86400', 11:21:02 INFO - 'USER': 'cltbld', 11:21:02 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:02 INFO - '_': '/tools/buildbot/bin/python'} 11:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:02 INFO - Downloading/unpacking psutil>=3.1.1 11:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:21:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:21:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:21:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:21:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:21:04 INFO - Installing collected packages: psutil 11:21:04 INFO - Running setup.py install for psutil 11:21:04 INFO - building 'psutil._psutil_linux' extension 11:21:04 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 11:21:04 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 11:21:04 INFO - building 'psutil._psutil_posix' extension 11:21:04 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 11:21:04 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 11:21:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:21:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:21:05 INFO - Successfully installed psutil 11:21:05 INFO - Cleaning up... 11:21:05 INFO - Return code: 0 11:21:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:05 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 0xb56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', '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 11:21:05 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 11:21:05 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 11:21:05 INFO - Using env: {'DISPLAY': ':0', 11:21:05 INFO - 'HOME': '/home/cltbld', 11:21:05 INFO - 'LANG': 'en_US.UTF-8', 11:21:05 INFO - 'LANGUAGE': 'en_US:en', 11:21:05 INFO - 'LOGNAME': 'cltbld', 11:21:05 INFO - 'MAIL': '/var/mail/cltbld', 11:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:05 INFO - 'MOZ_NO_REMOTE': '1', 11:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:05 INFO - 'NO_EM_RESTART': '1', 11:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:05 INFO - 'PWD': '/builds/slave/test', 11:21:05 INFO - 'SHELL': '/bin/bash', 11:21:05 INFO - 'SHLVL': '1', 11:21:05 INFO - 'TERM': 'linux', 11:21:05 INFO - 'TMOUT': '86400', 11:21:05 INFO - 'USER': 'cltbld', 11:21:05 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:05 INFO - '_': '/tools/buildbot/bin/python'} 11:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:21:05 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 11:21:05 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 11:21:05 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 11:21:05 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 11:21:05 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 11:21:05 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 11:21:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:21:07 INFO - Installing collected packages: mozsystemmonitor 11:21:07 INFO - Running setup.py install for mozsystemmonitor 11:21:07 INFO - Successfully installed mozsystemmonitor 11:21:07 INFO - Cleaning up... 11:21:07 INFO - Return code: 0 11:21:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:07 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 0xb56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', '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 11:21:07 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 11:21:07 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 11:21:07 INFO - Using env: {'DISPLAY': ':0', 11:21:07 INFO - 'HOME': '/home/cltbld', 11:21:07 INFO - 'LANG': 'en_US.UTF-8', 11:21:07 INFO - 'LANGUAGE': 'en_US:en', 11:21:07 INFO - 'LOGNAME': 'cltbld', 11:21:07 INFO - 'MAIL': '/var/mail/cltbld', 11:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:07 INFO - 'MOZ_NO_REMOTE': '1', 11:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:07 INFO - 'NO_EM_RESTART': '1', 11:21:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:07 INFO - 'PWD': '/builds/slave/test', 11:21:07 INFO - 'SHELL': '/bin/bash', 11:21:07 INFO - 'SHLVL': '1', 11:21:07 INFO - 'TERM': 'linux', 11:21:07 INFO - 'TMOUT': '86400', 11:21:07 INFO - 'USER': 'cltbld', 11:21:07 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:07 INFO - '_': '/tools/buildbot/bin/python'} 11:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:07 INFO - Downloading/unpacking jsonschema==2.5.1 11:21:07 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 11:21:07 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 11:21:07 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 11:21:07 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 11:21:07 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 11:21:07 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 11:21:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:21:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:21:09 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 11:21:09 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 11:21:09 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 11:21:09 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 11:21:09 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 11:21:09 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 11:21:09 INFO - Downloading functools32-3.2.3-2.tar.gz 11:21:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:21:09 INFO - warning: no files found matching '*.txt' 11:21:09 INFO - no previously-included directories found matching 'build' 11:21:09 INFO - no previously-included directories found matching 'dist' 11:21:09 INFO - no previously-included directories found matching '.git*' 11:21:09 INFO - Installing collected packages: jsonschema, functools32 11:21:09 INFO - Running setup.py install for functools32 11:21:09 INFO - warning: no files found matching '*.txt' 11:21:09 INFO - no previously-included directories found matching 'build' 11:21:09 INFO - no previously-included directories found matching 'dist' 11:21:09 INFO - no previously-included directories found matching '.git*' 11:21:10 INFO - Successfully installed jsonschema functools32 11:21:10 INFO - Cleaning up... 11:21:10 INFO - Return code: 0 11:21:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:10 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 0xb56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', '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 11:21:10 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 11:21:10 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 11:21:10 INFO - Using env: {'DISPLAY': ':0', 11:21:10 INFO - 'HOME': '/home/cltbld', 11:21:10 INFO - 'LANG': 'en_US.UTF-8', 11:21:10 INFO - 'LANGUAGE': 'en_US:en', 11:21:10 INFO - 'LOGNAME': 'cltbld', 11:21:10 INFO - 'MAIL': '/var/mail/cltbld', 11:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:10 INFO - 'MOZ_NO_REMOTE': '1', 11:21:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:10 INFO - 'NO_EM_RESTART': '1', 11:21:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:10 INFO - 'PWD': '/builds/slave/test', 11:21:10 INFO - 'SHELL': '/bin/bash', 11:21:10 INFO - 'SHLVL': '1', 11:21:10 INFO - 'TERM': 'linux', 11:21:10 INFO - 'TMOUT': '86400', 11:21:10 INFO - 'USER': 'cltbld', 11:21:10 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:10 INFO - '_': '/tools/buildbot/bin/python'} 11:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:10 INFO - Downloading/unpacking blobuploader==1.2.4 11:21:10 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 11:21:10 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 11:21:10 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 11:21:10 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 11:21:10 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 11:21:10 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 11:21:12 INFO - Downloading blobuploader-1.2.4.tar.gz 11:21:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:21:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:21:12 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 11:21:12 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 11:21:12 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 11:21:12 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 11:21:12 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 11:21:12 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 11:21:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:21:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:21:12 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 11:21:12 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 11:21:12 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 11:21:12 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 11:21:12 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 11:21:12 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 11:21:12 INFO - Downloading docopt-0.6.1.tar.gz 11:21:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:21:12 INFO - Installing collected packages: blobuploader, requests, docopt 11:21:12 INFO - Running setup.py install for blobuploader 11:21:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:21:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:21:13 INFO - Running setup.py install for requests 11:21:13 INFO - Running setup.py install for docopt 11:21:13 INFO - Successfully installed blobuploader requests docopt 11:21:13 INFO - Cleaning up... 11:21:13 INFO - Return code: 0 11:21:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:13 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 0xb56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', '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 11:21:13 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 11:21:13 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 11:21:13 INFO - Using env: {'DISPLAY': ':0', 11:21:13 INFO - 'HOME': '/home/cltbld', 11:21:13 INFO - 'LANG': 'en_US.UTF-8', 11:21:13 INFO - 'LANGUAGE': 'en_US:en', 11:21:13 INFO - 'LOGNAME': 'cltbld', 11:21:13 INFO - 'MAIL': '/var/mail/cltbld', 11:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:13 INFO - 'MOZ_NO_REMOTE': '1', 11:21:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:13 INFO - 'NO_EM_RESTART': '1', 11:21:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:13 INFO - 'PWD': '/builds/slave/test', 11:21:13 INFO - 'SHELL': '/bin/bash', 11:21:13 INFO - 'SHLVL': '1', 11:21:13 INFO - 'TERM': 'linux', 11:21:13 INFO - 'TMOUT': '86400', 11:21:13 INFO - 'USER': 'cltbld', 11:21:13 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:13 INFO - '_': '/tools/buildbot/bin/python'} 11:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:21:13 INFO - Running setup.py (path:/tmp/pip-pp8abc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:21:13 INFO - Running setup.py (path:/tmp/pip-nQhEGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:21:13 INFO - Running setup.py (path:/tmp/pip-C4MJNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:21:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:21:14 INFO - Running setup.py (path:/tmp/pip-DdMxyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:21:14 INFO - Running setup.py (path:/tmp/pip-z7LOQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:21:14 INFO - Running setup.py (path:/tmp/pip-kFkM1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:21:14 INFO - Running setup.py (path:/tmp/pip-S3pqfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:21:14 INFO - Running setup.py (path:/tmp/pip-sPKX6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:21:14 INFO - Running setup.py (path:/tmp/pip-gsljU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:21:14 INFO - Running setup.py (path:/tmp/pip-taTtYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:21:14 INFO - Running setup.py (path:/tmp/pip-qcY1mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:21:14 INFO - Running setup.py (path:/tmp/pip-z5F4Jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:21:14 INFO - Running setup.py (path:/tmp/pip-NLehyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:21:14 INFO - Running setup.py (path:/tmp/pip-5A16VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:21:14 INFO - Running setup.py (path:/tmp/pip-CI1dHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:21:14 INFO - Running setup.py (path:/tmp/pip-lSg9mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:21:14 INFO - Running setup.py (path:/tmp/pip-_80JEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:21:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:21:15 INFO - Running setup.py install for manifestparser 11:21:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:21:15 INFO - Running setup.py install for mozcrash 11:21:15 INFO - Running setup.py install for mozdebug 11:21:15 INFO - Running setup.py install for mozdevice 11:21:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:21:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:21:15 INFO - Running setup.py install for mozfile 11:21:15 INFO - Running setup.py install for mozhttpd 11:21:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:21:15 INFO - Running setup.py install for mozinfo 11:21:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:21:15 INFO - Running setup.py install for mozInstall 11:21:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:21:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:21:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:21:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:21:15 INFO - Running setup.py install for mozleak 11:21:15 INFO - Running setup.py install for mozlog 11:21:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:21:16 INFO - Running setup.py install for moznetwork 11:21:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:21:16 INFO - Running setup.py install for mozprocess 11:21:16 INFO - Running setup.py install for mozprofile 11:21:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:21:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:21:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:21:16 INFO - Running setup.py install for mozrunner 11:21:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:21:16 INFO - Running setup.py install for mozscreenshot 11:21:16 INFO - Running setup.py install for moztest 11:21:16 INFO - Running setup.py install for mozversion 11:21:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:21:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:21:16 INFO - Cleaning up... 11:21:16 INFO - Return code: 0 11:21:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:16 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 0xb56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', '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 11:21:16 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 11:21:16 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 11:21:16 INFO - Using env: {'DISPLAY': ':0', 11:21:16 INFO - 'HOME': '/home/cltbld', 11:21:16 INFO - 'LANG': 'en_US.UTF-8', 11:21:16 INFO - 'LANGUAGE': 'en_US:en', 11:21:16 INFO - 'LOGNAME': 'cltbld', 11:21:16 INFO - 'MAIL': '/var/mail/cltbld', 11:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:16 INFO - 'MOZ_NO_REMOTE': '1', 11:21:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:16 INFO - 'NO_EM_RESTART': '1', 11:21:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:16 INFO - 'PWD': '/builds/slave/test', 11:21:16 INFO - 'SHELL': '/bin/bash', 11:21:16 INFO - 'SHLVL': '1', 11:21:16 INFO - 'TERM': 'linux', 11:21:16 INFO - 'TMOUT': '86400', 11:21:16 INFO - 'USER': 'cltbld', 11:21:16 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:16 INFO - '_': '/tools/buildbot/bin/python'} 11:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:21:17 INFO - Running setup.py (path:/tmp/pip-PTeHTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:21:17 INFO - Running setup.py (path:/tmp/pip-j_KSVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:21:17 INFO - Running setup.py (path:/tmp/pip-vP7kns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:21:17 INFO - Running setup.py (path:/tmp/pip-o08319-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:21:17 INFO - Running setup.py (path:/tmp/pip-89j1Rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:21:17 INFO - Running setup.py (path:/tmp/pip-bQXPAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:21:17 INFO - Running setup.py (path:/tmp/pip-gUgc4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:21:17 INFO - Running setup.py (path:/tmp/pip-30LYe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:21:17 INFO - Running setup.py (path:/tmp/pip-wYXcxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:21:17 INFO - Running setup.py (path:/tmp/pip-6tkYNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:21:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:21:17 INFO - Running setup.py (path:/tmp/pip-HbYRxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:21:17 INFO - Running setup.py (path:/tmp/pip-aawyYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:21:17 INFO - Running setup.py (path:/tmp/pip-ba5DxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:21:17 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)) 11:21:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:21:17 INFO - Running setup.py (path:/tmp/pip-NGkIKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:21:18 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)) 11:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:21:18 INFO - Running setup.py (path:/tmp/pip-ym4vvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:21:18 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)) 11:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:21:18 INFO - Running setup.py (path:/tmp/pip-4KYx63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:21:18 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)) 11:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:21:18 INFO - Running setup.py (path:/tmp/pip-5n7bLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:21:18 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)) 11:21:18 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)) 11:21:18 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)) 11:21:18 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)) 11:21:18 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)) 11:21:18 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)) 11:21:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:21:18 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 11:21:18 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 11:21:18 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 11:21:18 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 11:21:18 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 11:21:18 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 11:21:20 INFO - Downloading blessings-1.6.tar.gz 11:21:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:21:20 INFO - Installing collected packages: blessings 11:21:20 INFO - Running setup.py install for blessings 11:21:20 INFO - Successfully installed blessings 11:21:20 INFO - Cleaning up... 11:21:20 INFO - Return code: 0 11:21:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:21:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:21:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:21:20 INFO - Reading from file tmpfile_stdout 11:21:20 INFO - Current package versions: 11:21:20 INFO - argparse == 1.2.1 11:21:20 INFO - blessings == 1.6 11:21:20 INFO - blobuploader == 1.2.4 11:21:20 INFO - docopt == 0.6.1 11:21:20 INFO - functools32 == 3.2.3-2 11:21:20 INFO - jsonschema == 2.5.1 11:21:20 INFO - manifestparser == 1.1 11:21:20 INFO - mozInstall == 1.12 11:21:20 INFO - mozcrash == 0.17 11:21:20 INFO - mozdebug == 0.1 11:21:20 INFO - mozdevice == 0.48 11:21:20 INFO - mozfile == 1.2 11:21:20 INFO - mozhttpd == 0.7 11:21:20 INFO - mozinfo == 0.9 11:21:20 INFO - mozleak == 0.1 11:21:20 INFO - mozlog == 3.2 11:21:20 INFO - moznetwork == 0.27 11:21:20 INFO - mozprocess == 0.23 11:21:20 INFO - mozprofile == 0.28 11:21:20 INFO - mozrunner == 6.12 11:21:20 INFO - mozscreenshot == 0.1 11:21:20 INFO - mozsystemmonitor == 0.3 11:21:20 INFO - moztest == 0.7 11:21:20 INFO - mozversion == 1.4 11:21:20 INFO - psutil == 3.1.1 11:21:20 INFO - requests == 1.2.3 11:21:20 INFO - wsgiref == 0.1.2 11:21:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:20 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 0xb56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', '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 11:21:20 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 11:21:20 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 11:21:20 INFO - Using env: {'DISPLAY': ':0', 11:21:20 INFO - 'HOME': '/home/cltbld', 11:21:20 INFO - 'LANG': 'en_US.UTF-8', 11:21:20 INFO - 'LANGUAGE': 'en_US:en', 11:21:20 INFO - 'LOGNAME': 'cltbld', 11:21:20 INFO - 'MAIL': '/var/mail/cltbld', 11:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:20 INFO - 'MOZ_NO_REMOTE': '1', 11:21:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:20 INFO - 'NO_EM_RESTART': '1', 11:21:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:20 INFO - 'PWD': '/builds/slave/test', 11:21:20 INFO - 'SHELL': '/bin/bash', 11:21:20 INFO - 'SHLVL': '1', 11:21:20 INFO - 'TERM': 'linux', 11:21:20 INFO - 'TMOUT': '86400', 11:21:20 INFO - 'USER': 'cltbld', 11:21:20 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:20 INFO - '_': '/tools/buildbot/bin/python'} 11:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:20 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)) 11:21:20 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)) 11:21:20 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)) 11:21:20 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)) 11:21:20 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)) 11:21:20 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)) 11:21:20 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)) 11:21:20 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)) 11:21:20 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)) 11:21:20 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)) 11:21:20 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)) 11:21:20 INFO - Cleaning up... 11:21:20 INFO - Return code: 0 11:21:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:21:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:21:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:21:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcb98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe17180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', '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 11:21:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:21:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:21:20 INFO - Using env: {'DISPLAY': ':0', 11:21:20 INFO - 'HOME': '/home/cltbld', 11:21:20 INFO - 'LANG': 'en_US.UTF-8', 11:21:20 INFO - 'LANGUAGE': 'en_US:en', 11:21:20 INFO - 'LOGNAME': 'cltbld', 11:21:20 INFO - 'MAIL': '/var/mail/cltbld', 11:21:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:20 INFO - 'MOZ_NO_REMOTE': '1', 11:21:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:20 INFO - 'NO_EM_RESTART': '1', 11:21:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:20 INFO - 'PWD': '/builds/slave/test', 11:21:20 INFO - 'SHELL': '/bin/bash', 11:21:20 INFO - 'SHLVL': '1', 11:21:20 INFO - 'TERM': 'linux', 11:21:20 INFO - 'TMOUT': '86400', 11:21:20 INFO - 'USER': 'cltbld', 11:21:20 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:20 INFO - '_': '/tools/buildbot/bin/python'} 11:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:21:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:21:21 INFO - Cleaning up... 11:21:21 INFO - Return code: 0 11:21:21 INFO - Running post-action listener: _resource_record_post_action 11:21:21 INFO - Running post-action listener: _start_resource_monitoring 11:21:21 INFO - Starting resource monitoring. 11:21:21 INFO - ##### 11:21:21 INFO - ##### Finished create-virtualenv step (success) 11:21:21 INFO - ##### 11:21:21 INFO - ##### 11:21:21 INFO - ##### Running install step. 11:21:21 INFO - ##### 11:21:21 INFO - Running pre-action listener: _resource_record_pre_action 11:21:21 INFO - Running main action method: install 11:21:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:21:21 INFO - Reading from file tmpfile_stdout 11:21:21 INFO - Detecting whether we're running mozinstall >=1.0... 11:21:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:21:21 INFO - Reading from file tmpfile_stdout 11:21:21 INFO - Output received: 11:21:21 INFO - Usage: mozinstall [options] installer 11:21:21 INFO - Options: 11:21:21 INFO - -h, --help show this help message and exit 11:21:21 INFO - -d DEST, --destination=DEST 11:21:21 INFO - Directory to install application into. [default: 11:21:21 INFO - "/builds/slave/test"] 11:21:21 INFO - --app=APP Application being installed. [default: firefox] 11:21:21 INFO - mkdir: /builds/slave/test/build/application 11:21:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:21:37 INFO - Reading from file tmpfile_stdout 11:21:37 INFO - Output received: 11:21:37 INFO - /builds/slave/test/build/application/firefox/firefox 11:21:37 INFO - Running post-action listener: _resource_record_post_action 11:21:37 INFO - ##### 11:21:37 INFO - ##### Finished install step (success) 11:21:37 INFO - ##### 11:21:37 INFO - ##### 11:21:37 INFO - ##### Running run-tests step. 11:21:37 INFO - ##### 11:21:37 INFO - Running pre-action listener: _resource_record_pre_action 11:21:37 INFO - Running main action method: run_tests 11:21:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:21:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:21:37 INFO - Python 2.7.3 11:21:37 INFO - Return code: 0 11:21:37 INFO - grabbing minidump binary from tooltool 11:21: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:21:37 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 0xe153b0>, '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 0xe162b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe16740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:21:37 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 11:21:37 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 11:21:37 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 11:21:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:21:37 INFO - Return code: 0 11:21:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:21:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:21:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:21:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:21:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:21:37 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/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:21:37 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/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:21:37 INFO - Using env: {'DISPLAY': ':0', 11:21:37 INFO - 'HOME': '/home/cltbld', 11:21:37 INFO - 'LANG': 'en_US.UTF-8', 11:21:37 INFO - 'LANGUAGE': 'en_US:en', 11:21:37 INFO - 'LOGNAME': 'cltbld', 11:21:37 INFO - 'MAIL': '/var/mail/cltbld', 11:21:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:21:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:21:37 INFO - 'MOZ_NO_REMOTE': '1', 11:21:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:21:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:21:37 INFO - 'NO_EM_RESTART': '1', 11:21:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:21:37 INFO - 'PWD': '/builds/slave/test', 11:21:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:21:37 INFO - 'SHELL': '/bin/bash', 11:21:37 INFO - 'SHLVL': '1', 11:21:37 INFO - 'TERM': 'linux', 11:21:37 INFO - 'TMOUT': '86400', 11:21:37 INFO - 'USER': 'cltbld', 11:21:37 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992', 11:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:21:37 INFO - '_': '/tools/buildbot/bin/python'} 11:21:37 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/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:21:38 INFO - mozversion application_buildid: 20160713100643 11:21:38 INFO - mozversion application_changeset: b8b27cb2e148119595af75ff7ee3c2af1b7e833a 11:21:38 INFO - mozversion application_display_name: Nightly 11:21:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:21:38 INFO - mozversion application_name: Firefox 11:21:38 INFO - mozversion application_remotingname: firefox 11:21:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:21:38 INFO - mozversion application_vendor: Mozilla 11:21:38 INFO - mozversion application_version: 50.0a1 11:21:38 INFO - mozversion platform_buildid: 20160713100643 11:21:38 INFO - mozversion platform_changeset: b8b27cb2e148119595af75ff7ee3c2af1b7e833a 11:21:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:21:38 INFO - mozversion platform_version: 50.0a1 11:21:38 INFO - using testdate: 1468434098 11:21:38 INFO - actual date: 1468434098 11:21:38 INFO - starting webserver on 'localhost:59096' 11:21:38 INFO - SUITE-START | Running 2 tests 11:21:38 INFO - TEST-START | dromaeo_css 11:21:38 INFO - operating with platform_type : linux_ 11:21:38 INFO - Initialising browser for dromaeo_css test... 11:21:38 INFO - TEST-INFO | started process 18809 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpavNlxY/profile http://localhost:59096/getInfo.html) 11:21:42 INFO - PROCESS | 18809 | __metrics Screen width/height:1600/1200 11:21:42 INFO - PROCESS | 18809 | colorDepth:24 11:21:42 INFO - PROCESS | 18809 | Browser inner width/height: 1024/697 11:21:42 INFO - PROCESS | 18809 | __metrics 11:21:43 INFO - PROCESS | 18809 | 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" 11:21:43 INFO - PROCESS | 18809 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:21:43 INFO - PROCESS | 18809 | console.error: PushService: 11:21:43 INFO - PROCESS | 18809 | stateChangeProcessEnqueue: Error transitioning state 11:21:43 INFO - PROCESS | 18809 | UnknownError 11:21:44 INFO - TEST-INFO | 18809: exit 0 11:21:44 INFO - Browser initialized. 11:21:44 INFO - Running cycle 1/1 for dromaeo_css test... 11:21:44 INFO - TEST-INFO | started process 18891 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpavNlxY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:21:56 INFO - PROCESS | 18891 | RSS: Main: 159064064 11:21:56 INFO - PROCESS | 18891 | 11:22:48 INFO - PROCESS | 18891 | 1468434168094 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] 11:24:59 INFO - PROCESS | 18891 | Cycle 1(1): loaded http://localhost:59096/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59096/tests/dromaeo/cssquery-ext.html) 11:25:00 INFO - PROCESS | 18891 | RSS: Main: 261664768 11:25:00 INFO - PROCESS | 18891 | 11:28:03 INFO - PROCESS | 18891 | Cycle 1(1): loaded http://localhost:59096/tests/dromaeo/cssquery-ext.html (next: http://localhost:59096/tests/dromaeo/cssquery-jquery.html) 11:28:04 INFO - PROCESS | 18891 | RSS: Main: 241467392 11:28:04 INFO - PROCESS | 18891 | 11:31:06 INFO - PROCESS | 18891 | Cycle 1(1): loaded http://localhost:59096/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59096/tests/dromaeo/cssquery-mootools.html) 11:31:07 INFO - PROCESS | 18891 | RSS: Main: 244879360 11:31:07 INFO - PROCESS | 18891 | 11:34:09 INFO - PROCESS | 18891 | Cycle 1(1): loaded http://localhost:59096/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59096/tests/dromaeo/cssquery-prototype.html) 11:34:10 INFO - PROCESS | 18891 | RSS: Main: 235589632 11:34:10 INFO - PROCESS | 18891 | 11:37:13 INFO - PROCESS | 18891 | Cycle 1(1): loaded http://localhost:59096/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59096/tests/dromaeo/cssquery-yui.html) 11:37:14 INFO - PROCESS | 18891 | RSS: Main: 222982144 11:37:14 INFO - PROCESS | 18891 | 11:40:16 INFO - PROCESS | 18891 | Cycle 1(1): loaded http://localhost:59096/tests/dromaeo/cssquery-yui.html (next: http://localhost:59096/tests/dromaeo/cssquery-dojo.html) 11:40:17 INFO - PROCESS | 18891 | RSS: Main: 279003136 11:40:17 INFO - PROCESS | 18891 | 11:40:17 INFO - PROCESS | 18891 | __start_tp_report 11:40:17 INFO - PROCESS | 18891 | _x_x_mozilla_page_load 11:40:17 INFO - PROCESS | 18891 | _x_x_mozilla_page_load_details 11:40:17 INFO - PROCESS | 18891 | |i|pagename|runs| 11:40:17 INFO - PROCESS | 18891 | |0;dojo.html;2558.104663367821;2566.1531694540804;2598.3114474664217;2606.7653911148004;2612.5689261271878;4448.555144485558;4521.796519156637;4565.931511027333;4589.83935562255;4608.493065762767;4516.548345165482;4521.118381915519;4532.048269863324;4560.2475591527445;4588.54114588541;4151.667866570675;4154.605312495323;4199.097194103268;4225.894352641178;4250.27745283301;4212.599803018708;4245.9575404246025;4286.078493123978;4295.656347492193;4301.956980430203;4113.650339721127;4154.626083652486;4155.231282212801;4220.78896055199;4278.871633850974;4121.91756164875;4123.793810309502;4146.419501269825;4185.790710464465;4221.87334379966;4123;4124.278251306009;4169.854055108087;4181.60274773896;4212.178625168093;4518.954810451886;4547.47704014036;4591.058832939239;4594.770261486912;4611.746353950532;4583.358329833793;4820.301056346845;4853.781579828881;4882.951170488285;4891.8287859925085;4927.408710954673;4959.933614272949;4967.05625931072;4979.128652485837;4984.401871775374;4615.053913947632;4619.722816631013;4622.14490319292;4629.675922685447;4638.9330453995435;4822.300766388854;4910.140725373046;4947.529984651453;4948.134076536592;5024.070546948826;4759.524047595282;4774.832880849153;4830.106430310404;4833.275008748716;4834.6374021948495;4645.419322584677;4795.112904112751;4850.490698476679;4850.738807909898;4856.902861942742;4636.884077898012;4691.741954192552;4728.101660684459;4748.6675932685075;4749.453812811502;4706.811727530929;4735.408073990751;4738.431388233434;4742.5020372861045;4782.665213435096;3938.7045971552247;3968.345223038224;4044.838206471767;4048.6558642515715;4082.9591704083173;3632.2372301816386;3663.853445862189;3667.1932174921476;3698.7780733156096;3704.6480584344445;4226.1336426032585;4239.427677263591;4252.659787217077;4253.680973926967;4296.2266791977745;4764.809407623657;4825.879353016133;4990.525900039491;4994.225894986283;5030.2957585938;4135.60711732385;4169.187008533307;4177.895552611209;4186.497620285585;4188.602082802129;4344.869653910388;4369.890752731207;4387.714798538085;4418.712783669115;4428.645708343325;4461.353103799996;4602.07958408313;4605.723656580616;4611.446626404853;4662.790174442091;4869.415670119467;4912.238603016538;4912.852614421573;4941.6293777966075;4955;4625.976870115628;4631.258998560214;4636.072785442858;4669.626429885533;4677.625789936865;4635.281531362644;4774.40319960005;4816.494268101939;4826.0830442215865;4827.131116399082;4891.339669144762;4907.828226012072;4916.3362946001835;4917.303530282047;4947.703137811743;4828.903421931612;4829.710217386968;4831.855044348676;4872.123017856819;4880.194767863263;4539.319102134574;4745.430548334222;4797.136515427256;4799.472058073539;4805.303231031551;4726.125666751662;4738.620910327235;4741.928871066868;4743.715377077248;4794.232922732346;4846.757662116809;4847.321375007431;4851.636127290538;4877.853664390074;4891.046245981999;4512.9097418052115;4529.637628989607;4595.241785105421;4627.421572303462;4677.415323084614 11:40:17 INFO - PROCESS | 18891 | |1;ext.html;579.5711173731439;586.3901542395909;586.8298193523879;587.8530367408146;591.5829340315079;38647.613523864755;39621.2075758485;40426.98932526685;43982.56017439825;44025;40358.59641403585;40779;40828;40840.18319633605;40868.18263634726;7549.282818132268;7776.105747839;7922.088959769614;7925.643346049413;9930.304878658497;9815.509224538744;10211.693649190536;10233;10238.385696858213;10245.231607629421;10215.693529194099;10257.589696412135;10260.486975651187;10262.58949642017;10264.281500294945;7572.999999999973;10186.337888037291;10245.64140255095;10565.154787616972;10573.418461984587;10003.649872254437;10053.195744340435;10062.547185376676;10081.899181008243;10094;37123.81438092792;37365.62634373648;37385.62614373849;37400;37414.43878341827;2422.8909699063584;2762.6684797824187;3030.575719399286;3429.3655673700446;3723.441483777428;26779.33051673704;27424.17727468179;27552.173434796787;29679.70320296791;30606.846965765253;2954.9261268468244;2959.7632189424794;2983.5673827295136;3015.050259168355;3061.127578640077;259238;267805;268216;268520;268701;48600.75699621479;50004.74997625061;50765.746171268904;50845.74577127091;50906;57291.42708572944;58884.705576471846;59268.70365648231;63926.6803665988;64146.679266603365;14879.404823807143;15056.849431505763;15089.924550377398;15100.471483498024;15105.848941510663;10367.740806479898;10391.53238104287;10665.733356666145;10669.09312708413;10865.130789536817;18764.437066888015;18823.34118305853;19041.904790475957;19050;19086;7031.542949708252;7202.819929501804;7236.493445458767;7246.673899674527;7300.853982920418;772.4129661457221;876.59676548787;888.9022207557164;922.5848368234322;960.279790157382;26722;27151.456970860472;27234.319142021213;27722.861385692944;27847.582286265722;983.6557204978201;1004.633308842282;1028.2956175020136;1029.1406675425953;1034.8913364096811;14077.788833167511;14601.415943362359;14614.826520369346;14616.342264598119;14653.267336633126;393175.3071111807;397048;397619;397660;407071;48415.273770893466;48955;49046.50953490491;49164;49184.26223606649;1069.5882085397022;1091.47609147608;1154.96535103947;1155.064397837754;1189.7263629365295;25718.742812571636;28358.85820570884;28648.57027144553;28778.136655900358;29215.269618259714;41047.1790564193;41509.962250943965;41552.58447415487;41571.58428415677;41630;299818;302757;302769;303364;304860;34070;36255.81872090728;37314.44028339631;37389;40029.79985100056;40682.37270509147;40933.38599921063;41049.384259235536;41167.588324116376;41680;34703.44744840814;34906.65093349034;35320.646793531734;35495.29009419746;35669.82165089262;34914.30171396609;35518.822405887804;35661.465078023335;36024;36216.637833621324;34911.825440872635;35305;35521;35761;35870.282594347445;31252;31930;32035.359292813548;32194.19514512156;33793;25305.114320998677;25435.61846572266;25605.231843044738;25729.023793905064;25900.61149082803 11:40:17 INFO - PROCESS | 18891 | |2;jquery.html;332.81351980190635;363.8435472746719;366.92111196092833;376;393.8365578285012;12980.675483112915;16233.431829885956;17324.22041008153;18640.161192746324;18681.53296167593;16652.66694666109;17211.483655490356;17372.391966281182;17637.82362176378;17760.822391776077;4989.850304490871;5253.185756207775;5545.833624991256;5559.305086864142;5632.887342253152;3405.404054290489;5591.7483713233005;5798.275215598051;6115.327313995457;6138.84652883677;6017.06735456005;6135.3557876423;6181.412765787243;6181.536384771138;6188.16459777931;5424.783008679648;5521.668699878021;5527.143292789624;5532.3914369419335;5538.280023596927;3353.7149342305934;4882.951170488285;5219.921701174486;5231.555317797992;5338.412774594792;12589.118761686686;12730.872691273062;12792.808107878389;14847.703045939128;20845.8957705212;1660.8837381383307;1745.729411941151;1990.5222746540871;2414.8309618326725;2442.755724427561;8849.424787388863;9404.764880877965;9545.761355966088;9575.904240957641;9786.951065244628;1566.8511491408299;1596.5210436868892;1624.1392062207049;1670.1649175412294;1692.3146125819062;59459.40540594625;60240;68534.3146568533;68628.6568567154;68751.65624171897;8352.206540378655;8817.823643527221;10061.748456288651;10065;10146.949265253626;12694.555690550786;13438.731225375634;13471.595852124452;13887.166769993833;14048;7121.82195445118;7239.167495738057;7252.891206631904;7389.187189409161;7436.776896693108;6923;7009.299070093052;7049.7885063448175;7126.5011449198055;7157.534760240567;4407.823687052482;4451.354553589711;4457.2199865023495;4461.977690111528;4554.908901821945;1817.2367605605677;1866.5986812835306;1875.8686891917434;1882.877612955181;1899.5441094137584;791.647716766045;800.7597720683772;876.8947726272888;891.7280229529925;914.9503078777999;16470.42353517645;16846.915765421094;16953.660926781395;17019.063951482538;17548.38580649671;827.7682248970298;847.8855354527183;881.1364862434722;890.5992303463468;904.615538396179;9120.726378208665;9267.76830579241;9574.521273936276;9602;9762.853557196648;335162;337785;338583;352990;355873;49357.25964110541;50070.499295007314;74374.25625743781;80619.59690201511;80793.19206807975;849.0787495567379;889.0531583863165;892.9285657147412;915.0894859614726;918.4122161816442;12055.276683398673;15793;15882.047077175406;16026.198690065683;16196.595085122966;38262.6173738259;38409.231815362975;38636.80681596574;39153.41269880897;40917.79541102275;50287;52099;57622;59758.701206493686;59813.401865980784;7007.404370628554;7092;7130.215676275502;7202.351788339075;7212.386947109345;9397.781536757484;10893.782124357627;11080.778384432428;11170.832437513607;11289.830652540055;9802.36284641496;9965.601375944882;9985.552094946337;9997.950010250193;10037.899621003697;9457.905420945977;9514;9699.369540980097;9750.75623109428;9866.802663946824;9895.505224738878;9898.010198980044;9917.355371900803;9977.256404117683;10006.599736010477;7147;7177.2463891292755;7272.381847542806;7382;7627.932089507362;8659.696910608098;10105.595776169164;10143.49282535885;10151.441670708182;10276.280660354003 11:40:17 INFO - PROCESS | 18891 | |3;mootools.html;1340.7117469744003;1387.5074348606245;1448.6305991972044;1692.5599344170512;1725.2667616263086;7541.811454713635;8015.799605009877;8085.151059138794;8085.797855053619;8090.393220508463;6550.475961923046;6780.491463140272;6780.627065511395;6954.165500139978;7921.524708517493;1915.80841915808;2134.157007981846;2203.2398822406244;2210.557888422314;2234.0505285253785;2210.8784016879067;2220.888955552224;2225.721784776903;2237.7762223777654;2240.630296001158;1831.716084006981;2209.6464565669494;2218.301235110935;2218.3455880515207;2221.7222847144108;1835.8714889957707;1864.5618279704258;1878.8966606836618;2080.739907511561;2135.4661334666334;2102.9158833646634;2106.494441334084;2107.104480595741;2108.789121087879;2108.9683654745036;7674.117476490219;7726.690932362699;7731.072271327421;7733.690652373898;7737.651805668758;4578.816847326103;4696.694714843524;4732.550407711262;4744.430668319824;4746.596539294164;2638.617400476939;2693.9057133000447;2762.9171124866284;2768.1280396675184;2768.9307767305777;776.4021703288437;802.1537278171527;813.8535063688533;819.5983967855767;842.9157084291583;10931.344119352785;10947.178961577914;10953.288036277616;10967.122630189566;10974.451277436096;4024.9195016099516;4064.085580744309;4212.073343864345;4214.4099826024385;4317.546657600969;9257.953710231406;9279.211267042241;9292.303077269233;9294.256459483226;9298.72103836886;4734.029523947583;4744.976275118603;4748.857534273978;4752.928706069411;4753.35829662998;4165.83336666536;4274.273373526508;4281.422008028876;4318.524962254212;4324.2648749712625;3408.16499957512;3468.791872487659;3506.1234691327168;3527.9118022049656;3530.3115892400733;1054.783769327286;1060.0194819791661;1064.973375665599;1074.2533938912381;1074.6238816414193;821.3511326052471;825.9793505162419;832.9416940814085;850.5364576305928;855.9700410485603;5213.556847667991;5266.47335266478;5270.183121616155;5379.273798037215;5564.8330550083565;416.6937301083701;419.22443479562565;420.81694462908735;430.78245486029357;431.04523480490866;3588.1567831559564;3683.2633473305445;3696.852125914987;3728.142527218755;3744.1762812181278;9544.570494327772;9839.704808855746;9948.602055917681;9958.15355694774;9958.80082398348;9801.215902727765;9821.80356392868;9824.754381140387;9824.754381140528;9877.60489580423;7395.075615548057;7496.512726672749;7509.887351689729;7511.361534269539;7511.737089201852;5230;5256.684598924076;5383.81156659515;5384.057789886676;5433.619646624857;8866.778330541789;8877.289816814768;9067.863982040406;9108;9141.451512909247;9168.129027742354;9265.86101208469;9283.953580232326;9287.117723816227;9365.859512107187;5621.184928185472;6220.657863817533;6361.968190159019;6362.522810789302;6498.935010649833;7489.8876516851205;7547.207543207884;7557.05536807899;7558.168601453725;7581.658825352762;6726.495512836655;6893.276205998298;6979.546329488567;6980.092587963532;7028.789136325714;6819.69311380999;6861.176658800976;6866.622335771581;6895.310468953065;6900.378965893094;6200.3179650239335;6420.7431702731365;6448.838779030561;6493.642849643126;6617.933820661924;4452.220861349186;4453.4433195850515;4521.276595744665;4572.199865023674;4614.907701845877;4688.249880019317;4708.529147085264;4714.29285607148;4736.976315118402;4794.95205047959 11:40:17 INFO - PROCESS | 18891 | |4;prototype.html;283.95740638904164;296.0452540556706;296.05263157894734;296.7848309975267;300.96689364169936;4578.221702310607;4722.834700785474;4749.928751068728;4758.643101767364;4789.802549362655;4559.563737422718;4714.528547145293;4740.525947405266;4754.714717116975;4776.761161941898;3782.73520853539;3801.1067399161175;3804.7146464015173;3811.2758575870566;3817.541894972607;3621.004223838439;3652.4703917931884;3798.8603418974335;3805.6765174960024;3824.8852534424013;3737.588865224823;3779.7732136071786;3786.2238241160635;3787.2993496203153;3802.771833689974;3734.3651579231596;3746.30693321735;3757.4739536464917;3762.7930463824478;3795.4876091727815;3356.328734253159;3362.7477939154414;3367.4107031269673;3371.7639765216445;3372.4098282800655;4595.701279416827;4644.1408339456975;4654.534546545352;4656.115338085787;4667.206574882278;4284.850030248957;4297.548757380492;4302.849400271007;4320.179165958458;4339.804708788112;4941.234108713155;4968.652194346397;4971.328870602459;4974.1543937530705;5015.448300686921;3548.982255088734;3577.5574293415048;3618.113562177257;3626.873059442933;3636.9272614547563;5112.744362781883;5153.2785410042625;5157.9226311605735;5180.6373553851245;5187.974060129713;3705.2589482103685;3718.1448266898765;3721.1813401051727;3744.5319335083113;3748.456473811282;3723.646253605903;3793.4499497572697;3806.809659517013;3815.465834783133;3823.063349479395;4226.725262857904;4260.275753121977;4267.3598960155605;4274.529801721808;4277.572242775697;4926.088673595396;4949.57928576075;4954.702717836869;4963.429205641398;4972.4336833897205;1222.4926655437894;1260.4769020856243;1312.3897387714567;1313.6715821044738;1314.9013823963242;896.3994123936978;900.2123142250531;917.2845180759018;917.6696389299847;917.9541022948825;4325.264705000158;4333.696641235147;4340.52254252032;4342.5657434256955;4370.2570563004365;5067.695938243717;5073.619478539124;5077.055307921397;5087.364079490064;5093.134167191586;4337.67467439937;4340.435743353344;4359.738415695068;4386.692931494828;4390.956090439118;2298.5977453945493;2430.708315002211;2439.475512764764;2439.621124742245;2443.97556024441;4178;4243.639290660267;4251.681123915719;4273.743575385487;4329.826806927751;4578.397560853675;4590.393362323227;4595.287730401793;4596.540345965377;4602.332661764025;4090.3864420337186;4270.210011147948;4329.242382583098;4335.3063509838275;4351.586599273064;4481.395011673382;4503.91905942578;4538.88652783683;4549.88430379875;4555.635549155993;4412.20580295537;4424.69027168108;4434;4471.396361491157;4477.9328310076025;3987.9232607195313;4266.637335826489;4293.334533147367;4295.5489673583825;4311.202427550913;3380.1211684961595;3405.7786243894066;3409.8124603147066;3416.555847739877;3431.3995050866497;4513.54864513546;4545.227361329018;4558.22510173278;4561.741937740707;4923.384576927884;4798.520147985174;4944.950550494449;4973.602111831117;4978.576820970258;4979.626528010342;4972.5027497249985;4973.875653108701;4995.475475075064;5007.39911210657;5013.523715247045;4807.519248075165;4870.1233777920315;4945.703257804544;4949.7030178189425;4951.207806750903;4979.701217926936;4980.900381992267;4994.650374473752;5007;5009.968968773839;4885.169521181479;4913.7543122843;4921.704697718148;4923.522943116979;4926.679765815211 11:40:17 INFO - PROCESS | 18891 | |5;yui.html;638.3584497579932;638.6327861479649;693.4279219643795;694.808927544925;753.984920301594;13082.607521774338;13777.586672399844;14000.22998735069;14333.641658958519;14342.067765595242;12945.546905858297;12979.61061168164;13046.804297935514;13048.086633935629;13070.15044022138;631.4443289904877;772.3628006894321;772.4168252969;792.2038351456778;794.7893808140848;785.1598789295456;800.6237068577759;800.6957356204634;800.8998875140608;800.9159038300982;834.3116928533954;834.6369329341726;836.6569706420369;837.2757564706516;838.1283465196188;651.25106127953;802.3982013489882;819.3076850061689;826.4793180296405;826.5247482697444;607.8419610901153;607.9756809727605;608.0757248981523;608.1060840564363;608.2123649873424;12993.61019169417;12995.740085198338;13023;13024.739505209844;13027.60917172477;12474.937625311815;12772.36138193096;12941.158824676366;12980.870191298061;13140.802887956688;11330.943345283304;11434.371109588968;11469.988810741697;11650.825237621442;11692.883071169264;4014.8594799181888;4096.344584866407;4133.21468920904;4137.875863724063;4221.725587836811;62192.37807621911;62237.68881155611;62457.375426246064;62485;62544.37455625431;9264.629414823467;9403.670871519464;9636.518174091267;9978.151857092083;9981.550830212658;18670.906645466686;18826.152823122717;18839.81160188408;18851.622967540574;18862.62274754497;6523.380278873499;6806.285340039323;6822.079019332426;6841.965790171117;6964.512484126164;3530.805805680661;3548.503209550665;3578.8568457261417;3589.3718599245026;3704.388775851954;2689.7175796541474;2761.4891245119716;2782.151443809617;2782.290515918428;2784.8607569621845;469.91071696377577;473.62346934187406;474.2909350520993;479.82443014614336;484.2639188433632;364.1805936642554;393.4373845401104;423.2064878353088;456.97029693069845;471.02262804680424;6619.371159739817;6850.588964662036;6981.189214951359;7017.61403122823;7089.22018577956;425.6849931051;439.7801099450275;457.4167935881777;466.5754163711007;484.81819317755844;3511.719062474996;3554.87557935471;3620.499897908858;3720.3861362874823;3825.1967086911504;77663;98474.50762746284;98945.55799430957;99461.50269248607;108856.45571772249;67649;70509.64745176242;71888.28111718816;79488.602556988;85356.57321713351;71548;72248;73448.63275683587;74779;76448.61775691085;8243.83512329762;8318.417710760188;8384.496930184208;8453.577321133796;8456.704015359433;43363.349549756145;45765.54234457746;45767.77116114398;48054.75972620115;48821.755891221736;58004;67206.32793672;67298.66350668215;67337.66331168312;67687.98468022885;10430.739231519272;10518.579256829948;10537.15702743794;10543.57825686964;10612.575496980344;10817;11245.550177993116;11869.643910682693;12105.302261076233;12286.75426491483;11430.771384572095;11454.656360309204;11725.941370293434;11965.880341196826;11981.400929953641;8289.171082891662;8298.336133109458;8471.661133554835;11281.71795705114;11595.594154204562;8772.473651580925;9315.39449935752;9336.206422454168;9438.622455101993;9763.560639771085;7581.658825352762;7724.1117271514495;7752;8030.558319292495;8076.111627720828;7730.458867879194;7826.295633393022;8097.635606397608;9382;9716.368436051634 11:40:17 INFO - PROCESS | 18891 | __end_tp_report 11:40:17 INFO - PROCESS | 18891 | __start_cc_report 11:40:17 INFO - PROCESS | 18891 | _x_x_mozilla_cycle_collect,1042 11:40:17 INFO - PROCESS | 18891 | __end_cc_report 11:40:17 INFO - PROCESS | 18891 | __startTimestamp1468435217011__endTimestamp 11:40:17 INFO - PROCESS | 18891 | 11:40:17 INFO - PROCESS | 18891 | ------- Summary: start ------- 11:40:17 INFO - PROCESS | 18891 | Number of tests: 6 11:40:17 INFO - PROCESS | 18891 | 11:40:17 INFO - PROCESS | 18891 | [#0] dojo.html Cycles:165 Average:4505.06 Median:4625.98 stddev:466.17 (10.1%) stddev-sans-first:441.88 11:40:17 INFO - PROCESS | 18891 | Values: 2558.1 2566.2 2598.3 2606.8 2612.6 4448.6 4521.8 4565.9 4589.8 4608.5 4516.5 4521.1 4532.0 4560.2 4588.5 4151.7 4154.6 4199.1 4225.9 4250.3 4212.6 4246.0 4286.1 4295.7 4302.0 4113.7 4154.6 4155.2 4220.8 4278.9 4121.9 4123.8 4146.4 4185.8 4221.9 4123.0 4124.3 4169.9 4181.6 4212.2 4519.0 4547.5 4591.1 4594.8 4611.7 4583.4 4820.3 4853.8 4883.0 4891.8 4927.4 4959.9 4967.1 4979.1 4984.4 4615.1 4619.7 4622.1 4629.7 4638.9 4822.3 4910.1 4947.5 4948.1 5024.1 4759.5 4774.8 4830.1 4833.3 4834.6 4645.4 4795.1 4850.5 4850.7 4856.9 4636.9 4691.7 4728.1 4748.7 4749.5 4706.8 4735.4 4738.4 4742.5 4782.7 3938.7 3968.3 4044.8 4048.7 4083.0 3632.2 3663.9 3667.2 3698.8 3704.6 4226.1 4239.4 4252.7 4253.7 4296.2 4764.8 4825.9 4990.5 4994.2 5030.3 4135.6 4169.2 4177.9 4186.5 4188.6 4344.9 4369.9 4387.7 4418.7 4428.6 4461.4 4602.1 4605.7 4611.4 4662.8 4869.4 4912.2 4912.9 4941.6 4955.0 4626.0 4631.3 4636.1 4669.6 4677.6 4635.3 4774.4 4816.5 4826.1 4827.1 4891.3 4907.8 4916.3 4917.3 4947.7 4828.9 4829.7 4831.9 4872.1 4880.2 4539.3 4745.4 4797.1 4799.5 4805.3 4726.1 4738.6 4741.9 4743.7 4794.2 4846.8 4847.3 4851.6 4877.9 4891.0 4512.9 4529.6 4595.2 4627.4 4677.4 11:40:17 INFO - PROCESS | 18891 | 11:40:17 INFO - PROCESS | 18891 | [#1] ext.html Cycles:180 Average:48310.68 Median:18749.68 stddev:86023.71 (458.8%) stddev-sans-first:86190.38 11:40:17 INFO - PROCESS | 18891 | Values: 579.6 586.4 586.8 587.9 591.6 38647.6 39621.2 40427.0 43982.6 44025.0 40358.6 40779.0 40828.0 40840.2 40868.2 7549.3 7776.1 7922.1 7925.6 9930.3 9815.5 10211.7 10233.0 10238.4 10245.2 10215.7 10257.6 10260.5 10262.6 10264.3 7573.0 10186.3 10245.6 10565.2 10573.4 10003.6 10053.2 10062.5 10081.9 10094.0 37123.8 37365.6 37385.6 37400.0 37414.4 2422.9 2762.7 3030.6 3429.4 3723.4 26779.3 27424.2 27552.2 29679.7 30606.8 2954.9 2959.8 2983.6 3015.1 3061.1 259238.0 267805.0 268216.0 268520.0 268701.0 48600.8 50004.7 50765.7 50845.7 50906.0 57291.4 58884.7 59268.7 63926.7 64146.7 14879.4 15056.8 15089.9 15100.5 15105.8 10367.7 10391.5 10665.7 10669.1 10865.1 18764.4 18823.3 19041.9 19050.0 19086.0 7031.5 7202.8 7236.5 7246.7 7300.9 772.4 876.6 888.9 922.6 960.3 26722.0 27151.5 27234.3 27722.9 27847.6 983.7 1004.6 1028.3 1029.1 1034.9 14077.8 14601.4 14614.8 14616.3 14653.3 393175.3 397048.0 397619.0 397660.0 407071.0 48415.3 48955.0 49046.5 49164.0 49184.3 1069.6 1091.5 1155.0 1155.1 1189.7 25718.7 28358.9 28648.6 28778.1 29215.3 41047.2 41510.0 41552.6 41571.6 41630.0 299818.0 302757.0 302769.0 303364.0 304860.0 34070.0 36255.8 37314.4 37389.0 40029.8 40682.4 40933.4 41049.4 41167.6 41680.0 34703.4 34906.7 35320.6 35495.3 35669.8 34914.3 35518.8 35661.5 36024.0 36216.6 34911.8 35305.0 35521.0 35761.0 35870.3 31252.0 31930.0 32035.4 32194.2 33793.0 25305.1 25435.6 25605.2 25729.0 25900.6 11:40:17 INFO - PROCESS | 18891 | 11:40:17 INFO - PROCESS | 18891 | [#2] jquery.html Cycles:180 Average:22594.22 Median:5473.23 stddev:57020.99 (1041.8%) stddev-sans-first:57156.46 11:40:17 INFO - PROCESS | 18891 | Values: 332.8 363.8 366.9 376.0 393.8 12980.7 16233.4 17324.2 18640.2 18681.5 16652.7 17211.5 17372.4 17637.8 17760.8 4989.9 5253.2 5545.8 5559.3 5632.9 3405.4 5591.7 5798.3 6115.3 6138.8 6017.1 6135.4 6181.4 6181.5 6188.2 5424.8 5521.7 5527.1 5532.4 5538.3 3353.7 4883.0 5219.9 5231.6 5338.4 12589.1 12730.9 12792.8 14847.7 20845.9 1660.9 1745.7 1990.5 2414.8 2442.8 8849.4 9404.8 9545.8 9575.9 9787.0 1566.9 1596.5 1624.1 1670.2 1692.3 59459.4 60240.0 68534.3 68628.7 68751.7 8352.2 8817.8 10061.7 10065.0 10146.9 12694.6 13438.7 13471.6 13887.2 14048.0 7121.8 7239.2 7252.9 7389.2 7436.8 6923.0 7009.3 7049.8 7126.5 7157.5 4407.8 4451.4 4457.2 4462.0 4554.9 1817.2 1866.6 1875.9 1882.9 1899.5 791.6 800.8 876.9 891.7 915.0 16470.4 16846.9 16953.7 17019.1 17548.4 827.8 847.9 881.1 890.6 904.6 9120.7 9267.8 9574.5 9602.0 9762.9 335162.0 337785.0 338583.0 352990.0 355873.0 49357.3 50070.5 74374.3 80619.6 80793.2 849.1 889.1 892.9 915.1 918.4 12055.3 15793.0 15882.0 16026.2 16196.6 38262.6 38409.2 38636.8 39153.4 40917.8 50287.0 52099.0 57622.0 59758.7 59813.4 7007.4 7092.0 7130.2 7202.4 7212.4 9397.8 10893.8 11080.8 11170.8 11289.8 9802.4 9965.6 9985.6 9998.0 10037.9 9457.9 9514.0 9699.4 9750.8 9866.8 9895.5 9898.0 9917.4 9977.3 10006.6 7147.0 7177.2 7272.4 7382.0 7627.9 8659.7 10105.6 10143.5 10151.4 10276.3 11:40:17 INFO - PROCESS | 18891 | 11:40:17 INFO - PROCESS | 18891 | [#3] mootools.html Cycles:180 Average:5147.36 Median:5221.78 stddev:2964.19 (56.8%) stddev-sans-first:2958.70 11:40:17 INFO - PROCESS | 18891 | Values: 1340.7 1387.5 1448.6 1692.6 1725.3 7541.8 8015.8 8085.2 8085.8 8090.4 6550.5 6780.5 6780.6 6954.2 7921.5 1915.8 2134.2 2203.2 2210.6 2234.1 2210.9 2220.9 2225.7 2237.8 2240.6 1831.7 2209.6 2218.3 2218.3 2221.7 1835.9 1864.6 1878.9 2080.7 2135.5 2102.9 2106.5 2107.1 2108.8 2109.0 7674.1 7726.7 7731.1 7733.7 7737.7 4578.8 4696.7 4732.6 4744.4 4746.6 2638.6 2693.9 2762.9 2768.1 2768.9 776.4 802.2 813.9 819.6 842.9 10931.3 10947.2 10953.3 10967.1 10974.5 4024.9 4064.1 4212.1 4214.4 4317.5 9258.0 9279.2 9292.3 9294.3 9298.7 4734.0 4745.0 4748.9 4752.9 4753.4 4165.8 4274.3 4281.4 4318.5 4324.3 3408.2 3468.8 3506.1 3527.9 3530.3 1054.8 1060.0 1065.0 1074.3 1074.6 821.4 826.0 832.9 850.5 856.0 5213.6 5266.5 5270.2 5379.3 5564.8 416.7 419.2 420.8 430.8 431.0 3588.2 3683.3 3696.9 3728.1 3744.2 9544.6 9839.7 9948.6 9958.2 9958.8 9801.2 9821.8 9824.8 9824.8 9877.6 7395.1 7496.5 7509.9 7511.4 7511.7 5230.0 5256.7 5383.8 5384.1 5433.6 8866.8 8877.3 9067.9 9108.0 9141.5 9168.1 9265.9 9284.0 9287.1 9365.9 5621.2 6220.7 6362.0 6362.5 6498.9 7489.9 7547.2 7557.1 7558.2 7581.7 6726.5 6893.3 6979.5 6980.1 7028.8 6819.7 6861.2 6866.6 6895.3 6900.4 6200.3 6420.7 6448.8 6493.6 6617.9 4452.2 4453.4 4521.3 4572.2 4614.9 4688.2 4708.5 4714.3 4737.0 4795.0 11:40:17 INFO - PROCESS | 18891 | 11:40:17 INFO - PROCESS | 18891 | [#4] prototype.html Cycles:180 Average:4029.96 Median:4355.66 stddev:1140.87 (26.2%) stddev-sans-first:1108.89 11:40:17 INFO - PROCESS | 18891 | Values: 284.0 296.0 296.1 296.8 301.0 4578.2 4722.8 4749.9 4758.6 4789.8 4559.6 4714.5 4740.5 4754.7 4776.8 3782.7 3801.1 3804.7 3811.3 3817.5 3621.0 3652.5 3798.9 3805.7 3824.9 3737.6 3779.8 3786.2 3787.3 3802.8 3734.4 3746.3 3757.5 3762.8 3795.5 3356.3 3362.7 3367.4 3371.8 3372.4 4595.7 4644.1 4654.5 4656.1 4667.2 4284.9 4297.5 4302.8 4320.2 4339.8 4941.2 4968.7 4971.3 4974.2 5015.4 3549.0 3577.6 3618.1 3626.9 3636.9 5112.7 5153.3 5157.9 5180.6 5188.0 3705.3 3718.1 3721.2 3744.5 3748.5 3723.6 3793.4 3806.8 3815.5 3823.1 4226.7 4260.3 4267.4 4274.5 4277.6 4926.1 4949.6 4954.7 4963.4 4972.4 1222.5 1260.5 1312.4 1313.7 1314.9 896.4 900.2 917.3 917.7 918.0 4325.3 4333.7 4340.5 4342.6 4370.3 5067.7 5073.6 5077.1 5087.4 5093.1 4337.7 4340.4 4359.7 4386.7 4391.0 2298.6 2430.7 2439.5 2439.6 2444.0 4178.0 4243.6 4251.7 4273.7 4329.8 4578.4 4590.4 4595.3 4596.5 4602.3 4090.4 4270.2 4329.2 4335.3 4351.6 4481.4 4503.9 4538.9 4549.9 4555.6 4412.2 4424.7 4434.0 4471.4 4477.9 3987.9 4266.6 4293.3 4295.5 4311.2 3380.1 3405.8 3409.8 3416.6 3431.4 4513.5 4545.2 4558.2 4561.7 4923.4 4798.5 4945.0 4973.6 4978.6 4979.6 4972.5 4973.9 4995.5 5007.4 5013.5 4807.5 4870.1 4945.7 4949.7 4951.2 4979.7 4980.9 4994.7 5007.0 5010.0 4885.2 4913.8 4921.7 4923.5 4926.7 11:40:17 INFO - PROCESS | 18891 | 11:40:17 INFO - PROCESS | 18891 | [#5] yui.html Cycles:180 Average:17321.87 Median:608.03 stddev:24955.48 (4104.3%) stddev-sans-first:24994.05 11:40:17 INFO - PROCESS | 18891 | Values: 638.4 638.6 693.4 694.8 754.0 13082.6 13777.6 14000.2 14333.6 14342.1 12945.5 12979.6 13046.8 13048.1 13070.2 631.4 772.4 772.4 792.2 794.8 785.2 800.6 800.7 800.9 800.9 834.3 834.6 836.7 837.3 838.1 651.3 802.4 819.3 826.5 826.5 607.8 608.0 608.1 608.1 608.2 12993.6 12995.7 13023.0 13024.7 13027.6 12474.9 12772.4 12941.2 12980.9 13140.8 11330.9 11434.4 11470.0 11650.8 11692.9 4014.9 4096.3 4133.2 4137.9 4221.7 62192.4 62237.7 62457.4 62485.0 62544.4 9264.6 9403.7 9636.5 9978.2 9981.6 18670.9 18826.2 18839.8 18851.6 18862.6 6523.4 6806.3 6822.1 6842.0 6964.5 3530.8 3548.5 3578.9 3589.4 3704.4 2689.7 2761.5 2782.2 2782.3 2784.9 469.9 473.6 474.3 479.8 484.3 364.2 393.4 423.2 457.0 471.0 6619.4 6850.6 6981.2 7017.6 7089.2 425.7 439.8 457.4 466.6 484.8 3511.7 3554.9 3620.5 3720.4 3825.2 77663.0 98474.5 98945.6 99461.5 108856.5 67649.0 70509.6 71888.3 79488.6 85356.6 71548.0 72248.0 73448.6 74779.0 76448.6 8243.8 8318.4 8384.5 8453.6 8456.7 43363.3 45765.5 45767.8 48054.8 48821.8 58004.0 67206.3 67298.7 67337.7 67688.0 10430.7 10518.6 10537.2 10543.6 10612.6 10817.0 11245.6 11869.6 12105.3 12286.8 11430.8 11454.7 11725.9 11965.9 11981.4 8289.2 8298.3 8471.7 11281.7 11595.6 8772.5 9315.4 9336.2 9438.6 9763.6 7581.7 7724.1 7752.0 8030.6 8076.1 7730.5 7826.3 8097.6 9382.0 9716.4 11:40:17 INFO - PROCESS | 18891 | -------- Summary: end -------- 11:40:17 INFO - PROCESS | 18891 | 11:40:17 INFO - TEST-INFO | 18891: exit 0 11:40:17 INFO - TEST-OK | dromaeo_css | took 1119762ms 11:40:17 INFO - TEST-START | kraken 11:40:17 INFO - operating with platform_type : linux_ 11:40:17 INFO - Initialising browser for kraken test... 11:40:17 INFO - TEST-INFO | started process 19085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmZGxUC/profile http://localhost:59096/getInfo.html) 11:40:22 INFO - PROCESS | 19085 | __metrics Screen width/height:1600/1200 11:40:22 INFO - PROCESS | 19085 | colorDepth:24 11:40:22 INFO - PROCESS | 19085 | Browser inner width/height: 1024/697 11:40:22 INFO - PROCESS | 19085 | __metrics 11:40:22 INFO - PROCESS | 19085 | 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" 11:40:22 INFO - PROCESS | 19085 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:40:23 INFO - TEST-INFO | 19085: exit 0 11:40:23 INFO - Browser initialized. 11:40:23 INFO - Running cycle 1/1 for kraken test... 11:40:23 INFO - TEST-INFO | started process 19168 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmZGxUC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:40:35 INFO - PROCESS | 19168 | RSS: Main: 159285248 11:40:35 INFO - PROCESS | 19168 | 11:40:38 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59096/tests/kraken/driver.html?testName=audio-beat-detection) 11:40:39 INFO - PROCESS | 19168 | RSS: Main: 217018368 11:40:39 INFO - PROCESS | 19168 | 11:40:42 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59096/tests/kraken/driver.html?testName=audio-dft) 11:40:42 INFO - PROCESS | 19168 | RSS: Main: 203988992 11:40:42 INFO - PROCESS | 19168 | 11:40:46 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59096/tests/kraken/driver.html?testName=audio-fft) 11:40:46 INFO - PROCESS | 19168 | RSS: Main: 271028224 11:40:46 INFO - PROCESS | 19168 | 11:40:48 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59096/tests/kraken/driver.html?testName=audio-oscillator) 11:40:49 INFO - PROCESS | 19168 | RSS: Main: 181424128 11:40:49 INFO - PROCESS | 19168 | 11:40:51 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59096/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:40:52 INFO - PROCESS | 19168 | RSS: Main: 186109952 11:40:52 INFO - PROCESS | 19168 | 11:40:55 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59096/tests/kraken/driver.html?testName=imaging-darkroom) 11:40:56 INFO - PROCESS | 19168 | RSS: Main: 222420992 11:40:56 INFO - PROCESS | 19168 | 11:40:59 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59096/tests/kraken/driver.html?testName=imaging-desaturate) 11:41:00 INFO - PROCESS | 19168 | RSS: Main: 217485312 11:41:00 INFO - PROCESS | 19168 | 11:41:03 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59096/tests/kraken/driver.html?testName=json-parse-financial) 11:41:03 INFO - PROCESS | 19168 | RSS: Main: 220553216 11:41:03 INFO - PROCESS | 19168 | 11:41:05 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59096/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:41:06 INFO - PROCESS | 19168 | RSS: Main: 206602240 11:41:06 INFO - PROCESS | 19168 | 11:41:08 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59096/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:41:08 INFO - PROCESS | 19168 | RSS: Main: 212140032 11:41:08 INFO - PROCESS | 19168 | 11:41:10 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59096/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:41:11 INFO - PROCESS | 19168 | RSS: Main: 210145280 11:41:11 INFO - PROCESS | 19168 | 11:41:14 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59096/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:41:14 INFO - PROCESS | 19168 | RSS: Main: 225329152 11:41:14 INFO - PROCESS | 19168 | 11:41:17 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59096/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:41:18 INFO - PROCESS | 19168 | RSS: Main: 226942976 11:41:18 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | Cycle 1(1): loaded http://localhost:59096/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59096/tests/kraken/driver.html?testName=ai-astar) 11:41:20 INFO - PROCESS | 19168 | RSS: Main: 269647872 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | __start_tp_report 11:41:20 INFO - PROCESS | 19168 | _x_x_mozilla_page_load 11:41:20 INFO - PROCESS | 19168 | _x_x_mozilla_page_load_details 11:41:20 INFO - PROCESS | 19168 | |i|pagename|runs| 11:41:20 INFO - PROCESS | 19168 | |0;ai-astar;108;116;113;116;114;113;115;114;114;113 11:41:20 INFO - PROCESS | 19168 | |1;audio-beat-detection;147;124;126;178;129;128;148;129;194;122 11:41:20 INFO - PROCESS | 19168 | |2;audio-dft;161;162;161;161;162;163;163;162;166;162 11:41:20 INFO - PROCESS | 19168 | |3;audio-fft;75;74;75;132;75;75;75;76;100;77 11:41:20 INFO - PROCESS | 19168 | |4;audio-oscillator;85;96;122;85;86;85;118;93;85;144 11:41:20 INFO - PROCESS | 19168 | |5;imaging-gaussian-blur;117;116;117;118;117;117;117;116;117;117 11:41:20 INFO - PROCESS | 19168 | |6;imaging-darkroom;147;146;146;171;147;146;147;146;147;146 11:41:20 INFO - PROCESS | 19168 | |7;imaging-desaturate;94;94;95;95;95;95;95;94;95;95 11:41:20 INFO - PROCESS | 19168 | |8;json-parse-financial;64;64;65;64;65;64;64;64;64;64 11:41:20 INFO - PROCESS | 19168 | |9;json-stringify-tinderbox;66;66;66;65;66;66;67;66;66;67 11:41:20 INFO - PROCESS | 19168 | |10;stanford-crypto-aes;78;79;77;79;80;81;82;79;81;81 11:41:20 INFO - PROCESS | 19168 | |11;stanford-crypto-ccm;132;129;128;128;128;129;128;129;200;133 11:41:20 INFO - PROCESS | 19168 | |12;stanford-crypto-pbkdf2;167;170;170;167;168;175;168;172;167;165 11:41:20 INFO - PROCESS | 19168 | |13;stanford-crypto-sha256-iterative;67;66;67;65;66;65;66;73;66;66 11:41:20 INFO - PROCESS | 19168 | __end_tp_report 11:41:20 INFO - PROCESS | 19168 | __start_cc_report 11:41:20 INFO - PROCESS | 19168 | _x_x_mozilla_cycle_collect,1929 11:41:20 INFO - PROCESS | 19168 | __end_cc_report 11:41:20 INFO - PROCESS | 19168 | __startTimestamp1468435280422__endTimestamp 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | ------- Summary: start ------- 11:41:20 INFO - PROCESS | 19168 | Number of tests: 14 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:2.27 (2.0%) stddev-sans-first:1.20 11:41:20 INFO - PROCESS | 19168 | Values: 108.0 116.0 113.0 116.0 114.0 113.0 115.0 114.0 114.0 113.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#1] audio-beat-detection Cycles:10 Average:142.50 Median:138.00 stddev:24.88 (18.0%) stddev-sans-first:26.34 11:41:20 INFO - PROCESS | 19168 | Values: 147.0 124.0 126.0 178.0 129.0 128.0 148.0 129.0 194.0 122.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.00 stddev:1.49 (0.9%) stddev-sans-first:1.51 11:41:20 INFO - PROCESS | 19168 | Values: 161.0 162.0 161.0 161.0 162.0 163.0 163.0 162.0 166.0 162.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#3] audio-fft Cycles:10 Average:83.40 Median:75.00 stddev:18.78 (25.0%) stddev-sans-first:19.67 11:41:20 INFO - PROCESS | 19168 | Values: 75.0 74.0 75.0 132.0 75.0 75.0 75.0 76.0 100.0 77.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#4] audio-oscillator Cycles:10 Average:99.90 Median:85.00 stddev:20.83 (24.5%) stddev-sans-first:21.38 11:41:20 INFO - PROCESS | 19168 | Values: 85.0 96.0 122.0 85.0 86.0 85.0 118.0 93.0 85.0 144.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 11:41:20 INFO - PROCESS | 19168 | Values: 117.0 116.0 117.0 118.0 117.0 117.0 117.0 116.0 117.0 117.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:147.00 stddev:7.78 (5.3%) stddev-sans-first:8.22 11:41:20 INFO - PROCESS | 19168 | Values: 147.0 146.0 146.0 171.0 147.0 146.0 147.0 146.0 147.0 146.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 11:41:20 INFO - PROCESS | 19168 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 11:41:20 INFO - PROCESS | 19168 | Values: 64.0 64.0 65.0 64.0 65.0 64.0 64.0 64.0 64.0 64.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 11:41:20 INFO - PROCESS | 19168 | Values: 66.0 66.0 66.0 65.0 66.0 66.0 67.0 66.0 66.0 67.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:80.50 stddev:1.57 (1.9%) stddev-sans-first:1.54 11:41:20 INFO - PROCESS | 19168 | Values: 78.0 79.0 77.0 79.0 80.0 81.0 82.0 79.0 81.0 81.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:129.00 stddev:22.42 (17.4%) stddev-sans-first:23.72 11:41:20 INFO - PROCESS | 19168 | Values: 132.0 129.0 128.0 128.0 128.0 129.0 128.0 129.0 200.0 133.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.00 stddev:2.92 (1.7%) stddev-sans-first:3.02 11:41:20 INFO - PROCESS | 19168 | Values: 167.0 170.0 170.0 167.0 168.0 175.0 168.0 172.0 167.0 165.0 11:41:20 INFO - PROCESS | 19168 | 11:41:20 INFO - PROCESS | 19168 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:66.00 stddev:2.31 (3.5%) stddev-sans-first:2.45 11:41:20 INFO - PROCESS | 19168 | Values: 67.0 66.0 67.0 65.0 66.0 65.0 66.0 73.0 66.0 66.0 11:41:20 INFO - PROCESS | 19168 | -------- Summary: end -------- 11:41:20 INFO - PROCESS | 19168 | 11:41:21 INFO - TEST-INFO | 19168: exit 0 11:41:21 INFO - TEST-OK | kraken | took 63747ms 11:41:21 INFO - SUITE-END | took 1183s 11:41:21 INFO - Completed test suite (00:19:43) 11:41:21 INFO - javascript benchmark 11:41:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2558.104663367821, 2566.1531694540804, 2598.3114474664217, 2606.7653911148004, 2612.5689261271878, 4448.555144485558, 4521.796519156637, 4565.931511027333, 4589.83935562255, 4608.493065762767, 4516.548345165482, 4521.118381915519, 4532.048269863324, 4560.2475591527445, 4588.54114588541, 4151.667866570675, 4154.605312495323, 4199.097194103268, 4225.894352641178, 4250.27745283301, 4212.599803018708, 4245.9575404246025, 4286.078493123978, 4295.656347492193, 4301.956980430203, 4113.650339721127, 4154.626083652486, 4155.231282212801, 4220.78896055199, 4278.871633850974, 4121.91756164875, 4123.793810309502, 4146.419501269825, 4185.790710464465, 4221.87334379966, 4123.0, 4124.278251306009, 4169.854055108087, 4181.60274773896, 4212.178625168093, 4518.954810451886, 4547.47704014036, 4591.058832939239, 4594.770261486912, 4611.746353950532, 4583.358329833793, 4820.301056346845, 4853.781579828881, 4882.951170488285, 4891.8287859925085, 4927.408710954673, 4959.933614272949, 4967.05625931072, 4979.128652485837, 4984.401871775374, 4615.053913947632, 4619.722816631013, 4622.14490319292, 4629.675922685447, 4638.9330453995435, 4822.300766388854, 4910.140725373046, 4947.529984651453, 4948.134076536592, 5024.070546948826, 4759.524047595282, 4774.832880849153, 4830.106430310404, 4833.275008748716, 4834.6374021948495, 4645.419322584677, 4795.112904112751, 4850.490698476679, 4850.738807909898, 4856.902861942742, 4636.884077898012, 4691.741954192552, 4728.101660684459, 4748.6675932685075, 4749.453812811502, 4706.811727530929, 4735.408073990751, 4738.431388233434, 4742.5020372861045, 4782.665213435096, 3938.7045971552247, 3968.345223038224, 4044.838206471767, 4048.6558642515715, 4082.9591704083173, 3632.2372301816386, 3663.853445862189, 3667.1932174921476, 3698.7780733156096, 3704.6480584344445, 4226.1336426032585, 4239.427677263591, 4252.659787217077, 4253.680973926967, 4296.2266791977745, 4764.809407623657, 4825.879353016133, 4990.525900039491, 4994.225894986283, 5030.2957585938, 4135.60711732385, 4169.187008533307, 4177.895552611209, 4186.497620285585, 4188.602082802129, 4344.869653910388, 4369.890752731207, 4387.714798538085, 4418.712783669115, 4428.645708343325, 4461.353103799996, 4602.07958408313, 4605.723656580616, 4611.446626404853, 4662.790174442091, 4869.415670119467, 4912.238603016538, 4912.852614421573, 4941.6293777966075, 4955.0, 4625.976870115628, 4631.258998560214, 4636.072785442858, 4669.626429885533, 4677.625789936865, 4635.281531362644, 4774.40319960005, 4816.494268101939, 4826.0830442215865, 4827.131116399082, 4891.339669144762, 4907.828226012072, 4916.3362946001835, 4917.303530282047, 4947.703137811743, 4828.903421931612, 4829.710217386968, 4831.855044348676, 4872.123017856819, 4880.194767863263, 4539.319102134574, 4745.430548334222, 4797.136515427256, 4799.472058073539, 4805.303231031551, 4726.125666751662, 4738.620910327235, 4741.928871066868, 4743.715377077248, 4794.232922732346, 4846.757662116809, 4847.321375007431, 4851.636127290538, 4877.853664390074, 4891.046245981999, 4512.9097418052115, 4529.637628989607, 4595.241785105421, 4627.421572303462, 4677.415323084614], "value": 4476.042895147344, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.5711173731439, 586.3901542395909, 586.8298193523879, 587.8530367408146, 591.5829340315079, 38647.613523864755, 39621.2075758485, 40426.98932526685, 43982.56017439825, 44025.0, 40358.59641403585, 40779.0, 40828.0, 40840.18319633605, 40868.18263634726, 7549.282818132268, 7776.105747839, 7922.088959769614, 7925.643346049413, 9930.304878658497, 9815.509224538744, 10211.693649190536, 10233.0, 10238.385696858213, 10245.231607629421, 10215.693529194099, 10257.589696412135, 10260.486975651187, 10262.58949642017, 10264.281500294945, 7572.999999999973, 10186.337888037291, 10245.64140255095, 10565.154787616972, 10573.418461984587, 10003.649872254437, 10053.195744340435, 10062.547185376676, 10081.899181008243, 10094.0, 37123.81438092792, 37365.62634373648, 37385.62614373849, 37400.0, 37414.43878341827, 2422.8909699063584, 2762.6684797824187, 3030.575719399286, 3429.3655673700446, 3723.441483777428, 26779.33051673704, 27424.17727468179, 27552.173434796787, 29679.70320296791, 30606.846965765253, 2954.9261268468244, 2959.7632189424794, 2983.5673827295136, 3015.050259168355, 3061.127578640077, 259238.0, 267805.0, 268216.0, 268520.0, 268701.0, 48600.75699621479, 50004.74997625061, 50765.746171268904, 50845.74577127091, 50906.0, 57291.42708572944, 58884.705576471846, 59268.70365648231, 63926.6803665988, 64146.679266603365, 14879.404823807143, 15056.849431505763, 15089.924550377398, 15100.471483498024, 15105.848941510663, 10367.740806479898, 10391.53238104287, 10665.733356666145, 10669.09312708413, 10865.130789536817, 18764.437066888015, 18823.34118305853, 19041.904790475957, 19050.0, 19086.0, 7031.542949708252, 7202.819929501804, 7236.493445458767, 7246.673899674527, 7300.853982920418, 772.4129661457221, 876.59676548787, 888.9022207557164, 922.5848368234322, 960.279790157382, 26722.0, 27151.456970860472, 27234.319142021213, 27722.861385692944, 27847.582286265722, 983.6557204978201, 1004.633308842282, 1028.2956175020136, 1029.1406675425953, 1034.8913364096811, 14077.788833167511, 14601.415943362359, 14614.826520369346, 14616.342264598119, 14653.267336633126, 393175.3071111807, 397048.0, 397619.0, 397660.0, 407071.0, 48415.273770893466, 48955.0, 49046.50953490491, 49164.0, 49184.26223606649, 1069.5882085397022, 1091.47609147608, 1154.96535103947, 1155.064397837754, 1189.7263629365295, 25718.742812571636, 28358.85820570884, 28648.57027144553, 28778.136655900358, 29215.269618259714, 41047.1790564193, 41509.962250943965, 41552.58447415487, 41571.58428415677, 41630.0, 299818.0, 302757.0, 302769.0, 303364.0, 304860.0, 34070.0, 36255.81872090728, 37314.44028339631, 37389.0, 40029.79985100056, 40682.37270509147, 40933.38599921063, 41049.384259235536, 41167.588324116376, 41680.0, 34703.44744840814, 34906.65093349034, 35320.646793531734, 35495.29009419746, 35669.82165089262, 34914.30171396609, 35518.822405887804, 35661.465078023335, 36024.0, 36216.637833621324, 34911.825440872635, 35305.0, 35521.0, 35761.0, 35870.282594347445, 31252.0, 31930.0, 32035.359292813548, 32194.19514512156, 33793.0, 25305.114320998677, 25435.61846572266, 25605.231843044738, 25729.023793905064, 25900.61149082803], "value": 18188.681918545306, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.81351980190635, 363.8435472746719, 366.92111196092833, 376.0, 393.8365578285012, 12980.675483112915, 16233.431829885956, 17324.22041008153, 18640.161192746324, 18681.53296167593, 16652.66694666109, 17211.483655490356, 17372.391966281182, 17637.82362176378, 17760.822391776077, 4989.850304490871, 5253.185756207775, 5545.833624991256, 5559.305086864142, 5632.887342253152, 3405.404054290489, 5591.7483713233005, 5798.275215598051, 6115.327313995457, 6138.84652883677, 6017.06735456005, 6135.3557876423, 6181.412765787243, 6181.536384771138, 6188.16459777931, 5424.783008679648, 5521.668699878021, 5527.143292789624, 5532.3914369419335, 5538.280023596927, 3353.7149342305934, 4882.951170488285, 5219.921701174486, 5231.555317797992, 5338.412774594792, 12589.118761686686, 12730.872691273062, 12792.808107878389, 14847.703045939128, 20845.8957705212, 1660.8837381383307, 1745.729411941151, 1990.5222746540871, 2414.8309618326725, 2442.755724427561, 8849.424787388863, 9404.764880877965, 9545.761355966088, 9575.904240957641, 9786.951065244628, 1566.8511491408299, 1596.5210436868892, 1624.1392062207049, 1670.1649175412294, 1692.3146125819062, 59459.40540594625, 60240.0, 68534.3146568533, 68628.6568567154, 68751.65624171897, 8352.206540378655, 8817.823643527221, 10061.748456288651, 10065.0, 10146.949265253626, 12694.555690550786, 13438.731225375634, 13471.595852124452, 13887.166769993833, 14048.0, 7121.82195445118, 7239.167495738057, 7252.891206631904, 7389.187189409161, 7436.776896693108, 6923.0, 7009.299070093052, 7049.7885063448175, 7126.5011449198055, 7157.534760240567, 4407.823687052482, 4451.354553589711, 4457.2199865023495, 4461.977690111528, 4554.908901821945, 1817.2367605605677, 1866.5986812835306, 1875.8686891917434, 1882.877612955181, 1899.5441094137584, 791.647716766045, 800.7597720683772, 876.8947726272888, 891.7280229529925, 914.9503078777999, 16470.42353517645, 16846.915765421094, 16953.660926781395, 17019.063951482538, 17548.38580649671, 827.7682248970298, 847.8855354527183, 881.1364862434722, 890.5992303463468, 904.615538396179, 9120.726378208665, 9267.76830579241, 9574.521273936276, 9602.0, 9762.853557196648, 335162.0, 337785.0, 338583.0, 352990.0, 355873.0, 49357.25964110541, 50070.499295007314, 74374.25625743781, 80619.59690201511, 80793.19206807975, 849.0787495567379, 889.0531583863165, 892.9285657147412, 915.0894859614726, 918.4122161816442, 12055.276683398673, 15793.0, 15882.047077175406, 16026.198690065683, 16196.595085122966, 38262.6173738259, 38409.231815362975, 38636.80681596574, 39153.41269880897, 40917.79541102275, 50287.0, 52099.0, 57622.0, 59758.701206493686, 59813.401865980784, 7007.404370628554, 7092.0, 7130.215676275502, 7202.351788339075, 7212.386947109345, 9397.781536757484, 10893.782124357627, 11080.778384432428, 11170.832437513607, 11289.830652540055, 9802.36284641496, 9965.601375944882, 9985.552094946337, 9997.950010250193, 10037.899621003697, 9457.905420945977, 9514.0, 9699.369540980097, 9750.75623109428, 9866.802663946824, 9895.505224738878, 9898.010198980044, 9917.355371900803, 9977.256404117683, 10006.599736010477, 7147.0, 7177.2463891292755, 7272.381847542806, 7382.0, 7627.932089507362, 8659.696910608098, 10105.595776169164, 10143.49282535885, 10151.441670708182, 10276.280660354003], "value": 8027.637768075765, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.7117469744003, 1387.5074348606245, 1448.6305991972044, 1692.5599344170512, 1725.2667616263086, 7541.811454713635, 8015.799605009877, 8085.151059138794, 8085.797855053619, 8090.393220508463, 6550.475961923046, 6780.491463140272, 6780.627065511395, 6954.165500139978, 7921.524708517493, 1915.80841915808, 2134.157007981846, 2203.2398822406244, 2210.557888422314, 2234.0505285253785, 2210.8784016879067, 2220.888955552224, 2225.721784776903, 2237.7762223777654, 2240.630296001158, 1831.716084006981, 2209.6464565669494, 2218.301235110935, 2218.3455880515207, 2221.7222847144108, 1835.8714889957707, 1864.5618279704258, 1878.8966606836618, 2080.739907511561, 2135.4661334666334, 2102.9158833646634, 2106.494441334084, 2107.104480595741, 2108.789121087879, 2108.9683654745036, 7674.117476490219, 7726.690932362699, 7731.072271327421, 7733.690652373898, 7737.651805668758, 4578.816847326103, 4696.694714843524, 4732.550407711262, 4744.430668319824, 4746.596539294164, 2638.617400476939, 2693.9057133000447, 2762.9171124866284, 2768.1280396675184, 2768.9307767305777, 776.4021703288437, 802.1537278171527, 813.8535063688533, 819.5983967855767, 842.9157084291583, 10931.344119352785, 10947.178961577914, 10953.288036277616, 10967.122630189566, 10974.451277436096, 4024.9195016099516, 4064.085580744309, 4212.073343864345, 4214.4099826024385, 4317.546657600969, 9257.953710231406, 9279.211267042241, 9292.303077269233, 9294.256459483226, 9298.72103836886, 4734.029523947583, 4744.976275118603, 4748.857534273978, 4752.928706069411, 4753.35829662998, 4165.83336666536, 4274.273373526508, 4281.422008028876, 4318.524962254212, 4324.2648749712625, 3408.16499957512, 3468.791872487659, 3506.1234691327168, 3527.9118022049656, 3530.3115892400733, 1054.783769327286, 1060.0194819791661, 1064.973375665599, 1074.2533938912381, 1074.6238816414193, 821.3511326052471, 825.9793505162419, 832.9416940814085, 850.5364576305928, 855.9700410485603, 5213.556847667991, 5266.47335266478, 5270.183121616155, 5379.273798037215, 5564.8330550083565, 416.6937301083701, 419.22443479562565, 420.81694462908735, 430.78245486029357, 431.04523480490866, 3588.1567831559564, 3683.2633473305445, 3696.852125914987, 3728.142527218755, 3744.1762812181278, 9544.570494327772, 9839.704808855746, 9948.602055917681, 9958.15355694774, 9958.80082398348, 9801.215902727765, 9821.80356392868, 9824.754381140387, 9824.754381140528, 9877.60489580423, 7395.075615548057, 7496.512726672749, 7509.887351689729, 7511.361534269539, 7511.737089201852, 5230.0, 5256.684598924076, 5383.81156659515, 5384.057789886676, 5433.619646624857, 8866.778330541789, 8877.289816814768, 9067.863982040406, 9108.0, 9141.451512909247, 9168.129027742354, 9265.86101208469, 9283.953580232326, 9287.117723816227, 9365.859512107187, 5621.184928185472, 6220.657863817533, 6361.968190159019, 6362.522810789302, 6498.935010649833, 7489.8876516851205, 7547.207543207884, 7557.05536807899, 7558.168601453725, 7581.658825352762, 6726.495512836655, 6893.276205998298, 6979.546329488567, 6980.092587963532, 7028.789136325714, 6819.69311380999, 6861.176658800976, 6866.622335771581, 6895.310468953065, 6900.378965893094, 6200.3179650239335, 6420.7431702731365, 6448.838779030561, 6493.642849643126, 6617.933820661924, 4452.220861349186, 4453.4433195850515, 4521.276595744665, 4572.199865023674, 4614.907701845877, 4688.249880019317, 4708.529147085264, 4714.29285607148, 4736.976315118402, 4794.95205047959], "value": 4033.1836075267515, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.95740638904164, 296.0452540556706, 296.05263157894734, 296.7848309975267, 300.96689364169936, 4578.221702310607, 4722.834700785474, 4749.928751068728, 4758.643101767364, 4789.802549362655, 4559.563737422718, 4714.528547145293, 4740.525947405266, 4754.714717116975, 4776.761161941898, 3782.73520853539, 3801.1067399161175, 3804.7146464015173, 3811.2758575870566, 3817.541894972607, 3621.004223838439, 3652.4703917931884, 3798.8603418974335, 3805.6765174960024, 3824.8852534424013, 3737.588865224823, 3779.7732136071786, 3786.2238241160635, 3787.2993496203153, 3802.771833689974, 3734.3651579231596, 3746.30693321735, 3757.4739536464917, 3762.7930463824478, 3795.4876091727815, 3356.328734253159, 3362.7477939154414, 3367.4107031269673, 3371.7639765216445, 3372.4098282800655, 4595.701279416827, 4644.1408339456975, 4654.534546545352, 4656.115338085787, 4667.206574882278, 4284.850030248957, 4297.548757380492, 4302.849400271007, 4320.179165958458, 4339.804708788112, 4941.234108713155, 4968.652194346397, 4971.328870602459, 4974.1543937530705, 5015.448300686921, 3548.982255088734, 3577.5574293415048, 3618.113562177257, 3626.873059442933, 3636.9272614547563, 5112.744362781883, 5153.2785410042625, 5157.9226311605735, 5180.6373553851245, 5187.974060129713, 3705.2589482103685, 3718.1448266898765, 3721.1813401051727, 3744.5319335083113, 3748.456473811282, 3723.646253605903, 3793.4499497572697, 3806.809659517013, 3815.465834783133, 3823.063349479395, 4226.725262857904, 4260.275753121977, 4267.3598960155605, 4274.529801721808, 4277.572242775697, 4926.088673595396, 4949.57928576075, 4954.702717836869, 4963.429205641398, 4972.4336833897205, 1222.4926655437894, 1260.4769020856243, 1312.3897387714567, 1313.6715821044738, 1314.9013823963242, 896.3994123936978, 900.2123142250531, 917.2845180759018, 917.6696389299847, 917.9541022948825, 4325.264705000158, 4333.696641235147, 4340.52254252032, 4342.5657434256955, 4370.2570563004365, 5067.695938243717, 5073.619478539124, 5077.055307921397, 5087.364079490064, 5093.134167191586, 4337.67467439937, 4340.435743353344, 4359.738415695068, 4386.692931494828, 4390.956090439118, 2298.5977453945493, 2430.708315002211, 2439.475512764764, 2439.621124742245, 2443.97556024441, 4178.0, 4243.639290660267, 4251.681123915719, 4273.743575385487, 4329.826806927751, 4578.397560853675, 4590.393362323227, 4595.287730401793, 4596.540345965377, 4602.332661764025, 4090.3864420337186, 4270.210011147948, 4329.242382583098, 4335.3063509838275, 4351.586599273064, 4481.395011673382, 4503.91905942578, 4538.88652783683, 4549.88430379875, 4555.635549155993, 4412.20580295537, 4424.69027168108, 4434.0, 4471.396361491157, 4477.9328310076025, 3987.9232607195313, 4266.637335826489, 4293.334533147367, 4295.5489673583825, 4311.202427550913, 3380.1211684961595, 3405.7786243894066, 3409.8124603147066, 3416.555847739877, 3431.3995050866497, 4513.54864513546, 4545.227361329018, 4558.22510173278, 4561.741937740707, 4923.384576927884, 4798.520147985174, 4944.950550494449, 4973.602111831117, 4978.576820970258, 4979.626528010342, 4972.5027497249985, 4973.875653108701, 4995.475475075064, 5007.39911210657, 5013.523715247045, 4807.519248075165, 4870.1233777920315, 4945.703257804544, 4949.7030178189425, 4951.207806750903, 4979.701217926936, 4980.900381992267, 4994.650374473752, 5007.0, 5009.968968773839, 4885.169521181479, 4913.7543122843, 4921.704697718148, 4923.522943116979, 4926.679765815211], "value": 3674.4417113834133, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.3584497579932, 638.6327861479649, 693.4279219643795, 694.808927544925, 753.984920301594, 13082.607521774338, 13777.586672399844, 14000.22998735069, 14333.641658958519, 14342.067765595242, 12945.546905858297, 12979.61061168164, 13046.804297935514, 13048.086633935629, 13070.15044022138, 631.4443289904877, 772.3628006894321, 772.4168252969, 792.2038351456778, 794.7893808140848, 785.1598789295456, 800.6237068577759, 800.6957356204634, 800.8998875140608, 800.9159038300982, 834.3116928533954, 834.6369329341726, 836.6569706420369, 837.2757564706516, 838.1283465196188, 651.25106127953, 802.3982013489882, 819.3076850061689, 826.4793180296405, 826.5247482697444, 607.8419610901153, 607.9756809727605, 608.0757248981523, 608.1060840564363, 608.2123649873424, 12993.61019169417, 12995.740085198338, 13023.0, 13024.739505209844, 13027.60917172477, 12474.937625311815, 12772.36138193096, 12941.158824676366, 12980.870191298061, 13140.802887956688, 11330.943345283304, 11434.371109588968, 11469.988810741697, 11650.825237621442, 11692.883071169264, 4014.8594799181888, 4096.344584866407, 4133.21468920904, 4137.875863724063, 4221.725587836811, 62192.37807621911, 62237.68881155611, 62457.375426246064, 62485.0, 62544.37455625431, 9264.629414823467, 9403.670871519464, 9636.518174091267, 9978.151857092083, 9981.550830212658, 18670.906645466686, 18826.152823122717, 18839.81160188408, 18851.622967540574, 18862.62274754497, 6523.380278873499, 6806.285340039323, 6822.079019332426, 6841.965790171117, 6964.512484126164, 3530.805805680661, 3548.503209550665, 3578.8568457261417, 3589.3718599245026, 3704.388775851954, 2689.7175796541474, 2761.4891245119716, 2782.151443809617, 2782.290515918428, 2784.8607569621845, 469.91071696377577, 473.62346934187406, 474.2909350520993, 479.82443014614336, 484.2639188433632, 364.1805936642554, 393.4373845401104, 423.2064878353088, 456.97029693069845, 471.02262804680424, 6619.371159739817, 6850.588964662036, 6981.189214951359, 7017.61403122823, 7089.22018577956, 425.6849931051, 439.7801099450275, 457.4167935881777, 466.5754163711007, 484.81819317755844, 3511.719062474996, 3554.87557935471, 3620.499897908858, 3720.3861362874823, 3825.1967086911504, 77663.0, 98474.50762746284, 98945.55799430957, 99461.50269248607, 108856.45571772249, 67649.0, 70509.64745176242, 71888.28111718816, 79488.602556988, 85356.57321713351, 71548.0, 72248.0, 73448.63275683587, 74779.0, 76448.61775691085, 8243.83512329762, 8318.417710760188, 8384.496930184208, 8453.577321133796, 8456.704015359433, 43363.349549756145, 45765.54234457746, 45767.77116114398, 48054.75972620115, 48821.755891221736, 58004.0, 67206.32793672, 67298.66350668215, 67337.66331168312, 67687.98468022885, 10430.739231519272, 10518.579256829948, 10537.15702743794, 10543.57825686964, 10612.575496980344, 10817.0, 11245.550177993116, 11869.643910682693, 12105.302261076233, 12286.75426491483, 11430.771384572095, 11454.656360309204, 11725.941370293434, 11965.880341196826, 11981.400929953641, 8289.171082891662, 8298.336133109458, 8471.661133554835, 11281.71795705114, 11595.594154204562, 8772.473651580925, 9315.39449935752, 9336.206422454168, 9438.622455101993, 9763.560639771085, 7581.658825352762, 7724.1117271514495, 7752.0, 8030.558319292495, 8076.111627720828, 7730.458867879194, 7826.295633393022, 8097.635606397608, 9382.0, 9716.368436051634], "value": 6265.372294322661, "unit": "score"}], "name": "dromaeo_css", "value": 6268.842089263018, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 116.0, 113.0, 116.0, 114.0, 113.0, 115.0, 114.0, 114.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 126.0, 178.0, 129.0, 128.0, 148.0, 129.0, 194.0, 122.0], "value": 142.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 162.0, 163.0, 163.0, 162.0, 166.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 132.0, 75.0, 75.0, 75.0, 76.0, 100.0, 77.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 96.0, 122.0, 85.0, 86.0, 85.0, 118.0, 93.0, 85.0, 144.0], "value": 99.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 118.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 171.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 66.0, 67.0, 66.0, 66.0, 67.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 77.0, 79.0, 80.0, 81.0, 82.0, 79.0, 81.0, 81.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 129.0, 128.0, 128.0, 128.0, 129.0, 128.0, 129.0, 200.0, 133.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 170.0, 167.0, 168.0, 175.0, 168.0, 172.0, 167.0, 165.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 65.0, 66.0, 65.0, 66.0, 73.0, 66.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1544.2000000000003, "alertThreshold": 2.0}]} 11:41:21 INFO - Return code: 0 11:41:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:41:21 INFO - # TBPL SUCCESS # 11:41:21 INFO - Running post-action listener: _resource_record_post_action 11:41:21 INFO - ##### 11:41:21 INFO - ##### Finished run-tests step (success) 11:41:21 INFO - ##### 11:41:21 INFO - Running post-run listener: _resource_record_post_run 11:41:21 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8192; Write bytes: 536297472; Read time: 176; Write time: 350440 11:41:21 INFO - TinderboxPrint: CPU usage
13.0% 11:41:21 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 176 11:41:21 INFO - TinderboxPrint: I/O write bytes / time
536,297,472 / 350,440 11:41:21 INFO - TinderboxPrint: CPU idle
8,329.9 (87.0%) 11:41:21 INFO - TinderboxPrint: CPU user
1,205.1 (12.6%) 11:41:21 INFO - TinderboxPrint: Swap in / out
0 / 0 11:41:21 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 246382592; Read time: 0; Write time: 199084 11:41:21 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289607680; Read time: 164; Write time: 151328 11:41:22 INFO - Running post-run listener: _upload_blobber_files 11:41:22 INFO - Blob upload gear active. 11:41:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:41:22 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 11:41:22 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'] 11:41:22 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 11:41:22 INFO - (blobuploader) - INFO - Open directory for files ... 11:41:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:41:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:41:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:41:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:41:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:41:26 INFO - (blobuploader) - INFO - Done attempting. 11:41:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:41:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:41:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:41:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:41:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:41:27 INFO - (blobuploader) - INFO - Done attempting. 11:41:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:41:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:41:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:41:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:41:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:41:28 INFO - (blobuploader) - INFO - Done attempting. 11:41:28 INFO - (blobuploader) - INFO - Iteration through files over. 11:41:28 INFO - Return code: 0 11:41:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:41:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:41:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/586ced205d257cba9eb8a2bb22a55fb15d039189a80bf2e2e123fd0208518a1d2166589d8110cc059263aceb8536b73b23575f113ac512874d9d7c79b33b74c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2882ce8fc0b0774664d39533f6787d0757531853e1495c839bad32ea26d51767ba7217b0e4abbb5d53ede5ef4d30a94866c71648ca446ac6f47b3c27be797683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5d9ecd382cf72c762cfaa3c527a8409d3153a98acfed5c594a4bea020b5ad08d16444787746be5774a0f4583c642a9cd257a4414295f96bd44278b50ea3a10c2"} 11:41:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:41:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:41:28 INFO - Contents: 11:41:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/586ced205d257cba9eb8a2bb22a55fb15d039189a80bf2e2e123fd0208518a1d2166589d8110cc059263aceb8536b73b23575f113ac512874d9d7c79b33b74c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2882ce8fc0b0774664d39533f6787d0757531853e1495c839bad32ea26d51767ba7217b0e4abbb5d53ede5ef4d30a94866c71648ca446ac6f47b3c27be797683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5d9ecd382cf72c762cfaa3c527a8409d3153a98acfed5c594a4bea020b5ad08d16444787746be5774a0f4583c642a9cd257a4414295f96bd44278b50ea3a10c2"} 11:41:28 INFO - Running post-run listener: copy_logs_to_upload_dir 11:41:28 INFO - Copying logs to upload dir... 11:41:28 INFO - mkdir: /builds/slave/test/build/upload/logs 11:41:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.406774 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-07-13 11:41:28.199822) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 11:41:28.208828) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992 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/586ced205d257cba9eb8a2bb22a55fb15d039189a80bf2e2e123fd0208518a1d2166589d8110cc059263aceb8536b73b23575f113ac512874d9d7c79b33b74c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2882ce8fc0b0774664d39533f6787d0757531853e1495c839bad32ea26d51767ba7217b0e4abbb5d53ede5ef4d30a94866c71648ca446ac6f47b3c27be797683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5d9ecd382cf72c762cfaa3c527a8409d3153a98acfed5c594a4bea020b5ad08d16444787746be5774a0f4583c642a9cd257a4414295f96bd44278b50ea3a10c2"} build_url:https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011389 build_url: 'https://queue.taskcluster.net/v1/task/aKLOB18-STqF4okicgBNVg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/586ced205d257cba9eb8a2bb22a55fb15d039189a80bf2e2e123fd0208518a1d2166589d8110cc059263aceb8536b73b23575f113ac512874d9d7c79b33b74c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2882ce8fc0b0774664d39533f6787d0757531853e1495c839bad32ea26d51767ba7217b0e4abbb5d53ede5ef4d30a94866c71648ca446ac6f47b3c27be797683", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5d9ecd382cf72c762cfaa3c527a8409d3153a98acfed5c594a4bea020b5ad08d16444787746be5774a0f4583c642a9cd257a4414295f96bd44278b50ea3a10c2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 11:41:28.237518) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:41:28.237925) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1468431687.857996-1120043992 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:41:28.320420) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 11:41:28.320735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 11:41:28.321023) ========= ========= Total master_lag: 0.17 =========