builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1468970276.83 results: success (0) buildid: 20160719133037 builduid: 0dfb97c2c35c4c9d994fad9affb772ea revision: 1ebb6348564337c00d889bd858eedf3c922e68c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:56.833523) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:56.833978) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:56.834415) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:56.852519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:56.852806) ========= 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=745a4373a2a16df560f216e000000264-1468970197.509159-2120899850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 16:17:57-- 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.1M=0s 2016-07-19 16:17:57 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083408 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:56.950244) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:56.950562) ========= 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=745a4373a2a16df560f216e000000264-1468970197.509159-2120899850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014253 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:57.000008) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:57.000450) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1ebb6348564337c00d889bd858eedf3c922e68c0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1ebb6348564337c00d889bd858eedf3c922e68c0 --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=745a4373a2a16df560f216e000000264-1468970197.509159-2120899850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 16:17:57,418 truncating revision to first 12 chars 2016-07-19 16:17:57,418 Setting DEBUG logging. 2016-07-19 16:17:57,419 attempt 1/10 2016-07-19 16:17:57,419 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1ebb63485643?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 16:17:57,743 unpacking tar archive at: mozilla-inbound-1ebb63485643/testing/mozharness/ program finished with exit code 0 elapsedTime=0.681180 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:57.694557) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:57.694827) ========= script_repo_revision: 1ebb6348564337c00d889bd858eedf3c922e68c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:57.695183) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:57.695462) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 16:17:57.706261) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-19 16:17:57.706574) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=745a4373a2a16df560f216e000000264-1468970197.509159-2120899850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:17:58 INFO - MultiFileLogger online at 20160719 16:17:58 in /builds/slave/test 16:17:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:17:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:17:58 INFO - {'append_to_log': False, 16:17:58 INFO - 'base_work_dir': '/builds/slave/test', 16:17:58 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:17:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:17:58 INFO - 'branch': 'Mozilla-Inbound', 16:17:58 INFO - 'buildbot_json_path': 'buildprops.json', 16:17:58 INFO - 'config_files': ('talos/linux_config.py',), 16:17:58 INFO - 'default_actions': ('clobber', 16:17:58 INFO - 'read-buildbot-config', 16:17:58 INFO - 'download-and-extract', 16:17:58 INFO - 'populate-webroot', 16:17:58 INFO - 'create-virtualenv', 16:17:58 INFO - 'install', 16:17:58 INFO - 'run-tests'), 16:17:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:17:58 INFO - 'download_minidump_stackwalk': True, 16:17:58 INFO - 'download_symbols': 'ondemand', 16:17:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:17:58 INFO - 'tooltool.py': '/tools/tooltool.py', 16:17:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:17:58 INFO - '/tools/misc-python/virtualenv.py')}, 16:17:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:17:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:17:58 INFO - 'installer_path': 'installer.exe', 16:17:58 INFO - 'log_level': 'info', 16:17:58 INFO - 'log_name': 'talos', 16:17:58 INFO - 'log_to_console': True, 16:17:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:17:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:17:58 INFO - 'opt_config_files': (), 16:17:58 INFO - 'pip_index': False, 16:17:58 INFO - 'sps_profile': False, 16:17:58 INFO - 'sps_profile_interval': 0, 16:17:58 INFO - 'suite': 'dromaeojs', 16:17:58 INFO - 'system_bits': '32', 16:17:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:17:58 INFO - 'title': 'talos-linux64-ix-032', 16:17:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:17:58 INFO - 'use_talos_json': True, 16:17:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:17:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:17:58 INFO - 'work_dir': 'build'} 16:17:58 INFO - ##### 16:17:58 INFO - ##### Running clobber step. 16:17:58 INFO - ##### 16:17:58 INFO - Running pre-action listener: _resource_record_pre_action 16:17:58 INFO - Running main action method: clobber 16:17:58 INFO - rmtree: /builds/slave/test/build 16:17:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:17:58 INFO - Running post-action listener: _resource_record_post_action 16:17:58 INFO - ##### 16:17:58 INFO - ##### Finished clobber step (success) 16:17:58 INFO - ##### 16:17:58 INFO - ##### 16:17:58 INFO - ##### Running read-buildbot-config step. 16:17:58 INFO - ##### 16:17:58 INFO - Running pre-action listener: _resource_record_pre_action 16:17:58 INFO - Running main action method: read_buildbot_config 16:17:58 INFO - Using buildbot properties: 16:17:58 INFO - { 16:17:58 INFO - "project": "", 16:17:58 INFO - "product": "firefox", 16:17:58 INFO - "script_repo_revision": "production", 16:17:58 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:17:58 INFO - "repository": "", 16:17:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:17:58 INFO - "buildid": "20160719133037", 16:17:58 INFO - "pgo_build": "True", 16:17:58 INFO - "basedir": "/builds/slave/test", 16:17:58 INFO - "buildnumber": 2089, 16:17:58 INFO - "slavename": "talos-linux64-ix-032", 16:17:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:17:58 INFO - "platform": "ubuntu64_hw", 16:17:58 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:17:58 INFO - "branch": "mozilla-inbound", 16:17:58 INFO - "repo_path": "integration/mozilla-inbound", 16:17:58 INFO - "revision": "1ebb6348564337c00d889bd858eedf3c922e68c0", 16:17:58 INFO - "stage_platform": "linux64-pgo", 16:17:58 INFO - "builduid": "0dfb97c2c35c4c9d994fad9affb772ea", 16:17:58 INFO - "slavebuilddir": "test" 16:17:58 INFO - } 16:17:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:17:58 INFO - Running post-action listener: _resource_record_post_action 16:17:58 INFO - ##### 16:17:58 INFO - ##### Finished read-buildbot-config step (success) 16:17:58 INFO - ##### 16:17:58 INFO - ##### 16:17:58 INFO - ##### Running download-and-extract step. 16:17:58 INFO - ##### 16:17:58 INFO - Running pre-action listener: _resource_record_pre_action 16:17:58 INFO - Running main action method: download_and_extract 16:17:58 INFO - mkdir: /builds/slave/test/build/tests 16:17:58 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')]} 16:17:58 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:17:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:17:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:17:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:17:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:18:00 INFO - Downloaded 1361 bytes. 16:18:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:18:00 INFO - Using the following test package requirements: 16:18:00 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:18:00 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:18:00 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:18:00 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:00 INFO - u'jsshell-linux-x86_64.zip'], 16:18:00 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:18:00 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:18:00 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:18:00 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:18:00 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:18:00 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:18:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:18:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:00 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:18:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:18:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:18:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:18:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:18:01 INFO - Downloaded 24336238 bytes. 16:18:01 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'] 16:18:01 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 16:18:02 INFO - Return code: 0 16:18:02 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 16:18: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')]} 16:18:02 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:18:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:18:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:18:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:18:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:18:05 INFO - Downloaded 24336238 bytes. 16:18:05 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'] 16:18:05 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 16:18:06 INFO - Return code: 0 16:18:06 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')]} 16:18:06 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:18:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:18:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:18:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:18:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:18:07 INFO - Downloaded 14403317 bytes. 16:18:07 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'] 16:18:07 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 16:18:08 INFO - Return code: 0 16:18:08 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')]} 16:18:08 INFO - https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:18:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:18:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:18:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:18:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PxsIAJDITCS_4pdpwb9FAg/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 16:18:09 INFO - Downloaded 60888591 bytes. 16:18:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:18:09 INFO - mkdir: /builds/slave/test/properties 16:18:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:18:09 INFO - Writing to file /builds/slave/test/properties/build_url 16:18:09 INFO - Contents: 16:18:09 INFO - build_url:https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:18:10 INFO - Running post-action listener: _resource_record_post_action 16:18:10 INFO - Running post-action listener: set_extra_try_arguments 16:18:10 INFO - ##### 16:18:10 INFO - ##### Finished download-and-extract step (success) 16:18:10 INFO - ##### 16:18:10 INFO - ##### 16:18:10 INFO - ##### Running populate-webroot step. 16:18:10 INFO - ##### 16:18:10 INFO - Running pre-action listener: _resource_record_pre_action 16:18:10 INFO - Running main action method: populate_webroot 16:18:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:18:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:18:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:18:10 INFO - '--tppagecycles', 16:18:10 INFO - '7'], 16:18:10 INFO - 'tests': ['tsvgm']}}, 16:18:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:18:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:18:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:18:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:18:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:10 INFO - 'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:10 INFO - 'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['damp', 'tps']}, 16:18:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:10 INFO - 'tests': ['damp', 'tps']}, 16:18:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['dromaeo_dom']}, 16:18:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:18:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['basic_compositor_video']}, 16:18:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:18:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['a11yr', 16:18:10 INFO - 'ts_paint', 16:18:10 INFO - 'tpaint', 16:18:10 INFO - 'sessionrestore', 16:18:10 INFO - 'sessionrestore_no_auto_restore', 16:18:10 INFO - 'tabpaint']}, 16:18:10 INFO - 'other-e10s': {'tests': ['a11yr', 16:18:10 INFO - 'ts_paint', 16:18:10 INFO - 'tpaint', 16:18:10 INFO - 'sessionrestore', 16:18:10 INFO - 'sessionrestore_no_auto_restore', 16:18:10 INFO - 'tabpaint']}, 16:18:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:18:10 INFO - 'ts_paint', 16:18:10 INFO - 'tpaint', 16:18:10 INFO - 'sessionrestore', 16:18:10 INFO - 'sessionrestore_no_auto_restore', 16:18:10 INFO - 'tabpaint']}, 16:18:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:18:10 INFO - 'ts_paint', 16:18:10 INFO - 'tpaint', 16:18:10 INFO - 'sessionrestore', 16:18:10 INFO - 'sessionrestore_no_auto_restore', 16:18:10 INFO - 'tabpaint']}, 16:18:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['a11yr', 16:18:10 INFO - 'ts_paint', 16:18:10 INFO - 'tpaint', 16:18:10 INFO - 'sessionrestore', 16:18:10 INFO - 'sessionrestore_no_auto_restore', 16:18:10 INFO - 'tabpaint']}, 16:18:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['a11yr', 16:18:10 INFO - 'ts_paint', 16:18:10 INFO - 'tpaint', 16:18:10 INFO - 'sessionrestore', 16:18:10 INFO - 'sessionrestore_no_auto_restore', 16:18:10 INFO - 'tabpaint']}, 16:18:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['tsvgx', 16:18:10 INFO - 'tsvgr_opacity', 16:18:10 INFO - 'tart', 16:18:10 INFO - 'tscrollx', 16:18:10 INFO - 'cart']}, 16:18:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:18:10 INFO - 'tsvgr_opacity', 16:18:10 INFO - 'tart', 16:18:10 INFO - 'tscrollx', 16:18:10 INFO - 'cart']}, 16:18:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:10 INFO - 'talos_options': ['--disable-e10s'], 16:18:10 INFO - 'tests': ['tp5o']}, 16:18:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:10 INFO - 'tests': ['tp5o']}, 16:18:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:10 INFO - 'talos_options': ['--disable-e10s', 16:18:10 INFO - '--xperf_path', 16:18:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:10 INFO - 'tests': ['tp5n']}, 16:18:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:10 INFO - 'talos_options': ['--xperf_path', 16:18:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:10 INFO - 'tests': ['tp5n']}}, 16:18:10 INFO - 'talos.zip': {'path': '', 16:18:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:18:10 INFO - Running post-action listener: _resource_record_post_action 16:18:10 INFO - ##### 16:18:10 INFO - ##### Finished populate-webroot step (success) 16:18:10 INFO - ##### 16:18:10 INFO - ##### 16:18:10 INFO - ##### Running create-virtualenv step. 16:18:10 INFO - ##### 16:18:10 INFO - Running pre-action listener: _resource_record_pre_action 16:18:10 INFO - Running main action method: create_virtualenv 16:18:10 INFO - Creating virtualenv /builds/slave/test/build/venv 16:18:10 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 16:18:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:18:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:18:10 INFO - Using real prefix '/usr' 16:18:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:18:10 INFO - Installing distribute.............................................................................................................................................................................................done. 16:18:12 INFO - Installing pip.................done. 16:18:12 INFO - Return code: 0 16:18:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:12 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 0x188c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, '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': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', '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 16:18:12 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 16:18:12 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 16:18:12 INFO - Using env: {'DISPLAY': ':0', 16:18:12 INFO - 'HOME': '/home/cltbld', 16:18:12 INFO - 'LANG': 'en_US.UTF-8', 16:18:12 INFO - 'LANGUAGE': 'en_US:en', 16:18:12 INFO - 'LOGNAME': 'cltbld', 16:18:12 INFO - 'MAIL': '/var/mail/cltbld', 16:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:12 INFO - 'MOZ_NO_REMOTE': '1', 16:18:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:12 INFO - 'NO_EM_RESTART': '1', 16:18:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:12 INFO - 'PWD': '/builds/slave/test', 16:18:12 INFO - 'SHELL': '/bin/bash', 16:18:12 INFO - 'SHLVL': '1', 16:18:12 INFO - 'TERM': 'linux', 16:18:12 INFO - 'TMOUT': '86400', 16:18:12 INFO - 'USER': 'cltbld', 16:18:12 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:12 INFO - '_': '/tools/buildbot/bin/python'} 16:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:13 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 16:18:13 INFO - Cleaning up... 16:18:13 INFO - Return code: 0 16:18:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:18: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')]} 16:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18: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')]} 16:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:13 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 0x188c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, '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': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', '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 16:18:13 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 16:18:13 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 16:18:13 INFO - Using env: {'DISPLAY': ':0', 16:18:13 INFO - 'HOME': '/home/cltbld', 16:18:13 INFO - 'LANG': 'en_US.UTF-8', 16:18:13 INFO - 'LANGUAGE': 'en_US:en', 16:18:13 INFO - 'LOGNAME': 'cltbld', 16:18:13 INFO - 'MAIL': '/var/mail/cltbld', 16:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:13 INFO - 'MOZ_NO_REMOTE': '1', 16:18:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:13 INFO - 'NO_EM_RESTART': '1', 16:18:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:13 INFO - 'PWD': '/builds/slave/test', 16:18:13 INFO - 'SHELL': '/bin/bash', 16:18:13 INFO - 'SHLVL': '1', 16:18:13 INFO - 'TERM': 'linux', 16:18:13 INFO - 'TMOUT': '86400', 16:18:13 INFO - 'USER': 'cltbld', 16:18:13 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:13 INFO - '_': '/tools/buildbot/bin/python'} 16:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:13 INFO - Downloading/unpacking psutil>=3.1.1 16:18:13 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 16:18:13 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 16:18:13 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 16:18:13 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 16:18:13 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 16:18:13 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 16:18:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:18:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:15 INFO - Installing collected packages: psutil 16:18:15 INFO - Running setup.py install for psutil 16:18:15 INFO - building 'psutil._psutil_linux' extension 16:18:15 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 16:18:15 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 16:18:15 INFO - building 'psutil._psutil_posix' extension 16:18:15 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 16:18:15 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 16:18:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:15 INFO - Successfully installed psutil 16:18:15 INFO - Cleaning up... 16:18:15 INFO - Return code: 0 16:18:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:15 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 0x188c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, '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': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', '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 16:18:15 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 16:18:15 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 16:18:15 INFO - Using env: {'DISPLAY': ':0', 16:18:15 INFO - 'HOME': '/home/cltbld', 16:18:15 INFO - 'LANG': 'en_US.UTF-8', 16:18:15 INFO - 'LANGUAGE': 'en_US:en', 16:18:15 INFO - 'LOGNAME': 'cltbld', 16:18:15 INFO - 'MAIL': '/var/mail/cltbld', 16:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:15 INFO - 'MOZ_NO_REMOTE': '1', 16:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:15 INFO - 'NO_EM_RESTART': '1', 16:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:15 INFO - 'PWD': '/builds/slave/test', 16:18:15 INFO - 'SHELL': '/bin/bash', 16:18:15 INFO - 'SHLVL': '1', 16:18:15 INFO - 'TERM': 'linux', 16:18:15 INFO - 'TMOUT': '86400', 16:18:15 INFO - 'USER': 'cltbld', 16:18:15 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:15 INFO - '_': '/tools/buildbot/bin/python'} 16:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:18:16 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 16:18:16 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 16:18:16 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 16:18:16 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 16:18:16 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 16:18:16 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 16:18:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:18:18 INFO - Installing collected packages: mozsystemmonitor 16:18:18 INFO - Running setup.py install for mozsystemmonitor 16:18:18 INFO - Successfully installed mozsystemmonitor 16:18:18 INFO - Cleaning up... 16:18:18 INFO - Return code: 0 16:18:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:18:18 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')]} 16:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:18 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')]} 16:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:18 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 0x188c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, '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': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', '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 16:18:18 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 16:18:18 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 16:18:18 INFO - Using env: {'DISPLAY': ':0', 16:18:18 INFO - 'HOME': '/home/cltbld', 16:18:18 INFO - 'LANG': 'en_US.UTF-8', 16:18:18 INFO - 'LANGUAGE': 'en_US:en', 16:18:18 INFO - 'LOGNAME': 'cltbld', 16:18:18 INFO - 'MAIL': '/var/mail/cltbld', 16:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:18 INFO - 'MOZ_NO_REMOTE': '1', 16:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:18 INFO - 'NO_EM_RESTART': '1', 16:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:18 INFO - 'PWD': '/builds/slave/test', 16:18:18 INFO - 'SHELL': '/bin/bash', 16:18:18 INFO - 'SHLVL': '1', 16:18:18 INFO - 'TERM': 'linux', 16:18:18 INFO - 'TMOUT': '86400', 16:18:18 INFO - 'USER': 'cltbld', 16:18:18 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:18 INFO - '_': '/tools/buildbot/bin/python'} 16:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:18 INFO - Downloading/unpacking jsonschema==2.5.1 16:18: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 16:18: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 16:18: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 16:18: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 16:18: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 16:18: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 16:18:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:20 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:20 INFO - warning: no files found matching '*.txt' 16:18:20 INFO - no previously-included directories found matching 'build' 16:18:20 INFO - no previously-included directories found matching 'dist' 16:18:20 INFO - no previously-included directories found matching '.git*' 16:18:20 INFO - Installing collected packages: jsonschema, functools32 16:18:20 INFO - Running setup.py install for functools32 16:18:20 INFO - warning: no files found matching '*.txt' 16:18:20 INFO - no previously-included directories found matching 'build' 16:18:20 INFO - no previously-included directories found matching 'dist' 16:18:20 INFO - no previously-included directories found matching '.git*' 16:18:20 INFO - Successfully installed jsonschema functools32 16:18:20 INFO - Cleaning up... 16:18:20 INFO - Return code: 0 16:18:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:18: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')]} 16:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18: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')]} 16:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18: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', '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 0x188c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, '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': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', '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 16:18: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:18: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 blobuploader==1.2.4 16:18:20 INFO - Using env: {'DISPLAY': ':0', 16:18:20 INFO - 'HOME': '/home/cltbld', 16:18:20 INFO - 'LANG': 'en_US.UTF-8', 16:18:20 INFO - 'LANGUAGE': 'en_US:en', 16:18:20 INFO - 'LOGNAME': 'cltbld', 16:18:20 INFO - 'MAIL': '/var/mail/cltbld', 16:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:20 INFO - 'MOZ_NO_REMOTE': '1', 16:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:20 INFO - 'NO_EM_RESTART': '1', 16:18:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:20 INFO - 'PWD': '/builds/slave/test', 16:18:20 INFO - 'SHELL': '/bin/bash', 16:18:20 INFO - 'SHLVL': '1', 16:18:20 INFO - 'TERM': 'linux', 16:18:20 INFO - 'TMOUT': '86400', 16:18:20 INFO - 'USER': 'cltbld', 16:18:20 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:20 INFO - '_': '/tools/buildbot/bin/python'} 16:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:20 INFO - Downloading/unpacking blobuploader==1.2.4 16:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:22 INFO - Downloading blobuploader-1.2.4.tar.gz 16:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:18:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:18:22 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 16:18:22 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 16:18:22 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 16:18:22 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 16:18:22 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 16:18:22 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 16:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:18:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:23 INFO - Downloading docopt-0.6.1.tar.gz 16:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:18:23 INFO - Installing collected packages: blobuploader, requests, docopt 16:18:23 INFO - Running setup.py install for blobuploader 16:18:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:18:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:18:23 INFO - Running setup.py install for requests 16:18:24 INFO - Running setup.py install for docopt 16:18:24 INFO - Successfully installed blobuploader requests docopt 16:18:24 INFO - Cleaning up... 16:18:24 INFO - Return code: 0 16:18:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:24 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')]} 16:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:24 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')]} 16:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:24 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 0x188c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, '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': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', '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 16:18:24 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 16:18:24 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 16:18:24 INFO - Using env: {'DISPLAY': ':0', 16:18:24 INFO - 'HOME': '/home/cltbld', 16:18:24 INFO - 'LANG': 'en_US.UTF-8', 16:18:24 INFO - 'LANGUAGE': 'en_US:en', 16:18:24 INFO - 'LOGNAME': 'cltbld', 16:18:24 INFO - 'MAIL': '/var/mail/cltbld', 16:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:24 INFO - 'MOZ_NO_REMOTE': '1', 16:18:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:24 INFO - 'NO_EM_RESTART': '1', 16:18:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:24 INFO - 'PWD': '/builds/slave/test', 16:18:24 INFO - 'SHELL': '/bin/bash', 16:18:24 INFO - 'SHLVL': '1', 16:18:24 INFO - 'TERM': 'linux', 16:18:24 INFO - 'TMOUT': '86400', 16:18:24 INFO - 'USER': 'cltbld', 16:18:24 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:24 INFO - '_': '/tools/buildbot/bin/python'} 16:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:24 INFO - Running setup.py (path:/tmp/pip-apssE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:24 INFO - Running setup.py (path:/tmp/pip-U4xggp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:24 INFO - Running setup.py (path:/tmp/pip-_WpCVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:24 INFO - Running setup.py (path:/tmp/pip-Eb5Nqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:24 INFO - Running setup.py (path:/tmp/pip-pwqCVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:24 INFO - Running setup.py (path:/tmp/pip-MHkYz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:24 INFO - Running setup.py (path:/tmp/pip-4JGXyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:25 INFO - Running setup.py (path:/tmp/pip-NvwFDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:25 INFO - Running setup.py (path:/tmp/pip-jT1Z8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:25 INFO - Running setup.py (path:/tmp/pip-Kqz9vO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:25 INFO - Running setup.py (path:/tmp/pip-v6pHuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:25 INFO - Running setup.py (path:/tmp/pip-ZXKmvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:25 INFO - Running setup.py (path:/tmp/pip-I2pvj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:25 INFO - Running setup.py (path:/tmp/pip-yYZ6M7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:25 INFO - Running setup.py (path:/tmp/pip-iRPPb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:25 INFO - Running setup.py (path:/tmp/pip-_ExM3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:25 INFO - Running setup.py (path:/tmp/pip-IQ82e5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:25 INFO - Running setup.py install for manifestparser 16:18:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:25 INFO - Running setup.py install for mozcrash 16:18:25 INFO - Running setup.py install for mozdebug 16:18:26 INFO - Running setup.py install for mozdevice 16:18:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Running setup.py install for mozfile 16:18:26 INFO - Running setup.py install for mozhttpd 16:18:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Running setup.py install for mozinfo 16:18:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Running setup.py install for mozInstall 16:18:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Running setup.py install for mozleak 16:18:26 INFO - Running setup.py install for mozlog 16:18:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Running setup.py install for moznetwork 16:18:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:26 INFO - Running setup.py install for mozprocess 16:18:26 INFO - Running setup.py install for mozprofile 16:18:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:27 INFO - Running setup.py install for mozrunner 16:18:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:27 INFO - Running setup.py install for mozscreenshot 16:18:27 INFO - Running setup.py install for moztest 16:18:27 INFO - Running setup.py install for mozversion 16:18:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:27 INFO - Cleaning up... 16:18:27 INFO - Return code: 0 16:18:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:27 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 0x188c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, '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': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', '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 16:18:27 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 16:18:27 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 16:18:27 INFO - Using env: {'DISPLAY': ':0', 16:18:27 INFO - 'HOME': '/home/cltbld', 16:18:27 INFO - 'LANG': 'en_US.UTF-8', 16:18:27 INFO - 'LANGUAGE': 'en_US:en', 16:18:27 INFO - 'LOGNAME': 'cltbld', 16:18:27 INFO - 'MAIL': '/var/mail/cltbld', 16:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:27 INFO - 'MOZ_NO_REMOTE': '1', 16:18:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:27 INFO - 'NO_EM_RESTART': '1', 16:18:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:27 INFO - 'PWD': '/builds/slave/test', 16:18:27 INFO - 'SHELL': '/bin/bash', 16:18:27 INFO - 'SHLVL': '1', 16:18:27 INFO - 'TERM': 'linux', 16:18:27 INFO - 'TMOUT': '86400', 16:18:27 INFO - 'USER': 'cltbld', 16:18:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:27 INFO - '_': '/tools/buildbot/bin/python'} 16:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:27 INFO - Running setup.py (path:/tmp/pip-q3Ku1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:27 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)) 16:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:27 INFO - Running setup.py (path:/tmp/pip-KKmk5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:27 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)) 16:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:27 INFO - Running setup.py (path:/tmp/pip-pH3FFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:27 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)) 16:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:27 INFO - Running setup.py (path:/tmp/pip-P2Fs0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:28 INFO - Running setup.py (path:/tmp/pip-I0N2Bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:28 INFO - Running setup.py (path:/tmp/pip-GHQIXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:28 INFO - Running setup.py (path:/tmp/pip-nXfT5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:28 INFO - Running setup.py (path:/tmp/pip-fN_PL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:28 INFO - Running setup.py (path:/tmp/pip-t4auO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:28 INFO - Running setup.py (path:/tmp/pip-mMLkiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:28 INFO - Running setup.py (path:/tmp/pip-qpZoTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:28 INFO - Running setup.py (path:/tmp/pip-0haslB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:28 INFO - Running setup.py (path:/tmp/pip-WcOJ6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:28 INFO - Running setup.py (path:/tmp/pip-7yxz04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:28 INFO - Running setup.py (path:/tmp/pip-Sbah27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:28 INFO - Running setup.py (path:/tmp/pip-sDgG0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:28 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)) 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:28 INFO - Running setup.py (path:/tmp/pip-77FJdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:28 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)) 16:18:28 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)) 16:18:28 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)) 16:18:28 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)) 16:18:28 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)) 16:18:28 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)) 16:18:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:30 INFO - Downloading blessings-1.6.tar.gz 16:18:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:30 INFO - Installing collected packages: blessings 16:18:30 INFO - Running setup.py install for blessings 16:18:31 INFO - Successfully installed blessings 16:18:31 INFO - Cleaning up... 16:18:31 INFO - Return code: 0 16:18:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:31 INFO - Reading from file tmpfile_stdout 16:18:31 INFO - Current package versions: 16:18:31 INFO - argparse == 1.2.1 16:18:31 INFO - blessings == 1.6 16:18:31 INFO - blobuploader == 1.2.4 16:18:31 INFO - docopt == 0.6.1 16:18:31 INFO - functools32 == 3.2.3-2 16:18:31 INFO - jsonschema == 2.5.1 16:18:31 INFO - manifestparser == 1.1 16:18:31 INFO - mozInstall == 1.12 16:18:31 INFO - mozcrash == 0.17 16:18:31 INFO - mozdebug == 0.1 16:18:31 INFO - mozdevice == 0.48 16:18:31 INFO - mozfile == 1.2 16:18:31 INFO - mozhttpd == 0.7 16:18:31 INFO - mozinfo == 0.9 16:18:31 INFO - mozleak == 0.1 16:18:31 INFO - mozlog == 3.2 16:18:31 INFO - moznetwork == 0.27 16:18:31 INFO - mozprocess == 0.23 16:18:31 INFO - mozprofile == 0.28 16:18:31 INFO - mozrunner == 6.12 16:18:31 INFO - mozscreenshot == 0.1 16:18:31 INFO - mozsystemmonitor == 0.3 16:18:31 INFO - moztest == 0.7 16:18:31 INFO - mozversion == 1.4 16:18:31 INFO - psutil == 3.1.1 16:18:31 INFO - requests == 1.2.3 16:18:31 INFO - wsgiref == 0.1.2 16:18:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:31 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 0x188c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, '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': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', '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 16:18:31 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 16:18:31 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 16:18:31 INFO - Using env: {'DISPLAY': ':0', 16:18:31 INFO - 'HOME': '/home/cltbld', 16:18:31 INFO - 'LANG': 'en_US.UTF-8', 16:18:31 INFO - 'LANGUAGE': 'en_US:en', 16:18:31 INFO - 'LOGNAME': 'cltbld', 16:18:31 INFO - 'MAIL': '/var/mail/cltbld', 16:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:31 INFO - 'MOZ_NO_REMOTE': '1', 16:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:31 INFO - 'NO_EM_RESTART': '1', 16:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:31 INFO - 'PWD': '/builds/slave/test', 16:18:31 INFO - 'SHELL': '/bin/bash', 16:18:31 INFO - 'SHLVL': '1', 16:18:31 INFO - 'TERM': 'linux', 16:18:31 INFO - 'TMOUT': '86400', 16:18:31 INFO - 'USER': 'cltbld', 16:18:31 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:31 INFO - '_': '/tools/buildbot/bin/python'} 16:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:31 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)) 16:18:31 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)) 16:18:31 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)) 16:18:31 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)) 16:18:31 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)) 16:18:31 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)) 16:18:31 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)) 16:18:31 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)) 16:18:31 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)) 16:18:31 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)) 16:18:31 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)) 16:18:31 INFO - Cleaning up... 16:18:31 INFO - Return code: 0 16:18:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x188c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ef8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, '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': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', '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 16:18:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:18:31 INFO - Using env: {'DISPLAY': ':0', 16:18:31 INFO - 'HOME': '/home/cltbld', 16:18:31 INFO - 'LANG': 'en_US.UTF-8', 16:18:31 INFO - 'LANGUAGE': 'en_US:en', 16:18:31 INFO - 'LOGNAME': 'cltbld', 16:18:31 INFO - 'MAIL': '/var/mail/cltbld', 16:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:31 INFO - 'MOZ_NO_REMOTE': '1', 16:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:31 INFO - 'NO_EM_RESTART': '1', 16:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:31 INFO - 'PWD': '/builds/slave/test', 16:18:31 INFO - 'SHELL': '/bin/bash', 16:18:31 INFO - 'SHLVL': '1', 16:18:31 INFO - 'TERM': 'linux', 16:18:31 INFO - 'TMOUT': '86400', 16:18:31 INFO - 'USER': 'cltbld', 16:18:31 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:31 INFO - '_': '/tools/buildbot/bin/python'} 16:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:18:31 INFO - Cleaning up... 16:18:31 INFO - Return code: 0 16:18:31 INFO - Running post-action listener: _resource_record_post_action 16:18:31 INFO - Running post-action listener: _start_resource_monitoring 16:18:31 INFO - Starting resource monitoring. 16:18:31 INFO - ##### 16:18:31 INFO - ##### Finished create-virtualenv step (success) 16:18:31 INFO - ##### 16:18:31 INFO - ##### 16:18:31 INFO - ##### Running install step. 16:18:31 INFO - ##### 16:18:31 INFO - Running pre-action listener: _resource_record_pre_action 16:18:31 INFO - Running main action method: install 16:18:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:32 INFO - Reading from file tmpfile_stdout 16:18:32 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:32 INFO - Reading from file tmpfile_stdout 16:18:32 INFO - Output received: 16:18:32 INFO - Usage: mozinstall [options] installer 16:18:32 INFO - Options: 16:18:32 INFO - -h, --help show this help message and exit 16:18:32 INFO - -d DEST, --destination=DEST 16:18:32 INFO - Directory to install application into. [default: 16:18:32 INFO - "/builds/slave/test"] 16:18:32 INFO - --app=APP Application being installed. [default: firefox] 16:18:32 INFO - mkdir: /builds/slave/test/build/application 16:18:32 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'] 16:18:32 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 16:18:49 INFO - Reading from file tmpfile_stdout 16:18:49 INFO - Output received: 16:18:49 INFO - /builds/slave/test/build/application/firefox/firefox 16:18:49 INFO - Running post-action listener: _resource_record_post_action 16:18:49 INFO - ##### 16:18:49 INFO - ##### Finished install step (success) 16:18:49 INFO - ##### 16:18:49 INFO - ##### 16:18:49 INFO - ##### Running run-tests step. 16:18:49 INFO - ##### 16:18:49 INFO - Running pre-action listener: _resource_record_pre_action 16:18:49 INFO - Running main action method: run_tests 16:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:49 INFO - Python 2.7.3 16:18:49 INFO - Return code: 0 16:18:49 INFO - grabbing minidump binary from tooltool 16:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:49 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 0x1b4b3b0>, '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 0x1b4c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:49 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 16:18:49 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 16:18:49 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 16:18:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:49 INFO - Return code: 0 16:18:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:18:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:18:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 16:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 16:18:49 INFO - Using env: {'DISPLAY': ':0', 16:18:49 INFO - 'HOME': '/home/cltbld', 16:18:49 INFO - 'LANG': 'en_US.UTF-8', 16:18:49 INFO - 'LANGUAGE': 'en_US:en', 16:18:49 INFO - 'LOGNAME': 'cltbld', 16:18:49 INFO - 'MAIL': '/var/mail/cltbld', 16:18:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:49 INFO - 'MOZ_NO_REMOTE': '1', 16:18:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:49 INFO - 'NO_EM_RESTART': '1', 16:18:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:49 INFO - 'PWD': '/builds/slave/test', 16:18:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:49 INFO - 'SHELL': '/bin/bash', 16:18:49 INFO - 'SHLVL': '1', 16:18:49 INFO - 'TERM': 'linux', 16:18:49 INFO - 'TMOUT': '86400', 16:18:49 INFO - 'USER': 'cltbld', 16:18:49 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1468970197.509159-2120899850', 16:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:49 INFO - '_': '/tools/buildbot/bin/python'} 16:18:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 16:18:49 INFO - mozversion application_buildid: 20160719133037 16:18:49 INFO - mozversion application_changeset: 1ebb6348564337c00d889bd858eedf3c922e68c0 16:18:49 INFO - mozversion application_display_name: Nightly 16:18:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:49 INFO - mozversion application_name: Firefox 16:18:49 INFO - mozversion application_remotingname: firefox 16:18:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:18:49 INFO - mozversion application_vendor: Mozilla 16:18:49 INFO - mozversion application_version: 50.0a1 16:18:49 INFO - mozversion platform_buildid: 20160719133037 16:18:49 INFO - mozversion platform_changeset: 1ebb6348564337c00d889bd858eedf3c922e68c0 16:18:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:18:49 INFO - mozversion platform_version: 50.0a1 16:18:49 INFO - using testdate: 1468970329 16:18:49 INFO - actual date: 1468970329 16:18:49 INFO - starting webserver on 'localhost:60984' 16:18:49 INFO - SUITE-START | Running 2 tests 16:18:49 INFO - TEST-START | dromaeo_css 16:18:49 INFO - operating with platform_type : linux_ 16:18:49 INFO - Initialising browser for dromaeo_css test... 16:18:49 INFO - TEST-INFO | started process 26193 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb1D8Aq/profile http://localhost:60984/getInfo.html) 16:18:54 INFO - PROCESS | 26193 | __metrics Screen width/height:1600/1200 16:18:54 INFO - PROCESS | 26193 | colorDepth:24 16:18:54 INFO - PROCESS | 26193 | Browser inner width/height: 1024/697 16:18:54 INFO - PROCESS | 26193 | __metrics 16:18:55 INFO - PROCESS | 26193 | 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" 16:18:55 INFO - PROCESS | 26193 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:18:56 INFO - TEST-INFO | 26193: exit 0 16:18:56 INFO - Browser initialized. 16:18:56 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:56 INFO - TEST-INFO | started process 26276 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb1D8Aq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:08 INFO - PROCESS | 26276 | RSS: Main: 157208576 16:19:08 INFO - PROCESS | 26276 | 16:19:58 INFO - PROCESS | 26276 | 1468970398968 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] 16:22:08 INFO - PROCESS | 26276 | Cycle 1(1): loaded http://localhost:60984/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60984/tests/dromaeo/cssquery-ext.html) 16:22:09 INFO - PROCESS | 26276 | RSS: Main: 291655680 16:22:09 INFO - PROCESS | 26276 | 16:25:11 INFO - PROCESS | 26276 | Cycle 1(1): loaded http://localhost:60984/tests/dromaeo/cssquery-ext.html (next: http://localhost:60984/tests/dromaeo/cssquery-jquery.html) 16:25:12 INFO - PROCESS | 26276 | RSS: Main: 243400704 16:25:12 INFO - PROCESS | 26276 | 16:28:14 INFO - PROCESS | 26276 | Cycle 1(1): loaded http://localhost:60984/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60984/tests/dromaeo/cssquery-mootools.html) 16:28:15 INFO - PROCESS | 26276 | RSS: Main: 219881472 16:28:15 INFO - PROCESS | 26276 | 16:31:17 INFO - PROCESS | 26276 | Cycle 1(1): loaded http://localhost:60984/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60984/tests/dromaeo/cssquery-prototype.html) 16:31:17 INFO - PROCESS | 26276 | RSS: Main: 183115776 16:31:17 INFO - PROCESS | 26276 | 16:34:20 INFO - PROCESS | 26276 | Cycle 1(1): loaded http://localhost:60984/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60984/tests/dromaeo/cssquery-yui.html) 16:34:21 INFO - PROCESS | 26276 | RSS: Main: 184639488 16:34:21 INFO - PROCESS | 26276 | 16:37:24 INFO - PROCESS | 26276 | Cycle 1(1): loaded http://localhost:60984/tests/dromaeo/cssquery-yui.html (next: http://localhost:60984/tests/dromaeo/cssquery-dojo.html) 16:37:24 INFO - PROCESS | 26276 | RSS: Main: 276598784 16:37:24 INFO - PROCESS | 26276 | 16:37:24 INFO - PROCESS | 26276 | __start_tp_report 16:37:24 INFO - PROCESS | 26276 | _x_x_mozilla_page_load 16:37:24 INFO - PROCESS | 26276 | _x_x_mozilla_page_load_details 16:37:24 INFO - PROCESS | 26276 | |i|pagename|runs| 16:37:24 INFO - PROCESS | 26276 | |0;dojo.html;3091.876324947002;3099.0547882895708;3119.9688003119963;3123.9062828115157;3135.7021082997107;5347.545458636011;5389.3802212745495;5402.189671549259;5481.068218402871;5510.834674959748;5394.838154855351;5456.454354564542;5462.781488740456;5478.123500239963;5526.171074338851;4875.293082503035;4958.132326842806;4961.057399094178;4982.626303027269;5107.336046313974;5035.1188542005;5046.621503387242;5067.417247016603;5081.237814327862;5083;4884.5848102911295;4920.6801557898625;4921.0157968406465;4979.775910084055;5050.090983622947;4882.7558622068755;4883.243097319921;4886.047220791947;4888.169011268093;4977.601791856679;4904.141775189363;4916.287138364953;4935.7532123393685;4951.232558953368;4957.801687932479;5306.469353064701;5399.83800485986;5433.972830135864;5448.37343705471;5454.018276710191;5845.853853653693;5866.9706651466895;5879.470847623692;5883.70581470929;5905.6751878646655;5954.732037058343;5957.702114894238;5966.373530779249;5975.551833612496;5975.671338076404;5351.3043304370185;5434.565234781247;5503.091989821714;5510.862228444321;5586.078297081018;6055.152278680988;6070.612267158524;6073.352704002548;6075.979706787087;6078.027515597571;5843.5325173985975;5844.211031510777;5844.795432159854;5847.181394604759;5847.415258474119;5678.914816277759;5713.8285851424525;5775.249217601685;5776.393478684761;5872.882542349129;5754.568407369464;5797.159411885337;5803.738831752581;5806.564507661942;5808.099744539603;5762.683052432073;5817.563682723728;5820.126980952891;5823.82528524145;5824.592278540462;4349.28236840924;4382.408374869416;4384.758838263862;4437.290033594674;4437.400950871655;3959.7822119783687;3979.9204015919518;3981.402789581586;3984.063745019918;3988.8205030773684;5440.4287549807495;5460.399356070829;5550.500454959073;5562.888742225214;5661.377248502661;5916.349201587822;5925.466707996301;5927.051671732502;5931.377205393372;5972.820815375546;5545.72271386429;5601.131824567198;5603.7758489659955;5603.971980140073;5609.747561359749;4945.7774400152075;5023.748812559357;5031.6729412588065;5066.847994560096;5082.415522214918;6044.395560443921;6048.243969503812;6052.243469566304;6053.394660534;6090.665013424305;5831.79588714393;5864.38423965486;5874.823755287348;5876.6180198286975;5881.911771323348;5808.448197421239;5996.970015149896;6028.367021462684;6029.969850150721;6033.034714445667;5808.651480911159;5811.622244554091;5818.650880947157;5820.70896455184;5875;5911.497522710446;5915.645061296336;5920.2895652521975;5922.703864806656;5924.585279030426;5621.269234999424;5665.915011274752;5703.657780533182;5727.284089488814;5774.9133762994425;5747.339056008613;5753.453421924911;5754.6547207167705;5757.5393968481585;5796.42035796417;5636.464535869086;5691.060974939089;5694.117411801178;5695.031844586348;5697.173909783141;5523.530499907552;5665.12190610456;5690.402507736793;5722.113072473773;5792.7103644818435;5635.408282130317;5641.351244606923;5641.943580564306;5650.152477128464;5681.9147712783515 16:37:24 INFO - PROCESS | 26276 | |1;ext.html;585.1340016775173;610.9327973922867;632.2349956552571;634.0742515926747;645.5126379583414;43777.34333984989;45365.546344536546;45414.999999999956;45490.77254613726;49738.50261497384;46897.76551117248;47424.762876185654;47463.67754257203;47469.7626511867;47526.48498392647;9069.276837400907;9207.217386522154;9223.308251881102;9227.677031303905;9236.21492173166;9064.229540489066;9169.816603667923;9175.541222938826;9175.816483670322;9178.311626627996;9172.266218702522;9200.907990920106;9204;9229.261659067291;9233.07669233078;9118.817623647523;9157.221636160933;9162.404443711137;9162.67930622425;9164.312676549253;9009.549522523914;9099.135582119688;9102.226310763594;9105.954470227607;9132.36073474857;43797;44214.11571768579;44222.33666494973;44304.556954430685;44305.77847110776;3562.991634411287;3638.745287829853;3648.069742215718;3669.3578623740736;3678.8160591970295;33325.33349333024;33616.15959601029;34071.82964085189;34123.829380852934;34142.65857341445;4100.36444351126;4124.463819703449;4170.70805043647;4213.683973701954;4256.808443620013;379204;381474;382931.9999999972;384595;385053;60962.695186523786;61365.69317153386;61470.38529614736;61506.384936150964;61532.69233653803;65155.674221628586;65326;65384;65411.34588654148;65450;17535;17752.55618609545;17760.023198723935;17761.289548417917;17795.19921603531;13816.861831381759;13874.098183618033;13876.375563099684;13895.305234738222;13905.513307034407;17645.240303441158;18511.722324165148;18546.629067418846;18615.906920465583;18764;6675.399214070757;6791.864162716718;6792.7962161134255;6867.862642747117;6935.237123916365;943.6225509796136;1023.2837014090018;1101.686019484443;1120.5573798349606;1145.5589598004663;33721.49417758736;33953.285482016334;34019.14952126167;34097.82951085229;34764.82617586896;989.6437282578266;1133.0765426177804;1146.134668325409;1207.3299318878107;1259.3829023778283;17738.201780919888;17793.644127117645;17875.195616197303;17893.642127157385;17995;487408;502165;502501;577319;581500;61085.38914610886;61348.38651613427;61381.38618613757;61392;61614.38385616176;1249.950001999928;1322.8610995845297;1355.749186400519;1386.5077897346498;1395.4697215058213;30366.052413186146;33651.66348336485;34912.65087349094;35403.29193416169;35519.82240088783;53906.46093539171;54836;54861;54864.45135548594;54907.45092549024;520840;524321;525759;526991;528923;33869.66130338665;36796.63203368039;44956.77521612502;47064.529354706014;47786.76106619445;49207;49332.75333623309;49518.75240623774;49601.75199123981;49686.25470618016;42111;42195.7890210547;43239.56760432355;44756;44777.32834007427;42170.1565968685;42363;42572.36141457818;42987.57012429961;43826;35957.460638090975;41435.027796664595;42421.575784241766;42482;44142;33784.66215337815;38388;38550.570243777875;38817.6118238814;39267.41098883462;28730.281742956595;29183.416331673674;29564.704352957055;29619;29657.555136672534 16:37:24 INFO - PROCESS | 26276 | |2;jquery.html;380.72207288679283;382.6020938224247;395.28848073467753;406.4188210858472;417.211470321093;13172.802548068146;19296.131674074666;20006.499837504052;20018.49953751155;20399.694004589943;19607.901960490177;19645.11596978136;19877;20834.479138021517;20836.8958155209;5944.464998150176;6110.725017374229;6120.081987701858;6120.449159575637;6135.938640613604;6006.639601623895;6052.213212282397;6055.333913269536;6056.06131049688;6066.181065556159;4263.829446822124;6321.557490975633;6576.736930522798;6616.966915165417;6717.63053032083;6605.669716514203;6633.800985970429;6669.966650166718;6675.632840193787;6677;5897.6166549174595;5898.203742494751;5903.26209223847;5906.084556893688;5906.734196961147;22616;22952.42618934523;23454.06183752648;23648.40878978022;23720.881395593085;2358.3750306168877;2488.0669748844184;2575.549278876189;2670.2790246633494;2947.410517896429;14835.961482696108;14846.70306593873;14883.627909302246;14912.552623421314;15066.849331506653;1936.74822273105;1973.6940774180025;1984.593158402524;2038.1337931379105;2070.823979961705;74823.25176748217;76293;76378;77790.61104694496;77961;10442.269041167045;10533.210009249337;10563;10598.0991615712;10998.505067271992;15179.468718594797;15192.544223673307;15193.924030379778;15196.696066078617;15237.542873713806;8019.198080191934;8491.448055876348;8519.701810436605;8615.91384086151;8703.303735701129;8303.169683031649;8312;8351.3736469765;8539.14608539141;8571.22858942698;6512.511561632897;6713.489464870463;6781.694823732944;6807.18313802337;6823.795286141423;1870.644577530292;1911.7713054795124;1932.4589115047809;1962.2347284559035;1978.5449346650064;949.9525023748785;972.9075737804898;976.2873102635116;1074.4198133008088;1081.1242893256488;20233.595328093357;20936.47658808542;21215.15139394438;21244.468888277916;21248;1001.2740762946951;1076.311160857052;1090.7164137324194;1094.9890501095047;1098.6923661374828;9566.808663826685;11926.262036329888;12144.69638259033;12148.271103733805;12151.817722734166;465093;473811;488136;491004;492740;80875;82705.58647206725;83323.5833820827;83331.5833420829;83693.58153209195;1001.3191030099457;1037.6731329631143;1063.1388575254068;1066.9786604267872;1070.8875568065396;17278.654426911642;17972.10139493046;18474.907625461787;18527.888326700442;18825.24699012079;39294.607053929096;39437.80281098576;39457.40813887736;40064.59935400609;41269.174616508106;52229.47770522398;52864.20703689525;53294;53569;53881.46118538921;7993.129740677568;8272.545010024507;8283.627236774239;8348.540830254395;8973.102689730975;11242.606508772154;13315.467381305027;13347.532836350681;13508.729825403634;13700.794488082885;11631.825522617337;11742.647720568397;11807.173497855068;11965.102617303563;11982.880171198525;6500.967495162494;11243.887561124284;11292.604758833402;11667.766644667228;11670.70823229426;11405.828912566483;11605.825912611486;11723.35521546323;11739.23694959825;11771.941140294244;8186.2632363087605;8330.750077497685;8382.874256886274;8425.199606037417;8593.140685931607;11238.719032024264;11378.658640240807;11470.483828227583;11587.188896777481;11609.652769529253 16:37:24 INFO - PROCESS | 26276 | |3;mootools.html;1268.2770820632243;1393.1989106263898;1462.510059130191;1582.6834633073388;1774.2193434888657;8142.185781421855;8504.064552899184;8538.103499132587;8745.038045814956;8755.430896991707;8559.828803423943;8636.784080397993;8675.47947123173;8682.088380720012;8700;2030.8375329973567;2268.6256767633404;2342.964855527164;2365.0894405653776;2367.869767162805;2351.2123438647986;2377.4888398994212;2378.512404956988;2380.238323736405;2383.0825132324094;2334.6964894563685;2358.5636657218383;2370.146747171017;2380.583397905368;2382.4639456122322;1976.2786582897138;2039.88780617066;2255.672927425522;2283.098176220392;2284.805791507724;2075.2529089527757;2234.530748542808;2307.411610039446;2313.005407674699;2333.8482998605036;8992.640294388217;9030.729078127666;9088.863667045;9124.680636177769;9128.452292862383;5164.251183578398;5241.659292146036;5288.365396152448;5302.098643230622;5309.761060752276;3223.4842425212087;3345.7992520448606;3395.2021275000357;3441.6214216436174;3454.2573346730323;1148.850649415579;1160.5937921727411;1163.2729544034978;1177.8939895409455;1973.8223559879536;12220.572279970247;12587.74824503514;12620.55828046014;12625.05312101596;12632.936835315857;4319.114581510783;4607.170709272363;4660.673752837337;4677.438707355139;4680.672352935262;9960.352577082467;9978.501074946224;9981;9982.151517120981;9998.350107243006;5305.522502974673;5308.814191503279;5310.6813591184655;5312.282841816383;5328.973355133199;4287.506936702319;4753.928691069636;4878.0975519529;5005.099082165245;5005.1491246488185;3846.480640147126;3874.283257597297;3963.821628026688;4009.298372784751;4011.3782363733667;1128.2666266926399;1128.949197286124;1134.8921852424007;1157.4907040901976;1185.3539820797687;835.807764214222;957.8218790887248;972.9318947673595;980.127686359141;1004.6031817432073;5921.61509501881;6067.059605761114;6096.268447786294;6147.139400483936;6256.405641464054;469.1321056046287;469.9718016918996;472.33165071424236;478.94492133405106;479.42469037154854;4341.196878577473;4371.322445021026;4390.05613793036;4471.3740076388685;4492.752898590544;10850.728731781768;10962;11436.485358158903;11444.484998175101;11480.483378247998;11202.887971120184;11493.367864767519;11513.251638643462;11537;11537.250078744855;8024.799380015546;8133.820596013545;8152.673893044331;8174.632141553644;8193;6323.494120470444;6338.397852204033;6546.345365463606;6554.8361290966195;6604.966975165094;10220.693379198636;10439.321444106108;10452.268341216042;10532.157427405942;10547.841782373118;10633.468326583485;10697.465126743477;10752.709674839069;10771.946140269249;10774.24580279373;8078.919210807817;8213.219744124299;8275;8318.833623327379;8320.375971802017;8786.253168480494;8800.075992020746;8886.644534218818;8890.555472226233;8900.332475064526;7945.0863150738405;7967.641456134372;8036.799080023046;8062.838743224986;8094.231048050426;7594.126675432396;7621.35218506411;7675;7703.88444173349;7879.054513458422;6838.418734407631;6907.309269073254;6933.5493192942295;6937.098177237129;7033;5333.466653334791;5386.48828361305;5387.434319396407;5391.541718953933;5553.0004599172535;5600.103983362643;5622.915656265078;5645.604807663424;5743.684097374685;5750.597458178055 16:37:24 INFO - PROCESS | 26276 | |4;prototype.html;328.7222297158901;329.30188001436954;335.50345488676766;343.1164750766775;344.5331575714908;5169.767360468779;5217.47825217478;5223.242629818674;5227.712475813829;5262.500437209252;5162.22566615007;5166.689998600077;5176.741162941857;5190.480951904808;5199.818006369778;3861.420786881969;3938.8227529761084;4063.6139566741113;4079.551249362568;4092.6930480213955;4088.9386659200136;4106.815193316308;4107.507099148106;4111.60939710727;4119.567445418233;4082.81627326771;4083.632473077422;4087.713860029799;4090.7750073745933;4091.672666186698;3880.4373365861934;3889.722338358632;4065.2072845795083;4082.265192265391;4092.2224777292217;3620.438831981021;3628.8548458061646;3630.2739452109686;3634.9273014539826;3643.3806252937065;5032.295478632995;5036;5042.495750424965;5050.343455350818;5051.621128415347;4445.199864024475;4633.212353899811;4645.396098507173;4657.464391594976;4679.110968915929;5399.461153571214;5403.243545903577;5403.378986304093;5404.594655400861;5416.295881535415;3808.847646094153;3963.6829053675638;3992.441058251847;3992.6207010333974;3992.6606238469767;5604.719764011783;5606.6636001840025;5623.9437605623825;5643.181738647914;5646.294213223306;4017.5580686124504;4027.496562929634;4031.588943869681;4034.4755181826176;4038.495188101487;4082.9591704082577;4090.0388408723925;4094.263032654091;4094.324436468009;4096.754194748325;4366.19225443294;4601.562851529099;4634.814607415733;4635.351050852883;4640.141573808789;5435.809746658927;5441.047816632074;5444.40111587725;5447.673139611636;5457.290552228185;1389.9652508687363;1398.9160650361011;1406.1773862290675;1408.7112142010862;1409.9506517271845;887.1173182683272;902.8826252587253;908.3777612335572;908.5230254116642;916.0610374366195;5089.847304580869;5184.403793563713;5214.217867319857;5305.336832895888;5311.521963023347;5559.33288005442;5564.860878478071;5577.9163312550345;5585.441455854463;5598.496135347838;5154.92267615986;5230.712310822942;5289.947100528945;5298.364196296392;5299.788008479618;2889.971100289012;2944.2933695912825;2944.6466424028827;2944.9116526503813;2949.9822561216347;5688.971555142198;5689.800856969986;5699.686517241592;5721.511142652959;5742.54059675225;5464.9180262296095;5650.632708873993;5651.2935883014625;5685.488306052475;5686.947914635805;5654.830355089354;5690.374058853523;5691.373948865621;5693.715314234355;5694.658320500783;5292.814751483679;5482.533984611353;5535.892821435648;5560.4161563035295;5568.969740598002;5507.394186639386;5508.256385387921;5526.253955715927;5530.898258661683;5559.694216818114;5733.197352370588;5737.885242295214;5742.339630942495;5753.309602847685;5758.884822303614;3940.862069827542;4024;4038.051058001309;4059.2287465381482;4060.289449346412;5566.332040155045;5588.41321661236;5602.52378547828;5623.409541998195;5637.238972738792;5396.4333744957785;5460.945390546044;5474.343078830566;5481.808136715196;5499.367572729027;5249.868753281198;5436.565074794086;5453.563714902719;5453.781848726166;5466.617336786387;5395.24466574672;5395.649282796606;5411.350637923474;5411.377691565521;5414.945850541444;5244.895102098013;5322.38792538863;5388.137897936312;5399.325084364455;5498.147787093007;4876.585790120967;5337.919931201112;5340.172273297645;5358.6248962572245;5372.650777699437 16:37:24 INFO - PROCESS | 26276 | |5;yui.html;756.6141267953344;820.5568992743919;828.7703108136212;838.8825564420982;868.9217970382668;15133;15751.133687647174;16019.439319623816;16025.198740063008;16036.59908502288;15345.462908798167;15377.154256515887;15500.302486388111;15512.84487155128;15513.612159696013;675.3347952267037;817.3134566963764;832.7168762620707;837.3426859914968;837.5728378526934;781.1563511407694;839.1063517354029;842.061101871412;846.7417437681505;847.4745657692208;874.2656168818191;875.155474966657;875.4222213339198;875.5797217335682;876.2989608313355;690.378659206713;864.6195673903462;866.9263112635433;868.3313848336777;868.9695860644879;595.4194660206264;633.1958412815699;634.9492040636784;638.865838173984;639.6897504710212;15202.771958420633;15279.312430940523;15291.847081529153;15319.846801531954;15325.31036103378;14079.859201407957;14127.364268607937;14380.85619143816;14664.853351466456;14877;13171.538996135185;13179.67050823737;13233.933830330883;13395.397207125605;13504.257265850374;4426.490953540352;4440.711353761995;4556.997460558672;4570.977145114286;4708.340832283483;71003;71038.64480677615;75242.24757752461;76546.23453765502;76934.61532692301;11473.942630286796;11721.765564688658;11729.228867500447;11808.645740627795;11810.52757889692;21500.24749133804;21535.892320538296;21563;21622.675659865115;21627.2430464933;7842.411819113589;7880.802979925433;8015.238552337519;8054.23484768946;8072.071711753224;4388.5830846069575;4588.472325682523;4857.247126695368;4903.068417000758;5093.337866077386;3372.8144952027383;3491.720662347006;3491.790492570403;3492.650734926487;3494.4408894577255;583.2301362201938;588.1442501160801;588.4233451217831;596.832886791699;604.8518113062328;658.6904155047121;661.7989438956909;676.5737585321216;706.20199174932;710.3784188834769;9686.564104615309;9767.365121267096;9812;9820.508974551243;9922.950385248028;557.4035781713609;592.1946153231602;621.2141640824361;664.3389827121948;690.526989012528;4448.310511870665;4449.377087207794;4563.703359281636;4581.541845815392;4663.253879379283;70752.29247707457;77202;110576;110611;110628;58724;92614.53692731493;97039.51480242553;100009;100526.48683782818;90208;90683.54658226666;91641.54179229194;92623.53688231515;94946.5252673746;8493.193146651058;8531.914680853111;8579.742607721779;8790.978389468786;8953.774664493678;54035.729821350644;54090.18864716954;54878.1585289026;55404.44595553993;55931.16103258373;72436.63781681057;78544.60727696324;78924.60537697274;79774;80124.59937700274;11080.445977701243;11549.538018479167;11589.24669896454;11735.706607334885;11778.587749428729;12881.484740610646;13345.733085338434;13384.531541396005;13605;13731.862681373457;11779.293242405483;12138.393080345772;12216.938915305722;12844.357782111045;12972.545960891324;12653.430595623404;12740.279380745606;13016.414261358452;13096.410661520064;13546.254955977516;12367.505299787908;12627.74744505123;12773.808392873929;12860.164089334163;13157.53948611794;9794.21646268311;10047.497625118569;10126.746831329276;10209.183265338606;10417.427041512487;9679.903200968181;10228.437435941094;10522.579096836347;10660.25378223517;10682.679519614425 16:37:24 INFO - PROCESS | 26276 | __end_tp_report 16:37:24 INFO - PROCESS | 26276 | __start_cc_report 16:37:24 INFO - PROCESS | 26276 | _x_x_mozilla_cycle_collect,1185 16:37:24 INFO - PROCESS | 26276 | __end_cc_report 16:37:24 INFO - PROCESS | 26276 | __startTimestamp1468971444366__endTimestamp 16:37:24 INFO - PROCESS | 26276 | 16:37:24 INFO - PROCESS | 26276 | ------- Summary: start ------- 16:37:24 INFO - PROCESS | 26276 | Number of tests: 6 16:37:24 INFO - PROCESS | 26276 | 16:37:24 INFO - PROCESS | 26276 | [#0] dojo.html Cycles:165 Average:5437.38 Median:5665.12 stddev:635.37 (11.2%) stddev-sans-first:610.10 16:37:24 INFO - PROCESS | 26276 | Values: 3091.9 3099.1 3120.0 3123.9 3135.7 5347.5 5389.4 5402.2 5481.1 5510.8 5394.8 5456.5 5462.8 5478.1 5526.2 4875.3 4958.1 4961.1 4982.6 5107.3 5035.1 5046.6 5067.4 5081.2 5083.0 4884.6 4920.7 4921.0 4979.8 5050.1 4882.8 4883.2 4886.0 4888.2 4977.6 4904.1 4916.3 4935.8 4951.2 4957.8 5306.5 5399.8 5434.0 5448.4 5454.0 5845.9 5867.0 5879.5 5883.7 5905.7 5954.7 5957.7 5966.4 5975.6 5975.7 5351.3 5434.6 5503.1 5510.9 5586.1 6055.2 6070.6 6073.4 6076.0 6078.0 5843.5 5844.2 5844.8 5847.2 5847.4 5678.9 5713.8 5775.2 5776.4 5872.9 5754.6 5797.2 5803.7 5806.6 5808.1 5762.7 5817.6 5820.1 5823.8 5824.6 4349.3 4382.4 4384.8 4437.3 4437.4 3959.8 3979.9 3981.4 3984.1 3988.8 5440.4 5460.4 5550.5 5562.9 5661.4 5916.3 5925.5 5927.1 5931.4 5972.8 5545.7 5601.1 5603.8 5604.0 5609.7 4945.8 5023.7 5031.7 5066.8 5082.4 6044.4 6048.2 6052.2 6053.4 6090.7 5831.8 5864.4 5874.8 5876.6 5881.9 5808.4 5997.0 6028.4 6030.0 6033.0 5808.7 5811.6 5818.7 5820.7 5875.0 5911.5 5915.6 5920.3 5922.7 5924.6 5621.3 5665.9 5703.7 5727.3 5774.9 5747.3 5753.5 5754.7 5757.5 5796.4 5636.5 5691.1 5694.1 5695.0 5697.2 5523.5 5665.1 5690.4 5722.1 5792.7 5635.4 5641.4 5641.9 5650.2 5681.9 16:37:24 INFO - PROCESS | 26276 | 16:37:24 INFO - PROCESS | 26276 | [#1] ext.html Cycles:180 Average:64972.33 Median:44218.23 stddev:128530.35 (290.7%) stddev-sans-first:128800.00 16:37:24 INFO - PROCESS | 26276 | Values: 585.1 610.9 632.2 634.1 645.5 43777.3 45365.5 45415.0 45490.8 49738.5 46897.8 47424.8 47463.7 47469.8 47526.5 9069.3 9207.2 9223.3 9227.7 9236.2 9064.2 9169.8 9175.5 9175.8 9178.3 9172.3 9200.9 9204.0 9229.3 9233.1 9118.8 9157.2 9162.4 9162.7 9164.3 9009.5 9099.1 9102.2 9106.0 9132.4 43797.0 44214.1 44222.3 44304.6 44305.8 3563.0 3638.7 3648.1 3669.4 3678.8 33325.3 33616.2 34071.8 34123.8 34142.7 4100.4 4124.5 4170.7 4213.7 4256.8 379204.0 381474.0 382932.0 384595.0 385053.0 60962.7 61365.7 61470.4 61506.4 61532.7 65155.7 65326.0 65384.0 65411.3 65450.0 17535.0 17752.6 17760.0 17761.3 17795.2 13816.9 13874.1 13876.4 13895.3 13905.5 17645.2 18511.7 18546.6 18615.9 18764.0 6675.4 6791.9 6792.8 6867.9 6935.2 943.6 1023.3 1101.7 1120.6 1145.6 33721.5 33953.3 34019.1 34097.8 34764.8 989.6 1133.1 1146.1 1207.3 1259.4 17738.2 17793.6 17875.2 17893.6 17995.0 487408.0 502165.0 502501.0 577319.0 581500.0 61085.4 61348.4 61381.4 61392.0 61614.4 1250.0 1322.9 1355.7 1386.5 1395.5 30366.1 33651.7 34912.7 35403.3 35519.8 53906.5 54836.0 54861.0 54864.5 54907.5 520840.0 524321.0 525759.0 526991.0 528923.0 33869.7 36796.6 44956.8 47064.5 47786.8 49207.0 49332.8 49518.8 49601.8 49686.3 42111.0 42195.8 43239.6 44756.0 44777.3 42170.2 42363.0 42572.4 42987.6 43826.0 35957.5 41435.0 42421.6 42482.0 44142.0 33784.7 38388.0 38550.6 38817.6 39267.4 28730.3 29183.4 29564.7 29619.0 29657.6 16:37:24 INFO - PROCESS | 26276 | 16:37:24 INFO - PROCESS | 26276 | [#2] jquery.html Cycles:180 Average:28551.48 Median:13104.47 stddev:79171.74 (604.2%) stddev-sans-first:79365.58 16:37:24 INFO - PROCESS | 26276 | Values: 380.7 382.6 395.3 406.4 417.2 13172.8 19296.1 20006.5 20018.5 20399.7 19607.9 19645.1 19877.0 20834.5 20836.9 5944.5 6110.7 6120.1 6120.4 6135.9 6006.6 6052.2 6055.3 6056.1 6066.2 4263.8 6321.6 6576.7 6617.0 6717.6 6605.7 6633.8 6670.0 6675.6 6677.0 5897.6 5898.2 5903.3 5906.1 5906.7 22616.0 22952.4 23454.1 23648.4 23720.9 2358.4 2488.1 2575.5 2670.3 2947.4 14836.0 14846.7 14883.6 14912.6 15066.8 1936.7 1973.7 1984.6 2038.1 2070.8 74823.3 76293.0 76378.0 77790.6 77961.0 10442.3 10533.2 10563.0 10598.1 10998.5 15179.5 15192.5 15193.9 15196.7 15237.5 8019.2 8491.4 8519.7 8615.9 8703.3 8303.2 8312.0 8351.4 8539.1 8571.2 6512.5 6713.5 6781.7 6807.2 6823.8 1870.6 1911.8 1932.5 1962.2 1978.5 950.0 972.9 976.3 1074.4 1081.1 20233.6 20936.5 21215.2 21244.5 21248.0 1001.3 1076.3 1090.7 1095.0 1098.7 9566.8 11926.3 12144.7 12148.3 12151.8 465093.0 473811.0 488136.0 491004.0 492740.0 80875.0 82705.6 83323.6 83331.6 83693.6 1001.3 1037.7 1063.1 1067.0 1070.9 17278.7 17972.1 18474.9 18527.9 18825.2 39294.6 39437.8 39457.4 40064.6 41269.2 52229.5 52864.2 53294.0 53569.0 53881.5 7993.1 8272.5 8283.6 8348.5 8973.1 11242.6 13315.5 13347.5 13508.7 13700.8 11631.8 11742.6 11807.2 11965.1 11982.9 6501.0 11243.9 11292.6 11667.8 11670.7 11405.8 11605.8 11723.4 11739.2 11771.9 8186.3 8330.8 8382.9 8425.2 8593.1 11238.7 11378.7 11470.5 11587.2 11609.7 16:37:24 INFO - PROCESS | 26276 | 16:37:24 INFO - PROCESS | 26276 | [#3] mootools.html Cycles:180 Average:5907.44 Median:2616.44 stddev:3408.84 (130.3%) stddev-sans-first:3400.57 16:37:24 INFO - PROCESS | 26276 | Values: 1268.3 1393.2 1462.5 1582.7 1774.2 8142.2 8504.1 8538.1 8745.0 8755.4 8559.8 8636.8 8675.5 8682.1 8700.0 2030.8 2268.6 2343.0 2365.1 2367.9 2351.2 2377.5 2378.5 2380.2 2383.1 2334.7 2358.6 2370.1 2380.6 2382.5 1976.3 2039.9 2255.7 2283.1 2284.8 2075.3 2234.5 2307.4 2313.0 2333.8 8992.6 9030.7 9088.9 9124.7 9128.5 5164.3 5241.7 5288.4 5302.1 5309.8 3223.5 3345.8 3395.2 3441.6 3454.3 1148.9 1160.6 1163.3 1177.9 1973.8 12220.6 12587.7 12620.6 12625.1 12632.9 4319.1 4607.2 4660.7 4677.4 4680.7 9960.4 9978.5 9981.0 9982.2 9998.4 5305.5 5308.8 5310.7 5312.3 5329.0 4287.5 4753.9 4878.1 5005.1 5005.1 3846.5 3874.3 3963.8 4009.3 4011.4 1128.3 1128.9 1134.9 1157.5 1185.4 835.8 957.8 972.9 980.1 1004.6 5921.6 6067.1 6096.3 6147.1 6256.4 469.1 470.0 472.3 478.9 479.4 4341.2 4371.3 4390.1 4471.4 4492.8 10850.7 10962.0 11436.5 11444.5 11480.5 11202.9 11493.4 11513.3 11537.0 11537.3 8024.8 8133.8 8152.7 8174.6 8193.0 6323.5 6338.4 6546.3 6554.8 6605.0 10220.7 10439.3 10452.3 10532.2 10547.8 10633.5 10697.5 10752.7 10771.9 10774.2 8078.9 8213.2 8275.0 8318.8 8320.4 8786.3 8800.1 8886.6 8890.6 8900.3 7945.1 7967.6 8036.8 8062.8 8094.2 7594.1 7621.4 7675.0 7703.9 7879.1 6838.4 6907.3 6933.5 6937.1 7033.0 5333.5 5386.5 5387.4 5391.5 5553.0 5600.1 5622.9 5645.6 5743.7 5750.6 16:37:24 INFO - PROCESS | 26276 | 16:37:24 INFO - PROCESS | 26276 | [#4] prototype.html Cycles:180 Average:4582.23 Median:5247.38 stddev:1337.49 (25.5%) stddev-sans-first:1302.58 16:37:24 INFO - PROCESS | 26276 | Values: 328.7 329.3 335.5 343.1 344.5 5169.8 5217.5 5223.2 5227.7 5262.5 5162.2 5166.7 5176.7 5190.5 5199.8 3861.4 3938.8 4063.6 4079.6 4092.7 4088.9 4106.8 4107.5 4111.6 4119.6 4082.8 4083.6 4087.7 4090.8 4091.7 3880.4 3889.7 4065.2 4082.3 4092.2 3620.4 3628.9 3630.3 3634.9 3643.4 5032.3 5036.0 5042.5 5050.3 5051.6 4445.2 4633.2 4645.4 4657.5 4679.1 5399.5 5403.2 5403.4 5404.6 5416.3 3808.8 3963.7 3992.4 3992.6 3992.7 5604.7 5606.7 5623.9 5643.2 5646.3 4017.6 4027.5 4031.6 4034.5 4038.5 4083.0 4090.0 4094.3 4094.3 4096.8 4366.2 4601.6 4634.8 4635.4 4640.1 5435.8 5441.0 5444.4 5447.7 5457.3 1390.0 1398.9 1406.2 1408.7 1410.0 887.1 902.9 908.4 908.5 916.1 5089.8 5184.4 5214.2 5305.3 5311.5 5559.3 5564.9 5577.9 5585.4 5598.5 5154.9 5230.7 5289.9 5298.4 5299.8 2890.0 2944.3 2944.6 2944.9 2950.0 5689.0 5689.8 5699.7 5721.5 5742.5 5464.9 5650.6 5651.3 5685.5 5686.9 5654.8 5690.4 5691.4 5693.7 5694.7 5292.8 5482.5 5535.9 5560.4 5569.0 5507.4 5508.3 5526.3 5530.9 5559.7 5733.2 5737.9 5742.3 5753.3 5758.9 3940.9 4024.0 4038.1 4059.2 4060.3 5566.3 5588.4 5602.5 5623.4 5637.2 5396.4 5460.9 5474.3 5481.8 5499.4 5249.9 5436.6 5453.6 5453.8 5466.6 5395.2 5395.6 5411.4 5411.4 5414.9 5244.9 5322.4 5388.1 5399.3 5498.1 4876.6 5337.9 5340.2 5358.6 5372.7 16:37:24 INFO - PROCESS | 26276 | 16:37:24 INFO - PROCESS | 26276 | [#5] yui.html Cycles:180 Average:20174.64 Median:4880.16 stddev:28607.07 (586.2%) stddev-sans-first:28650.16 16:37:24 INFO - PROCESS | 26276 | Values: 756.6 820.6 828.8 838.9 868.9 15133.0 15751.1 16019.4 16025.2 16036.6 15345.5 15377.2 15500.3 15512.8 15513.6 675.3 817.3 832.7 837.3 837.6 781.2 839.1 842.1 846.7 847.5 874.3 875.2 875.4 875.6 876.3 690.4 864.6 866.9 868.3 869.0 595.4 633.2 634.9 638.9 639.7 15202.8 15279.3 15291.8 15319.8 15325.3 14079.9 14127.4 14380.9 14664.9 14877.0 13171.5 13179.7 13233.9 13395.4 13504.3 4426.5 4440.7 4557.0 4571.0 4708.3 71003.0 71038.6 75242.2 76546.2 76934.6 11473.9 11721.8 11729.2 11808.6 11810.5 21500.2 21535.9 21563.0 21622.7 21627.2 7842.4 7880.8 8015.2 8054.2 8072.1 4388.6 4588.5 4857.2 4903.1 5093.3 3372.8 3491.7 3491.8 3492.7 3494.4 583.2 588.1 588.4 596.8 604.9 658.7 661.8 676.6 706.2 710.4 9686.6 9767.4 9812.0 9820.5 9923.0 557.4 592.2 621.2 664.3 690.5 4448.3 4449.4 4563.7 4581.5 4663.3 70752.3 77202.0 110576.0 110611.0 110628.0 58724.0 92614.5 97039.5 100009.0 100526.5 90208.0 90683.5 91641.5 92623.5 94946.5 8493.2 8531.9 8579.7 8791.0 8953.8 54035.7 54090.2 54878.2 55404.4 55931.2 72436.6 78544.6 78924.6 79774.0 80124.6 11080.4 11549.5 11589.2 11735.7 11778.6 12881.5 13345.7 13384.5 13605.0 13731.9 11779.3 12138.4 12216.9 12844.4 12972.5 12653.4 12740.3 13016.4 13096.4 13546.3 12367.5 12627.7 12773.8 12860.2 13157.5 9794.2 10047.5 10126.7 10209.2 10417.4 9679.9 10228.4 10522.6 10660.3 10682.7 16:37:24 INFO - PROCESS | 26276 | -------- Summary: end -------- 16:37:24 INFO - PROCESS | 26276 | 16:37:25 INFO - TEST-INFO | 26276: exit 0 16:37:25 INFO - TEST-OK | dromaeo_css | took 1115336ms 16:37:25 INFO - TEST-START | kraken 16:37:25 INFO - operating with platform_type : linux_ 16:37:25 INFO - Initialising browser for kraken test... 16:37:25 INFO - TEST-INFO | started process 26468 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmZUQsl/profile http://localhost:60984/getInfo.html) 16:37:29 INFO - PROCESS | 26468 | __metrics Screen width/height:1600/1200 16:37:29 INFO - PROCESS | 26468 | colorDepth:24 16:37:29 INFO - PROCESS | 26468 | Browser inner width/height: 1024/697 16:37:29 INFO - PROCESS | 26468 | __metrics 16:37:30 INFO - PROCESS | 26468 | 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" 16:37:30 INFO - PROCESS | 26468 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:37:31 INFO - PROCESS | 26468 | console.error: PushService: 16:37:31 INFO - PROCESS | 26468 | stateChangeProcessEnqueue: Error transitioning state 16:37:31 INFO - PROCESS | 26468 | UnknownError 16:37:31 INFO - TEST-INFO | 26468: exit 0 16:37:31 INFO - Browser initialized. 16:37:31 INFO - Running cycle 1/1 for kraken test... 16:37:31 INFO - TEST-INFO | started process 26550 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmZUQsl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:43 INFO - PROCESS | 26550 | RSS: Main: 157057024 16:37:43 INFO - PROCESS | 26550 | 16:37:45 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60984/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:46 INFO - PROCESS | 26550 | RSS: Main: 213803008 16:37:46 INFO - PROCESS | 26550 | 16:37:49 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60984/tests/kraken/driver.html?testName=audio-dft) 16:37:49 INFO - PROCESS | 26550 | RSS: Main: 192245760 16:37:49 INFO - PROCESS | 26550 | 16:37:52 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60984/tests/kraken/driver.html?testName=audio-fft) 16:37:53 INFO - PROCESS | 26550 | RSS: Main: 270049280 16:37:53 INFO - PROCESS | 26550 | 16:37:55 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60984/tests/kraken/driver.html?testName=audio-oscillator) 16:37:56 INFO - PROCESS | 26550 | RSS: Main: 180248576 16:37:56 INFO - PROCESS | 26550 | 16:37:58 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60984/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:37:58 INFO - PROCESS | 26550 | RSS: Main: 184688640 16:37:58 INFO - PROCESS | 26550 | 16:38:01 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60984/tests/kraken/driver.html?testName=imaging-darkroom) 16:38:02 INFO - PROCESS | 26550 | RSS: Main: 221728768 16:38:02 INFO - PROCESS | 26550 | 16:38:05 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60984/tests/kraken/driver.html?testName=imaging-desaturate) 16:38:06 INFO - PROCESS | 26550 | RSS: Main: 220876800 16:38:06 INFO - PROCESS | 26550 | 16:38:09 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60984/tests/kraken/driver.html?testName=json-parse-financial) 16:38:09 INFO - PROCESS | 26550 | RSS: Main: 219803648 16:38:09 INFO - PROCESS | 26550 | 16:38:11 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60984/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:38:11 INFO - PROCESS | 26550 | RSS: Main: 185016320 16:38:11 INFO - PROCESS | 26550 | 16:38:13 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60984/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:38:14 INFO - PROCESS | 26550 | RSS: Main: 190107648 16:38:14 INFO - PROCESS | 26550 | 16:38:16 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60984/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:17 INFO - PROCESS | 26550 | RSS: Main: 195330048 16:38:17 INFO - PROCESS | 26550 | 16:38:19 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60984/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:20 INFO - PROCESS | 26550 | RSS: Main: 189739008 16:38:20 INFO - PROCESS | 26550 | 16:38:22 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60984/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:23 INFO - PROCESS | 26550 | RSS: Main: 192991232 16:38:23 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | Cycle 1(1): loaded http://localhost:60984/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60984/tests/kraken/driver.html?testName=ai-astar) 16:38:25 INFO - PROCESS | 26550 | RSS: Main: 244195328 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | __start_tp_report 16:38:25 INFO - PROCESS | 26550 | _x_x_mozilla_page_load 16:38:25 INFO - PROCESS | 26550 | _x_x_mozilla_page_load_details 16:38:25 INFO - PROCESS | 26550 | |i|pagename|runs| 16:38:25 INFO - PROCESS | 26550 | |0;ai-astar;103;110;110;111;111;111;111;110;112;112 16:38:25 INFO - PROCESS | 26550 | |1;audio-beat-detection;146;117;122;169;118;119;150;117;154;118 16:38:25 INFO - PROCESS | 26550 | |2;audio-dft;161;161;159;160;160;160;160;161;162;160 16:38:25 INFO - PROCESS | 26550 | |3;audio-fft;74;73;73;123;75;75;74;74;100;75 16:38:25 INFO - PROCESS | 26550 | |4;audio-oscillator;84;83;119;84;83;84;120;83;84;137 16:38:25 INFO - PROCESS | 26550 | |5;imaging-gaussian-blur;118;117;117;117;119;118;118;116;117;117 16:38:25 INFO - PROCESS | 26550 | |6;imaging-darkroom;145;147;147;147;146;146;146;146;145;146 16:38:25 INFO - PROCESS | 26550 | |7;imaging-desaturate;95;95;95;95;95;95;94;96;95;95 16:38:25 INFO - PROCESS | 26550 | |8;json-parse-financial;51;50;50;49;56;51;51;50;50;49 16:38:25 INFO - PROCESS | 26550 | |9;json-stringify-tinderbox;62;62;62;62;63;61;64;62;62;62 16:38:25 INFO - PROCESS | 26550 | |10;stanford-crypto-aes;76;75;74;85;82;75;79;80;75;75 16:38:25 INFO - PROCESS | 26550 | |11;stanford-crypto-ccm;122;119;119;119;125;117;122;131;155;119 16:38:25 INFO - PROCESS | 26550 | |12;stanford-crypto-pbkdf2;158;153;155;155;154;154;153;154;154;158 16:38:25 INFO - PROCESS | 26550 | |13;stanford-crypto-sha256-iterative;64;63;64;64;65;70;64;62;63;63 16:38:25 INFO - PROCESS | 26550 | __end_tp_report 16:38:25 INFO - PROCESS | 26550 | __start_cc_report 16:38:25 INFO - PROCESS | 26550 | _x_x_mozilla_cycle_collect,1700 16:38:25 INFO - PROCESS | 26550 | __end_cc_report 16:38:25 INFO - PROCESS | 26550 | __startTimestamp1468971505250__endTimestamp 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | ------- Summary: start ------- 16:38:25 INFO - PROCESS | 26550 | Number of tests: 14 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.60 (2.3%) stddev-sans-first:0.78 16:38:25 INFO - PROCESS | 26550 | Values: 103.0 110.0 110.0 111.0 111.0 111.0 111.0 110.0 112.0 112.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:134.00 stddev:19.65 (14.7%) stddev-sans-first:20.27 16:38:25 INFO - PROCESS | 26550 | Values: 146.0 117.0 122.0 169.0 118.0 119.0 150.0 117.0 154.0 118.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 16:38:25 INFO - PROCESS | 26550 | Values: 161.0 161.0 159.0 160.0 160.0 160.0 160.0 161.0 162.0 160.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#3] audio-fft Cycles:10 Average:81.60 Median:74.00 stddev:16.68 (22.5%) stddev-sans-first:17.47 16:38:25 INFO - PROCESS | 26550 | Values: 74.0 73.0 73.0 123.0 75.0 75.0 74.0 74.0 100.0 75.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:83.50 stddev:20.73 (24.8%) stddev-sans-first:21.52 16:38:25 INFO - PROCESS | 26550 | Values: 84.0 83.0 119.0 84.0 83.0 84.0 120.0 83.0 84.0 137.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.84 (0.7%) stddev-sans-first:0.87 16:38:25 INFO - PROCESS | 26550 | Values: 118.0 117.0 117.0 117.0 119.0 118.0 118.0 116.0 117.0 117.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 16:38:25 INFO - PROCESS | 26550 | Values: 145.0 147.0 147.0 147.0 146.0 146.0 146.0 146.0 145.0 146.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 16:38:25 INFO - PROCESS | 26550 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 96.0 95.0 95.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:50.50 stddev:2.00 (4.0%) stddev-sans-first:2.12 16:38:25 INFO - PROCESS | 26550 | Values: 51.0 50.0 50.0 49.0 56.0 51.0 51.0 50.0 50.0 49.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.79 (1.3%) stddev-sans-first:0.83 16:38:25 INFO - PROCESS | 26550 | Values: 62.0 62.0 62.0 62.0 63.0 61.0 64.0 62.0 62.0 62.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:77.50 stddev:3.72 (4.8%) stddev-sans-first:3.90 16:38:25 INFO - PROCESS | 26550 | Values: 76.0 75.0 74.0 85.0 82.0 75.0 79.0 80.0 75.0 75.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#11] stanford-crypto-ccm Cycles:10 Average:124.80 Median:122.00 stddev:11.36 (9.3%) stddev-sans-first:12.00 16:38:25 INFO - PROCESS | 26550 | Values: 122.0 119.0 119.0 119.0 125.0 117.0 122.0 131.0 155.0 119.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.80 Median:154.50 stddev:1.81 (1.2%) stddev-sans-first:1.51 16:38:25 INFO - PROCESS | 26550 | Values: 158.0 153.0 155.0 155.0 154.0 154.0 153.0 154.0 154.0 158.0 16:38:25 INFO - PROCESS | 26550 | 16:38:25 INFO - PROCESS | 26550 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:2.20 (3.4%) stddev-sans-first:2.33 16:38:25 INFO - PROCESS | 26550 | Values: 64.0 63.0 64.0 64.0 65.0 70.0 64.0 62.0 63.0 63.0 16:38:25 INFO - PROCESS | 26550 | -------- Summary: end -------- 16:38:25 INFO - PROCESS | 26550 | 16:38:26 INFO - TEST-INFO | 26550: exit 0 16:38:26 INFO - TEST-OK | kraken | took 61152ms 16:38:26 INFO - SUITE-END | took 1176s 16:38:26 INFO - Completed test suite (00:19:36) 16:38:26 INFO - javascript benchmark 16:38:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3091.876324947002, 3099.0547882895708, 3119.9688003119963, 3123.9062828115157, 3135.7021082997107, 5347.545458636011, 5389.3802212745495, 5402.189671549259, 5481.068218402871, 5510.834674959748, 5394.838154855351, 5456.454354564542, 5462.781488740456, 5478.123500239963, 5526.171074338851, 4875.293082503035, 4958.132326842806, 4961.057399094178, 4982.626303027269, 5107.336046313974, 5035.1188542005, 5046.621503387242, 5067.417247016603, 5081.237814327862, 5083.0, 4884.5848102911295, 4920.6801557898625, 4921.0157968406465, 4979.775910084055, 5050.090983622947, 4882.7558622068755, 4883.243097319921, 4886.047220791947, 4888.169011268093, 4977.601791856679, 4904.141775189363, 4916.287138364953, 4935.7532123393685, 4951.232558953368, 4957.801687932479, 5306.469353064701, 5399.83800485986, 5433.972830135864, 5448.37343705471, 5454.018276710191, 5845.853853653693, 5866.9706651466895, 5879.470847623692, 5883.70581470929, 5905.6751878646655, 5954.732037058343, 5957.702114894238, 5966.373530779249, 5975.551833612496, 5975.671338076404, 5351.3043304370185, 5434.565234781247, 5503.091989821714, 5510.862228444321, 5586.078297081018, 6055.152278680988, 6070.612267158524, 6073.352704002548, 6075.979706787087, 6078.027515597571, 5843.5325173985975, 5844.211031510777, 5844.795432159854, 5847.181394604759, 5847.415258474119, 5678.914816277759, 5713.8285851424525, 5775.249217601685, 5776.393478684761, 5872.882542349129, 5754.568407369464, 5797.159411885337, 5803.738831752581, 5806.564507661942, 5808.099744539603, 5762.683052432073, 5817.563682723728, 5820.126980952891, 5823.82528524145, 5824.592278540462, 4349.28236840924, 4382.408374869416, 4384.758838263862, 4437.290033594674, 4437.400950871655, 3959.7822119783687, 3979.9204015919518, 3981.402789581586, 3984.063745019918, 3988.8205030773684, 5440.4287549807495, 5460.399356070829, 5550.500454959073, 5562.888742225214, 5661.377248502661, 5916.349201587822, 5925.466707996301, 5927.051671732502, 5931.377205393372, 5972.820815375546, 5545.72271386429, 5601.131824567198, 5603.7758489659955, 5603.971980140073, 5609.747561359749, 4945.7774400152075, 5023.748812559357, 5031.6729412588065, 5066.847994560096, 5082.415522214918, 6044.395560443921, 6048.243969503812, 6052.243469566304, 6053.394660534, 6090.665013424305, 5831.79588714393, 5864.38423965486, 5874.823755287348, 5876.6180198286975, 5881.911771323348, 5808.448197421239, 5996.970015149896, 6028.367021462684, 6029.969850150721, 6033.034714445667, 5808.651480911159, 5811.622244554091, 5818.650880947157, 5820.70896455184, 5875.0, 5911.497522710446, 5915.645061296336, 5920.2895652521975, 5922.703864806656, 5924.585279030426, 5621.269234999424, 5665.915011274752, 5703.657780533182, 5727.284089488814, 5774.9133762994425, 5747.339056008613, 5753.453421924911, 5754.6547207167705, 5757.5393968481585, 5796.42035796417, 5636.464535869086, 5691.060974939089, 5694.117411801178, 5695.031844586348, 5697.173909783141, 5523.530499907552, 5665.12190610456, 5690.402507736793, 5722.113072473773, 5792.7103644818435, 5635.408282130317, 5641.351244606923, 5641.943580564306, 5650.152477128464, 5681.9147712783515], "value": 5392.950491353625, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.1340016775173, 610.9327973922867, 632.2349956552571, 634.0742515926747, 645.5126379583414, 43777.34333984989, 45365.546344536546, 45414.999999999956, 45490.77254613726, 49738.50261497384, 46897.76551117248, 47424.762876185654, 47463.67754257203, 47469.7626511867, 47526.48498392647, 9069.276837400907, 9207.217386522154, 9223.308251881102, 9227.677031303905, 9236.21492173166, 9064.229540489066, 9169.816603667923, 9175.541222938826, 9175.816483670322, 9178.311626627996, 9172.266218702522, 9200.907990920106, 9204.0, 9229.261659067291, 9233.07669233078, 9118.817623647523, 9157.221636160933, 9162.404443711137, 9162.67930622425, 9164.312676549253, 9009.549522523914, 9099.135582119688, 9102.226310763594, 9105.954470227607, 9132.36073474857, 43797.0, 44214.11571768579, 44222.33666494973, 44304.556954430685, 44305.77847110776, 3562.991634411287, 3638.745287829853, 3648.069742215718, 3669.3578623740736, 3678.8160591970295, 33325.33349333024, 33616.15959601029, 34071.82964085189, 34123.829380852934, 34142.65857341445, 4100.36444351126, 4124.463819703449, 4170.70805043647, 4213.683973701954, 4256.808443620013, 379204.0, 381474.0, 382931.9999999972, 384595.0, 385053.0, 60962.695186523786, 61365.69317153386, 61470.38529614736, 61506.384936150964, 61532.69233653803, 65155.674221628586, 65326.0, 65384.0, 65411.34588654148, 65450.0, 17535.0, 17752.55618609545, 17760.023198723935, 17761.289548417917, 17795.19921603531, 13816.861831381759, 13874.098183618033, 13876.375563099684, 13895.305234738222, 13905.513307034407, 17645.240303441158, 18511.722324165148, 18546.629067418846, 18615.906920465583, 18764.0, 6675.399214070757, 6791.864162716718, 6792.7962161134255, 6867.862642747117, 6935.237123916365, 943.6225509796136, 1023.2837014090018, 1101.686019484443, 1120.5573798349606, 1145.5589598004663, 33721.49417758736, 33953.285482016334, 34019.14952126167, 34097.82951085229, 34764.82617586896, 989.6437282578266, 1133.0765426177804, 1146.134668325409, 1207.3299318878107, 1259.3829023778283, 17738.201780919888, 17793.644127117645, 17875.195616197303, 17893.642127157385, 17995.0, 487408.0, 502165.0, 502501.0, 577319.0, 581500.0, 61085.38914610886, 61348.38651613427, 61381.38618613757, 61392.0, 61614.38385616176, 1249.950001999928, 1322.8610995845297, 1355.749186400519, 1386.5077897346498, 1395.4697215058213, 30366.052413186146, 33651.66348336485, 34912.65087349094, 35403.29193416169, 35519.82240088783, 53906.46093539171, 54836.0, 54861.0, 54864.45135548594, 54907.45092549024, 520840.0, 524321.0, 525759.0, 526991.0, 528923.0, 33869.66130338665, 36796.63203368039, 44956.77521612502, 47064.529354706014, 47786.76106619445, 49207.0, 49332.75333623309, 49518.75240623774, 49601.75199123981, 49686.25470618016, 42111.0, 42195.7890210547, 43239.56760432355, 44756.0, 44777.32834007427, 42170.1565968685, 42363.0, 42572.36141457818, 42987.57012429961, 43826.0, 35957.460638090975, 41435.027796664595, 42421.575784241766, 42482.0, 44142.0, 33784.66215337815, 38388.0, 38550.570243777875, 38817.6118238814, 39267.41098883462, 28730.281742956595, 29183.416331673674, 29564.704352957055, 29619.0, 29657.555136672534], "value": 21167.072529239187, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.72207288679283, 382.6020938224247, 395.28848073467753, 406.4188210858472, 417.211470321093, 13172.802548068146, 19296.131674074666, 20006.499837504052, 20018.49953751155, 20399.694004589943, 19607.901960490177, 19645.11596978136, 19877.0, 20834.479138021517, 20836.8958155209, 5944.464998150176, 6110.725017374229, 6120.081987701858, 6120.449159575637, 6135.938640613604, 6006.639601623895, 6052.213212282397, 6055.333913269536, 6056.06131049688, 6066.181065556159, 4263.829446822124, 6321.557490975633, 6576.736930522798, 6616.966915165417, 6717.63053032083, 6605.669716514203, 6633.800985970429, 6669.966650166718, 6675.632840193787, 6677.0, 5897.6166549174595, 5898.203742494751, 5903.26209223847, 5906.084556893688, 5906.734196961147, 22616.0, 22952.42618934523, 23454.06183752648, 23648.40878978022, 23720.881395593085, 2358.3750306168877, 2488.0669748844184, 2575.549278876189, 2670.2790246633494, 2947.410517896429, 14835.961482696108, 14846.70306593873, 14883.627909302246, 14912.552623421314, 15066.849331506653, 1936.74822273105, 1973.6940774180025, 1984.593158402524, 2038.1337931379105, 2070.823979961705, 74823.25176748217, 76293.0, 76378.0, 77790.61104694496, 77961.0, 10442.269041167045, 10533.210009249337, 10563.0, 10598.0991615712, 10998.505067271992, 15179.468718594797, 15192.544223673307, 15193.924030379778, 15196.696066078617, 15237.542873713806, 8019.198080191934, 8491.448055876348, 8519.701810436605, 8615.91384086151, 8703.303735701129, 8303.169683031649, 8312.0, 8351.3736469765, 8539.14608539141, 8571.22858942698, 6512.511561632897, 6713.489464870463, 6781.694823732944, 6807.18313802337, 6823.795286141423, 1870.644577530292, 1911.7713054795124, 1932.4589115047809, 1962.2347284559035, 1978.5449346650064, 949.9525023748785, 972.9075737804898, 976.2873102635116, 1074.4198133008088, 1081.1242893256488, 20233.595328093357, 20936.47658808542, 21215.15139394438, 21244.468888277916, 21248.0, 1001.2740762946951, 1076.311160857052, 1090.7164137324194, 1094.9890501095047, 1098.6923661374828, 9566.808663826685, 11926.262036329888, 12144.69638259033, 12148.271103733805, 12151.817722734166, 465093.0, 473811.0, 488136.0, 491004.0, 492740.0, 80875.0, 82705.58647206725, 83323.5833820827, 83331.5833420829, 83693.58153209195, 1001.3191030099457, 1037.6731329631143, 1063.1388575254068, 1066.9786604267872, 1070.8875568065396, 17278.654426911642, 17972.10139493046, 18474.907625461787, 18527.888326700442, 18825.24699012079, 39294.607053929096, 39437.80281098576, 39457.40813887736, 40064.59935400609, 41269.174616508106, 52229.47770522398, 52864.20703689525, 53294.0, 53569.0, 53881.46118538921, 7993.129740677568, 8272.545010024507, 8283.627236774239, 8348.540830254395, 8973.102689730975, 11242.606508772154, 13315.467381305027, 13347.532836350681, 13508.729825403634, 13700.794488082885, 11631.825522617337, 11742.647720568397, 11807.173497855068, 11965.102617303563, 11982.880171198525, 6500.967495162494, 11243.887561124284, 11292.604758833402, 11667.766644667228, 11670.70823229426, 11405.828912566483, 11605.825912611486, 11723.35521546323, 11739.23694959825, 11771.941140294244, 8186.2632363087605, 8330.750077497685, 8382.874256886274, 8425.199606037417, 8593.140685931607, 11238.719032024264, 11378.658640240807, 11470.483828227583, 11587.188896777481, 11609.652769529253], "value": 9528.85385158643, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1268.2770820632243, 1393.1989106263898, 1462.510059130191, 1582.6834633073388, 1774.2193434888657, 8142.185781421855, 8504.064552899184, 8538.103499132587, 8745.038045814956, 8755.430896991707, 8559.828803423943, 8636.784080397993, 8675.47947123173, 8682.088380720012, 8700.0, 2030.8375329973567, 2268.6256767633404, 2342.964855527164, 2365.0894405653776, 2367.869767162805, 2351.2123438647986, 2377.4888398994212, 2378.512404956988, 2380.238323736405, 2383.0825132324094, 2334.6964894563685, 2358.5636657218383, 2370.146747171017, 2380.583397905368, 2382.4639456122322, 1976.2786582897138, 2039.88780617066, 2255.672927425522, 2283.098176220392, 2284.805791507724, 2075.2529089527757, 2234.530748542808, 2307.411610039446, 2313.005407674699, 2333.8482998605036, 8992.640294388217, 9030.729078127666, 9088.863667045, 9124.680636177769, 9128.452292862383, 5164.251183578398, 5241.659292146036, 5288.365396152448, 5302.098643230622, 5309.761060752276, 3223.4842425212087, 3345.7992520448606, 3395.2021275000357, 3441.6214216436174, 3454.2573346730323, 1148.850649415579, 1160.5937921727411, 1163.2729544034978, 1177.8939895409455, 1973.8223559879536, 12220.572279970247, 12587.74824503514, 12620.55828046014, 12625.05312101596, 12632.936835315857, 4319.114581510783, 4607.170709272363, 4660.673752837337, 4677.438707355139, 4680.672352935262, 9960.352577082467, 9978.501074946224, 9981.0, 9982.151517120981, 9998.350107243006, 5305.522502974673, 5308.814191503279, 5310.6813591184655, 5312.282841816383, 5328.973355133199, 4287.506936702319, 4753.928691069636, 4878.0975519529, 5005.099082165245, 5005.1491246488185, 3846.480640147126, 3874.283257597297, 3963.821628026688, 4009.298372784751, 4011.3782363733667, 1128.2666266926399, 1128.949197286124, 1134.8921852424007, 1157.4907040901976, 1185.3539820797687, 835.807764214222, 957.8218790887248, 972.9318947673595, 980.127686359141, 1004.6031817432073, 5921.61509501881, 6067.059605761114, 6096.268447786294, 6147.139400483936, 6256.405641464054, 469.1321056046287, 469.9718016918996, 472.33165071424236, 478.94492133405106, 479.42469037154854, 4341.196878577473, 4371.322445021026, 4390.05613793036, 4471.3740076388685, 4492.752898590544, 10850.728731781768, 10962.0, 11436.485358158903, 11444.484998175101, 11480.483378247998, 11202.887971120184, 11493.367864767519, 11513.251638643462, 11537.0, 11537.250078744855, 8024.799380015546, 8133.820596013545, 8152.673893044331, 8174.632141553644, 8193.0, 6323.494120470444, 6338.397852204033, 6546.345365463606, 6554.8361290966195, 6604.966975165094, 10220.693379198636, 10439.321444106108, 10452.268341216042, 10532.157427405942, 10547.841782373118, 10633.468326583485, 10697.465126743477, 10752.709674839069, 10771.946140269249, 10774.24580279373, 8078.919210807817, 8213.219744124299, 8275.0, 8318.833623327379, 8320.375971802017, 8786.253168480494, 8800.075992020746, 8886.644534218818, 8890.555472226233, 8900.332475064526, 7945.0863150738405, 7967.641456134372, 8036.799080023046, 8062.838743224986, 8094.231048050426, 7594.126675432396, 7621.35218506411, 7675.0, 7703.88444173349, 7879.054513458422, 6838.418734407631, 6907.309269073254, 6933.5493192942295, 6937.098177237129, 7033.0, 5333.466653334791, 5386.48828361305, 5387.434319396407, 5391.541718953933, 5553.0004599172535, 5600.103983362643, 5622.915656265078, 5645.604807663424, 5743.684097374685, 5750.597458178055], "value": 4621.923495638676, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.7222297158901, 329.30188001436954, 335.50345488676766, 343.1164750766775, 344.5331575714908, 5169.767360468779, 5217.47825217478, 5223.242629818674, 5227.712475813829, 5262.500437209252, 5162.22566615007, 5166.689998600077, 5176.741162941857, 5190.480951904808, 5199.818006369778, 3861.420786881969, 3938.8227529761084, 4063.6139566741113, 4079.551249362568, 4092.6930480213955, 4088.9386659200136, 4106.815193316308, 4107.507099148106, 4111.60939710727, 4119.567445418233, 4082.81627326771, 4083.632473077422, 4087.713860029799, 4090.7750073745933, 4091.672666186698, 3880.4373365861934, 3889.722338358632, 4065.2072845795083, 4082.265192265391, 4092.2224777292217, 3620.438831981021, 3628.8548458061646, 3630.2739452109686, 3634.9273014539826, 3643.3806252937065, 5032.295478632995, 5036.0, 5042.495750424965, 5050.343455350818, 5051.621128415347, 4445.199864024475, 4633.212353899811, 4645.396098507173, 4657.464391594976, 4679.110968915929, 5399.461153571214, 5403.243545903577, 5403.378986304093, 5404.594655400861, 5416.295881535415, 3808.847646094153, 3963.6829053675638, 3992.441058251847, 3992.6207010333974, 3992.6606238469767, 5604.719764011783, 5606.6636001840025, 5623.9437605623825, 5643.181738647914, 5646.294213223306, 4017.5580686124504, 4027.496562929634, 4031.588943869681, 4034.4755181826176, 4038.495188101487, 4082.9591704082577, 4090.0388408723925, 4094.263032654091, 4094.324436468009, 4096.754194748325, 4366.19225443294, 4601.562851529099, 4634.814607415733, 4635.351050852883, 4640.141573808789, 5435.809746658927, 5441.047816632074, 5444.40111587725, 5447.673139611636, 5457.290552228185, 1389.9652508687363, 1398.9160650361011, 1406.1773862290675, 1408.7112142010862, 1409.9506517271845, 887.1173182683272, 902.8826252587253, 908.3777612335572, 908.5230254116642, 916.0610374366195, 5089.847304580869, 5184.403793563713, 5214.217867319857, 5305.336832895888, 5311.521963023347, 5559.33288005442, 5564.860878478071, 5577.9163312550345, 5585.441455854463, 5598.496135347838, 5154.92267615986, 5230.712310822942, 5289.947100528945, 5298.364196296392, 5299.788008479618, 2889.971100289012, 2944.2933695912825, 2944.6466424028827, 2944.9116526503813, 2949.9822561216347, 5688.971555142198, 5689.800856969986, 5699.686517241592, 5721.511142652959, 5742.54059675225, 5464.9180262296095, 5650.632708873993, 5651.2935883014625, 5685.488306052475, 5686.947914635805, 5654.830355089354, 5690.374058853523, 5691.373948865621, 5693.715314234355, 5694.658320500783, 5292.814751483679, 5482.533984611353, 5535.892821435648, 5560.4161563035295, 5568.969740598002, 5507.394186639386, 5508.256385387921, 5526.253955715927, 5530.898258661683, 5559.694216818114, 5733.197352370588, 5737.885242295214, 5742.339630942495, 5753.309602847685, 5758.884822303614, 3940.862069827542, 4024.0, 4038.051058001309, 4059.2287465381482, 4060.289449346412, 5566.332040155045, 5588.41321661236, 5602.52378547828, 5623.409541998195, 5637.238972738792, 5396.4333744957785, 5460.945390546044, 5474.343078830566, 5481.808136715196, 5499.367572729027, 5249.868753281198, 5436.565074794086, 5453.563714902719, 5453.781848726166, 5466.617336786387, 5395.24466574672, 5395.649282796606, 5411.350637923474, 5411.377691565521, 5414.945850541444, 5244.895102098013, 5322.38792538863, 5388.137897936312, 5399.325084364455, 5498.147787093007, 4876.585790120967, 5337.919931201112, 5340.172273297645, 5358.6248962572245, 5372.650777699437], "value": 4156.949703530532, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.6141267953344, 820.5568992743919, 828.7703108136212, 838.8825564420982, 868.9217970382668, 15133.0, 15751.133687647174, 16019.439319623816, 16025.198740063008, 16036.59908502288, 15345.462908798167, 15377.154256515887, 15500.302486388111, 15512.84487155128, 15513.612159696013, 675.3347952267037, 817.3134566963764, 832.7168762620707, 837.3426859914968, 837.5728378526934, 781.1563511407694, 839.1063517354029, 842.061101871412, 846.7417437681505, 847.4745657692208, 874.2656168818191, 875.155474966657, 875.4222213339198, 875.5797217335682, 876.2989608313355, 690.378659206713, 864.6195673903462, 866.9263112635433, 868.3313848336777, 868.9695860644879, 595.4194660206264, 633.1958412815699, 634.9492040636784, 638.865838173984, 639.6897504710212, 15202.771958420633, 15279.312430940523, 15291.847081529153, 15319.846801531954, 15325.31036103378, 14079.859201407957, 14127.364268607937, 14380.85619143816, 14664.853351466456, 14877.0, 13171.538996135185, 13179.67050823737, 13233.933830330883, 13395.397207125605, 13504.257265850374, 4426.490953540352, 4440.711353761995, 4556.997460558672, 4570.977145114286, 4708.340832283483, 71003.0, 71038.64480677615, 75242.24757752461, 76546.23453765502, 76934.61532692301, 11473.942630286796, 11721.765564688658, 11729.228867500447, 11808.645740627795, 11810.52757889692, 21500.24749133804, 21535.892320538296, 21563.0, 21622.675659865115, 21627.2430464933, 7842.411819113589, 7880.802979925433, 8015.238552337519, 8054.23484768946, 8072.071711753224, 4388.5830846069575, 4588.472325682523, 4857.247126695368, 4903.068417000758, 5093.337866077386, 3372.8144952027383, 3491.720662347006, 3491.790492570403, 3492.650734926487, 3494.4408894577255, 583.2301362201938, 588.1442501160801, 588.4233451217831, 596.832886791699, 604.8518113062328, 658.6904155047121, 661.7989438956909, 676.5737585321216, 706.20199174932, 710.3784188834769, 9686.564104615309, 9767.365121267096, 9812.0, 9820.508974551243, 9922.950385248028, 557.4035781713609, 592.1946153231602, 621.2141640824361, 664.3389827121948, 690.526989012528, 4448.310511870665, 4449.377087207794, 4563.703359281636, 4581.541845815392, 4663.253879379283, 70752.29247707457, 77202.0, 110576.0, 110611.0, 110628.0, 58724.0, 92614.53692731493, 97039.51480242553, 100009.0, 100526.48683782818, 90208.0, 90683.54658226666, 91641.54179229194, 92623.53688231515, 94946.5252673746, 8493.193146651058, 8531.914680853111, 8579.742607721779, 8790.978389468786, 8953.774664493678, 54035.729821350644, 54090.18864716954, 54878.1585289026, 55404.44595553993, 55931.16103258373, 72436.63781681057, 78544.60727696324, 78924.60537697274, 79774.0, 80124.59937700274, 11080.445977701243, 11549.538018479167, 11589.24669896454, 11735.706607334885, 11778.587749428729, 12881.484740610646, 13345.733085338434, 13384.531541396005, 13605.0, 13731.862681373457, 11779.293242405483, 12138.393080345772, 12216.938915305722, 12844.357782111045, 12972.545960891324, 12653.430595623404, 12740.279380745606, 13016.414261358452, 13096.410661520064, 13546.254955977516, 12367.505299787908, 12627.74744505123, 12773.808392873929, 12860.164089334163, 13157.53948611794, 9794.21646268311, 10047.497625118569, 10126.746831329276, 10209.183265338606, 10417.427041512487, 9679.903200968181, 10228.437435941094, 10522.579096836347, 10660.25378223517, 10682.679519614425], "value": 7426.988550046696, "unit": "score"}], "name": "dromaeo_css", "value": 7331.019519419154, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 110.0, 110.0, 111.0, 111.0, 111.0, 111.0, 110.0, 112.0, 112.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 122.0, 169.0, 118.0, 119.0, 150.0, 117.0, 154.0, 118.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 159.0, 160.0, 160.0, 160.0, 160.0, 161.0, 162.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 123.0, 75.0, 75.0, 74.0, 74.0, 100.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 119.0, 84.0, 83.0, 84.0, 120.0, 83.0, 84.0, 137.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 119.0, 118.0, 118.0, 116.0, 117.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 49.0, 56.0, 51.0, 51.0, 50.0, 50.0, 49.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 63.0, 61.0, 64.0, 62.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 85.0, 82.0, 75.0, 79.0, 80.0, 75.0, 75.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 119.0, 119.0, 119.0, 125.0, 117.0, 122.0, 131.0, 155.0, 119.0], "value": 124.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 153.0, 155.0, 155.0, 154.0, 154.0, 153.0, 154.0, 154.0, 158.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 64.0, 65.0, 70.0, 64.0, 62.0, 63.0, 63.0], "value": 64.2, "unit": "score"}], "name": "kraken", "value": 1474.0, "alertThreshold": 2.0}]} 16:38:26 INFO - Return code: 0 16:38:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:38:26 INFO - # TBPL SUCCESS # 16:38:26 INFO - Running post-action listener: _resource_record_post_action 16:38:26 INFO - ##### 16:38:26 INFO - ##### Finished run-tests step (success) 16:38:26 INFO - ##### 16:38:26 INFO - Running post-run listener: _resource_record_post_run 16:38:26 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 8192; Write bytes: 568107008; Read time: 432; Write time: 1081964 16:38:26 INFO - TinderboxPrint: CPU usage
13.1% 16:38:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 432 16:38:26 INFO - TinderboxPrint: I/O write bytes / time
568,107,008 / 1,081,964 16:38:26 INFO - TinderboxPrint: CPU idle
8,283.4 (86.9%) 16:38:26 INFO - TinderboxPrint: CPU user
1,192.0 (12.5%) 16:38:26 INFO - TinderboxPrint: Swap in / out
0 / 0 16:38:26 INFO - install - Wall time: 18s; CPU: 18.0%; Read bytes: 0; Write bytes: 257630208; Read time: 0; Write time: 716628 16:38:26 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 8192; Write bytes: 294854656; Read time: 432; Write time: 356036 16:38:27 INFO - Running post-run listener: _upload_blobber_files 16:38:27 INFO - Blob upload gear active. 16:38:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:27 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 16:38:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:38:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:38:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:40 INFO - (blobuploader) - INFO - Done attempting. 16:38:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:38:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:38:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:41 INFO - (blobuploader) - INFO - Done attempting. 16:38:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:38:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:38:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:41 INFO - (blobuploader) - INFO - Done attempting. 16:38:41 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:41 INFO - Return code: 0 16:38:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3e1a2335686c7a543d6deefb60bb1e71f98084ee7c93cf504d6dccce75961b69c59c3e80d12992caba4c3ada95bc3518e32140a9ffe089415000fb4b287748bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7446605880bd8d20586d133699acf02a0b055d066a7d36fb246abba8727a36e23692ece590a70367d26b2c57a3182bf2e2981c821fd6918ad4e76988892fc0df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/38367844de8a15e4c692f367a233c40fedc9c6fc1a89d00c64bfb52397c7f0d366d0f847a5e7b2496a758ba152711acdd7551b9b4a2f5698cc4e5582a7f4a8e2"} 16:38:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:41 INFO - Contents: 16:38:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3e1a2335686c7a543d6deefb60bb1e71f98084ee7c93cf504d6dccce75961b69c59c3e80d12992caba4c3ada95bc3518e32140a9ffe089415000fb4b287748bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7446605880bd8d20586d133699acf02a0b055d066a7d36fb246abba8727a36e23692ece590a70367d26b2c57a3182bf2e2981c821fd6918ad4e76988892fc0df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/38367844de8a15e4c692f367a233c40fedc9c6fc1a89d00c64bfb52397c7f0d366d0f847a5e7b2496a758ba152711acdd7551b9b4a2f5698cc4e5582a7f4a8e2"} 16:38:41 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:41 INFO - Copying logs to upload dir... 16:38:41 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.705900 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-07-19 16:38:41.433806) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 16:38:41.437752) ========= 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=745a4373a2a16df560f216e000000264-1468970197.509159-2120899850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3e1a2335686c7a543d6deefb60bb1e71f98084ee7c93cf504d6dccce75961b69c59c3e80d12992caba4c3ada95bc3518e32140a9ffe089415000fb4b287748bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7446605880bd8d20586d133699acf02a0b055d066a7d36fb246abba8727a36e23692ece590a70367d26b2c57a3182bf2e2981c821fd6918ad4e76988892fc0df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/38367844de8a15e4c692f367a233c40fedc9c6fc1a89d00c64bfb52397c7f0d366d0f847a5e7b2496a758ba152711acdd7551b9b4a2f5698cc4e5582a7f4a8e2"} build_url:https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011460 build_url: 'https://queue.taskcluster.net/v1/task/PxsIAJDITCS_4pdpwb9FAg/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/Mozilla-Inbound/sha512/3e1a2335686c7a543d6deefb60bb1e71f98084ee7c93cf504d6dccce75961b69c59c3e80d12992caba4c3ada95bc3518e32140a9ffe089415000fb4b287748bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7446605880bd8d20586d133699acf02a0b055d066a7d36fb246abba8727a36e23692ece590a70367d26b2c57a3182bf2e2981c821fd6918ad4e76988892fc0df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/38367844de8a15e4c692f367a233c40fedc9c6fc1a89d00c64bfb52397c7f0d366d0f847a5e7b2496a758ba152711acdd7551b9b4a2f5698cc4e5582a7f4a8e2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 16:38:41.463930) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:38:41.464249) ========= 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=745a4373a2a16df560f216e000000264-1468970197.509159-2120899850 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 16:38:41.547874) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 16:38:41.548466) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 16:38:41.549081) ========= ========= Total master_lag: 0.18 =========