builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1468435909.53 results: success (0) buildid: 20160713073201 builduid: f4a63f0f426c4835a92d4f02f319e0a8 revision: dcb7835534e21a62cebdd5d1205d4e81f6bc6946 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:49.534206) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:49.534721) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:49.535008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:49.546475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:49.546867) ========= 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=8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-13 11:51:49-- 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% 46.4M=0s 2016-07-13 11:51:50 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128039 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:49.690746) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:49.691499) ========= 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=8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018856 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:49.744682) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:49.745460) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev dcb7835534e21a62cebdd5d1205d4e81f6bc6946 --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 dcb7835534e21a62cebdd5d1205d4e81f6bc6946 --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=8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-13 11:51:50,170 truncating revision to first 12 chars 2016-07-13 11:51:50,170 Setting DEBUG logging. 2016-07-13 11:51:50,170 attempt 1/10 2016-07-13 11:51:50,170 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/dcb7835534e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 11:51:50,477 unpacking tar archive at: mozilla-inbound-dcb7835534e2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.741339 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:50.501726) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:50.502071) ========= script_repo_revision: dcb7835534e21a62cebdd5d1205d4e81f6bc6946 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:50.502503) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:50.502776) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 11:51:50.514375) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-07-13 11:51:50.514665) ========= /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=8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:51:51 INFO - MultiFileLogger online at 20160713 11:51:51 in /builds/slave/test 11:51:51 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 11:51:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:51:51 INFO - {'append_to_log': False, 11:51:51 INFO - 'base_work_dir': '/builds/slave/test', 11:51:51 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 11:51:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:51:51 INFO - 'branch': 'Mozilla-Inbound', 11:51:51 INFO - 'buildbot_json_path': 'buildprops.json', 11:51:51 INFO - 'config_files': ('talos/linux_config.py',), 11:51:51 INFO - 'default_actions': ('clobber', 11:51:51 INFO - 'read-buildbot-config', 11:51:51 INFO - 'download-and-extract', 11:51:51 INFO - 'populate-webroot', 11:51:51 INFO - 'create-virtualenv', 11:51:51 INFO - 'install', 11:51:51 INFO - 'run-tests'), 11:51:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:51:51 INFO - 'download_minidump_stackwalk': True, 11:51:51 INFO - 'download_symbols': 'ondemand', 11:51:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:51:51 INFO - 'tooltool.py': '/tools/tooltool.py', 11:51:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:51:51 INFO - '/tools/misc-python/virtualenv.py')}, 11:51:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:51:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:51:51 INFO - 'installer_path': 'installer.exe', 11:51:51 INFO - 'log_level': 'info', 11:51:51 INFO - 'log_name': 'talos', 11:51:51 INFO - 'log_to_console': True, 11:51:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:51:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:51:51 INFO - 'opt_config_files': (), 11:51:51 INFO - 'pip_index': False, 11:51:51 INFO - 'sps_profile': False, 11:51:51 INFO - 'sps_profile_interval': 0, 11:51:51 INFO - 'suite': 'dromaeojs', 11:51:51 INFO - 'system_bits': '32', 11:51:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:51:51 INFO - 'title': 'talos-linux64-ix-038', 11:51:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:51:51 INFO - 'use_talos_json': True, 11:51:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:51:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:51:51 INFO - 'work_dir': 'build'} 11:51:51 INFO - ##### 11:51:51 INFO - ##### Running clobber step. 11:51:51 INFO - ##### 11:51:51 INFO - Running pre-action listener: _resource_record_pre_action 11:51:51 INFO - Running main action method: clobber 11:51:51 INFO - rmtree: /builds/slave/test/build 11:51:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:51:52 INFO - Running post-action listener: _resource_record_post_action 11:51:52 INFO - ##### 11:51:52 INFO - ##### Finished clobber step (success) 11:51:52 INFO - ##### 11:51:52 INFO - ##### 11:51:52 INFO - ##### Running read-buildbot-config step. 11:51:52 INFO - ##### 11:51:52 INFO - Running pre-action listener: _resource_record_pre_action 11:51:52 INFO - Running main action method: read_buildbot_config 11:51:52 INFO - Using buildbot properties: 11:51:52 INFO - { 11:51:52 INFO - "project": "", 11:51:52 INFO - "product": "firefox", 11:51:52 INFO - "script_repo_revision": "production", 11:51:52 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 11:51:52 INFO - "repository": "", 11:51:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 11:51:52 INFO - "buildid": "20160713073201", 11:51:52 INFO - "pgo_build": "True", 11:51:52 INFO - "basedir": "/builds/slave/test", 11:51:52 INFO - "buildnumber": 1330, 11:51:52 INFO - "slavename": "talos-linux64-ix-038", 11:51:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:51:52 INFO - "platform": "ubuntu64_hw", 11:51:52 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 11:51:52 INFO - "branch": "mozilla-inbound", 11:51:52 INFO - "repo_path": "integration/mozilla-inbound", 11:51:52 INFO - "revision": "dcb7835534e21a62cebdd5d1205d4e81f6bc6946", 11:51:52 INFO - "stage_platform": "linux64-pgo", 11:51:52 INFO - "builduid": "f4a63f0f426c4835a92d4f02f319e0a8", 11:51:52 INFO - "slavebuilddir": "test" 11:51:52 INFO - } 11:51:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 11:51:52 INFO - Running post-action listener: _resource_record_post_action 11:51:52 INFO - ##### 11:51:52 INFO - ##### Finished read-buildbot-config step (success) 11:51:52 INFO - ##### 11:51:52 INFO - ##### 11:51:52 INFO - ##### Running download-and-extract step. 11:51:52 INFO - ##### 11:51:52 INFO - Running pre-action listener: _resource_record_pre_action 11:51:52 INFO - Running main action method: download_and_extract 11:51:52 INFO - mkdir: /builds/slave/test/build/tests 11:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:52 INFO - https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:51:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:51:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:51:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:51:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:51:52 INFO - Downloaded 1361 bytes. 11:51:52 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 11:51:52 INFO - Using the following test package requirements: 11:51:52 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:51:52 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:51:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:51:52 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:51:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:51:52 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:51:52 INFO - u'jsshell-linux-x86_64.zip'], 11:51:52 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:51:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:51:52 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 11:51:52 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:51:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:51:52 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:51:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:51:52 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:51:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:51:52 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 11:51:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:51:52 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:52 INFO - https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:51:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:51:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:51:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:51:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:51:53 INFO - Downloaded 24326088 bytes. 11:51:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:51:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:51:55 INFO - Return code: 0 11:51:55 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:51:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:55 INFO - https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:51:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:51:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:51:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 11:51:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:51:56 INFO - Downloaded 24326088 bytes. 11:51:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:51:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:51:57 INFO - Return code: 0 11:51:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:57 INFO - https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:51:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:51:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:51:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 11:51:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:51:58 INFO - Downloaded 14403317 bytes. 11:51:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:51:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 11:51:59 INFO - Return code: 0 11:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:59 INFO - https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:51:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:51:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:51:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:51:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:52:00 INFO - Downloaded 60851647 bytes. 11:52:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:52:00 INFO - mkdir: /builds/slave/test/properties 11:52:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:52:00 INFO - Writing to file /builds/slave/test/properties/build_url 11:52:00 INFO - Contents: 11:52:00 INFO - build_url:https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 11:52:01 INFO - Running post-action listener: _resource_record_post_action 11:52:01 INFO - Running post-action listener: set_extra_try_arguments 11:52:01 INFO - ##### 11:52:01 INFO - ##### Finished download-and-extract step (success) 11:52:01 INFO - ##### 11:52:01 INFO - ##### 11:52:01 INFO - ##### Running populate-webroot step. 11:52:01 INFO - ##### 11:52:01 INFO - Running pre-action listener: _resource_record_pre_action 11:52:01 INFO - Running main action method: populate_webroot 11:52:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:52:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:52:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:52:01 INFO - '--tppagecycles', 11:52:01 INFO - '7'], 11:52:01 INFO - 'tests': ['tsvgm']}}, 11:52:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:52:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:52:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:52:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:52:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:01 INFO - 'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:52:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:52:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:01 INFO - 'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['damp', 'tps']}, 11:52:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:01 INFO - 'tests': ['damp', 'tps']}, 11:52:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['dromaeo_dom']}, 11:52:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:52:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['basic_compositor_video']}, 11:52:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:52:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['a11yr', 11:52:01 INFO - 'ts_paint', 11:52:01 INFO - 'tpaint', 11:52:01 INFO - 'sessionrestore', 11:52:01 INFO - 'sessionrestore_no_auto_restore', 11:52:01 INFO - 'tabpaint']}, 11:52:01 INFO - 'other-e10s': {'tests': ['a11yr', 11:52:01 INFO - 'ts_paint', 11:52:01 INFO - 'tpaint', 11:52:01 INFO - 'sessionrestore', 11:52:01 INFO - 'sessionrestore_no_auto_restore', 11:52:01 INFO - 'tabpaint']}, 11:52:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:52:01 INFO - 'ts_paint', 11:52:01 INFO - 'tpaint', 11:52:01 INFO - 'sessionrestore', 11:52:01 INFO - 'sessionrestore_no_auto_restore', 11:52:01 INFO - 'tabpaint']}, 11:52:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:52:01 INFO - 'ts_paint', 11:52:01 INFO - 'tpaint', 11:52:01 INFO - 'sessionrestore', 11:52:01 INFO - 'sessionrestore_no_auto_restore', 11:52:01 INFO - 'tabpaint']}, 11:52:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['a11yr', 11:52:01 INFO - 'ts_paint', 11:52:01 INFO - 'tpaint', 11:52:01 INFO - 'sessionrestore', 11:52:01 INFO - 'sessionrestore_no_auto_restore', 11:52:01 INFO - 'tabpaint']}, 11:52:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['a11yr', 11:52:01 INFO - 'ts_paint', 11:52:01 INFO - 'tpaint', 11:52:01 INFO - 'sessionrestore', 11:52:01 INFO - 'sessionrestore_no_auto_restore', 11:52:01 INFO - 'tabpaint']}, 11:52:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['tsvgx', 11:52:01 INFO - 'tsvgr_opacity', 11:52:01 INFO - 'tart', 11:52:01 INFO - 'tscrollx', 11:52:01 INFO - 'cart']}, 11:52:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:52:01 INFO - 'tsvgr_opacity', 11:52:01 INFO - 'tart', 11:52:01 INFO - 'tscrollx', 11:52:01 INFO - 'cart']}, 11:52:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:01 INFO - 'talos_options': ['--disable-e10s'], 11:52:01 INFO - 'tests': ['tp5o']}, 11:52:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:01 INFO - 'tests': ['tp5o']}, 11:52:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:01 INFO - 'talos_options': ['--disable-e10s', 11:52:01 INFO - '--xperf_path', 11:52:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:52:01 INFO - 'tests': ['tp5n']}, 11:52:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:01 INFO - 'talos_options': ['--xperf_path', 11:52:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:52:01 INFO - 'tests': ['tp5n']}}, 11:52:01 INFO - 'talos.zip': {'path': '', 11:52:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:52:01 INFO - Running post-action listener: _resource_record_post_action 11:52:01 INFO - ##### 11:52:01 INFO - ##### Finished populate-webroot step (success) 11:52:01 INFO - ##### 11:52:01 INFO - ##### 11:52:01 INFO - ##### Running create-virtualenv step. 11:52:01 INFO - ##### 11:52:01 INFO - Running pre-action listener: _resource_record_pre_action 11:52:01 INFO - Running main action method: create_virtualenv 11:52:01 INFO - Creating virtualenv /builds/slave/test/build/venv 11:52:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:52:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:52:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:52:01 INFO - Using real prefix '/usr' 11:52:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:52:01 INFO - Installing distribute.............................................................................................................................................................................................done. 11:52:03 INFO - Installing pip.................done. 11:52:03 INFO - Return code: 0 11:52:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:03 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 0x2342030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2603180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, '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': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:52:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:52:03 INFO - Using env: {'DISPLAY': ':0', 11:52:03 INFO - 'HOME': '/home/cltbld', 11:52:03 INFO - 'LANG': 'en_US.UTF-8', 11:52:03 INFO - 'LANGUAGE': 'en_US:en', 11:52:03 INFO - 'LOGNAME': 'cltbld', 11:52:03 INFO - 'MAIL': '/var/mail/cltbld', 11:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:03 INFO - 'MOZ_NO_REMOTE': '1', 11:52:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:03 INFO - 'NO_EM_RESTART': '1', 11:52:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:03 INFO - 'PWD': '/builds/slave/test', 11:52:03 INFO - 'SHELL': '/bin/bash', 11:52:03 INFO - 'SHLVL': '1', 11:52:03 INFO - 'TERM': 'linux', 11:52:03 INFO - 'TMOUT': '86400', 11:52:03 INFO - 'USER': 'cltbld', 11:52:03 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:03 INFO - '_': '/tools/buildbot/bin/python'} 11:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:52:04 INFO - Cleaning up... 11:52:04 INFO - Return code: 0 11:52:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:04 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 0x2342030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2603180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, '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': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:52:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:52:04 INFO - Using env: {'DISPLAY': ':0', 11:52:04 INFO - 'HOME': '/home/cltbld', 11:52:04 INFO - 'LANG': 'en_US.UTF-8', 11:52:04 INFO - 'LANGUAGE': 'en_US:en', 11:52:04 INFO - 'LOGNAME': 'cltbld', 11:52:04 INFO - 'MAIL': '/var/mail/cltbld', 11:52:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:04 INFO - 'MOZ_NO_REMOTE': '1', 11:52:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:04 INFO - 'NO_EM_RESTART': '1', 11:52:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:04 INFO - 'PWD': '/builds/slave/test', 11:52:04 INFO - 'SHELL': '/bin/bash', 11:52:04 INFO - 'SHLVL': '1', 11:52:04 INFO - 'TERM': 'linux', 11:52:04 INFO - 'TMOUT': '86400', 11:52:04 INFO - 'USER': 'cltbld', 11:52:04 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:04 INFO - '_': '/tools/buildbot/bin/python'} 11:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:04 INFO - Downloading/unpacking psutil>=3.1.1 11:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:52:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:06 INFO - Installing collected packages: psutil 11:52:06 INFO - Running setup.py install for psutil 11:52:06 INFO - building 'psutil._psutil_linux' extension 11:52:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:52:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:52:06 INFO - building 'psutil._psutil_posix' extension 11:52:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:52:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:52:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:06 INFO - Successfully installed psutil 11:52:06 INFO - Cleaning up... 11:52:06 INFO - Return code: 0 11:52:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52: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')]} 11:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:06 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 0x2342030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2603180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, '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': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:52:06 INFO - Using env: {'DISPLAY': ':0', 11:52:06 INFO - 'HOME': '/home/cltbld', 11:52:06 INFO - 'LANG': 'en_US.UTF-8', 11:52:06 INFO - 'LANGUAGE': 'en_US:en', 11:52:06 INFO - 'LOGNAME': 'cltbld', 11:52:06 INFO - 'MAIL': '/var/mail/cltbld', 11:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:06 INFO - 'MOZ_NO_REMOTE': '1', 11:52:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:06 INFO - 'NO_EM_RESTART': '1', 11:52:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:06 INFO - 'PWD': '/builds/slave/test', 11:52:06 INFO - 'SHELL': '/bin/bash', 11:52:06 INFO - 'SHLVL': '1', 11:52:06 INFO - 'TERM': 'linux', 11:52:06 INFO - 'TMOUT': '86400', 11:52:06 INFO - 'USER': 'cltbld', 11:52:06 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:06 INFO - '_': '/tools/buildbot/bin/python'} 11:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:52:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:52:09 INFO - Installing collected packages: mozsystemmonitor 11:52:09 INFO - Running setup.py install for mozsystemmonitor 11:52:09 INFO - Successfully installed mozsystemmonitor 11:52:09 INFO - Cleaning up... 11:52:09 INFO - Return code: 0 11:52:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:09 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 0x2342030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2603180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, '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': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:52:09 INFO - Using env: {'DISPLAY': ':0', 11:52:09 INFO - 'HOME': '/home/cltbld', 11:52:09 INFO - 'LANG': 'en_US.UTF-8', 11:52:09 INFO - 'LANGUAGE': 'en_US:en', 11:52:09 INFO - 'LOGNAME': 'cltbld', 11:52:09 INFO - 'MAIL': '/var/mail/cltbld', 11:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:09 INFO - 'MOZ_NO_REMOTE': '1', 11:52:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:09 INFO - 'NO_EM_RESTART': '1', 11:52:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:09 INFO - 'PWD': '/builds/slave/test', 11:52:09 INFO - 'SHELL': '/bin/bash', 11:52:09 INFO - 'SHLVL': '1', 11:52:09 INFO - 'TERM': 'linux', 11:52:09 INFO - 'TMOUT': '86400', 11:52:09 INFO - 'USER': 'cltbld', 11:52:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:09 INFO - '_': '/tools/buildbot/bin/python'} 11:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:09 INFO - Downloading/unpacking jsonschema==2.5.1 11:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:52:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:11 INFO - Downloading functools32-3.2.3-2.tar.gz 11:52:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:52:11 INFO - warning: no files found matching '*.txt' 11:52:11 INFO - no previously-included directories found matching 'build' 11:52:11 INFO - no previously-included directories found matching 'dist' 11:52:11 INFO - no previously-included directories found matching '.git*' 11:52:11 INFO - Installing collected packages: jsonschema, functools32 11:52:11 INFO - Running setup.py install for functools32 11:52:11 INFO - warning: no files found matching '*.txt' 11:52:11 INFO - no previously-included directories found matching 'build' 11:52:11 INFO - no previously-included directories found matching 'dist' 11:52:11 INFO - no previously-included directories found matching '.git*' 11:52:11 INFO - Successfully installed jsonschema functools32 11:52:11 INFO - Cleaning up... 11:52:11 INFO - Return code: 0 11:52:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:52:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:11 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 0x2342030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2603180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, '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': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:52:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:52:11 INFO - Using env: {'DISPLAY': ':0', 11:52:11 INFO - 'HOME': '/home/cltbld', 11:52:11 INFO - 'LANG': 'en_US.UTF-8', 11:52:11 INFO - 'LANGUAGE': 'en_US:en', 11:52:11 INFO - 'LOGNAME': 'cltbld', 11:52:11 INFO - 'MAIL': '/var/mail/cltbld', 11:52:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:11 INFO - 'MOZ_NO_REMOTE': '1', 11:52:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:11 INFO - 'NO_EM_RESTART': '1', 11:52:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:11 INFO - 'PWD': '/builds/slave/test', 11:52:11 INFO - 'SHELL': '/bin/bash', 11:52:11 INFO - 'SHLVL': '1', 11:52:11 INFO - 'TERM': 'linux', 11:52:11 INFO - 'TMOUT': '86400', 11:52:11 INFO - 'USER': 'cltbld', 11:52:11 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:11 INFO - '_': '/tools/buildbot/bin/python'} 11:52:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:11 INFO - Downloading/unpacking blobuploader==1.2.4 11:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:13 INFO - Downloading blobuploader-1.2.4.tar.gz 11:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:52:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:52: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 11:52: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 11:52: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 11:52: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 11:52: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 11:52: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 11:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:52:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:14 INFO - Downloading docopt-0.6.1.tar.gz 11:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:52:14 INFO - Installing collected packages: blobuploader, requests, docopt 11:52:14 INFO - Running setup.py install for blobuploader 11:52:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:52:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:52:14 INFO - Running setup.py install for requests 11:52:15 INFO - Running setup.py install for docopt 11:52:15 INFO - Successfully installed blobuploader requests docopt 11:52:15 INFO - Cleaning up... 11:52:15 INFO - Return code: 0 11:52:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52: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')]} 11:52:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:15 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 0x2342030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2603180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, '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': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:52:15 INFO - Using env: {'DISPLAY': ':0', 11:52:15 INFO - 'HOME': '/home/cltbld', 11:52:15 INFO - 'LANG': 'en_US.UTF-8', 11:52:15 INFO - 'LANGUAGE': 'en_US:en', 11:52:15 INFO - 'LOGNAME': 'cltbld', 11:52:15 INFO - 'MAIL': '/var/mail/cltbld', 11:52:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:15 INFO - 'MOZ_NO_REMOTE': '1', 11:52:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:15 INFO - 'NO_EM_RESTART': '1', 11:52:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:15 INFO - 'PWD': '/builds/slave/test', 11:52:15 INFO - 'SHELL': '/bin/bash', 11:52:15 INFO - 'SHLVL': '1', 11:52:15 INFO - 'TERM': 'linux', 11:52:15 INFO - 'TMOUT': '86400', 11:52:15 INFO - 'USER': 'cltbld', 11:52:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:15 INFO - '_': '/tools/buildbot/bin/python'} 11:52:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:15 INFO - Running setup.py (path:/tmp/pip-v6VhPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:15 INFO - Running setup.py (path:/tmp/pip-VWlo6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:15 INFO - Running setup.py (path:/tmp/pip-brOui2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:15 INFO - Running setup.py (path:/tmp/pip-yNx6pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:15 INFO - Running setup.py (path:/tmp/pip-GnkaIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:15 INFO - Running setup.py (path:/tmp/pip-tuclIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:15 INFO - Running setup.py (path:/tmp/pip-XV0JeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:16 INFO - Running setup.py (path:/tmp/pip-VM7Zrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:16 INFO - Running setup.py (path:/tmp/pip-OMxp5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:16 INFO - Running setup.py (path:/tmp/pip-A6K_4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:16 INFO - Running setup.py (path:/tmp/pip-GGCI8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:16 INFO - Running setup.py (path:/tmp/pip-WI3Fb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:16 INFO - Running setup.py (path:/tmp/pip-AI_YWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:16 INFO - Running setup.py (path:/tmp/pip-74E_HB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:16 INFO - Running setup.py (path:/tmp/pip-F6_Ibm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:16 INFO - Running setup.py (path:/tmp/pip-_U9eRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:16 INFO - Running setup.py (path:/tmp/pip-V2mzFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:52:16 INFO - Running setup.py install for manifestparser 11:52:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:52:16 INFO - Running setup.py install for mozcrash 11:52:17 INFO - Running setup.py install for mozdebug 11:52:17 INFO - Running setup.py install for mozdevice 11:52:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Running setup.py install for mozfile 11:52:17 INFO - Running setup.py install for mozhttpd 11:52:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Running setup.py install for mozinfo 11:52:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Running setup.py install for mozInstall 11:52:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Running setup.py install for mozleak 11:52:17 INFO - Running setup.py install for mozlog 11:52:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Running setup.py install for moznetwork 11:52:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:52:17 INFO - Running setup.py install for mozprocess 11:52:18 INFO - Running setup.py install for mozprofile 11:52:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:52:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:52:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:52:18 INFO - Running setup.py install for mozrunner 11:52:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:52:18 INFO - Running setup.py install for mozscreenshot 11:52:18 INFO - Running setup.py install for moztest 11:52:18 INFO - Running setup.py install for mozversion 11:52:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:52:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:52:18 INFO - Cleaning up... 11:52:18 INFO - Return code: 0 11:52:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52: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')]} 11:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:18 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 0x2342030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2603180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, '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': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:52:18 INFO - Using env: {'DISPLAY': ':0', 11:52:18 INFO - 'HOME': '/home/cltbld', 11:52:18 INFO - 'LANG': 'en_US.UTF-8', 11:52:18 INFO - 'LANGUAGE': 'en_US:en', 11:52:18 INFO - 'LOGNAME': 'cltbld', 11:52:18 INFO - 'MAIL': '/var/mail/cltbld', 11:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:18 INFO - 'MOZ_NO_REMOTE': '1', 11:52:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:18 INFO - 'NO_EM_RESTART': '1', 11:52:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:18 INFO - 'PWD': '/builds/slave/test', 11:52:18 INFO - 'SHELL': '/bin/bash', 11:52:18 INFO - 'SHLVL': '1', 11:52:18 INFO - 'TERM': 'linux', 11:52:18 INFO - 'TMOUT': '86400', 11:52:18 INFO - 'USER': 'cltbld', 11:52:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:18 INFO - '_': '/tools/buildbot/bin/python'} 11:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:18 INFO - Running setup.py (path:/tmp/pip-pQPpkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:18 INFO - Running setup.py (path:/tmp/pip-pcWXFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:19 INFO - Running setup.py (path:/tmp/pip-Wp5wXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:19 INFO - Running setup.py (path:/tmp/pip-KQY228-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:19 INFO - Running setup.py (path:/tmp/pip-i8yVH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:19 INFO - Running setup.py (path:/tmp/pip-XLG7xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:19 INFO - Running setup.py (path:/tmp/pip-CfN8zM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:19 INFO - Running setup.py (path:/tmp/pip-K7ma9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:19 INFO - Running setup.py (path:/tmp/pip-YchDoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:19 INFO - Running setup.py (path:/tmp/pip-0V6bv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:19 INFO - Running setup.py (path:/tmp/pip-81t89W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:19 INFO - Running setup.py (path:/tmp/pip-XyO64O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:19 INFO - Running setup.py (path:/tmp/pip-9j1ISU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:19 INFO - Running setup.py (path:/tmp/pip-iVbnv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:19 INFO - Running setup.py (path:/tmp/pip-S7qBeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:19 INFO - Running setup.py (path:/tmp/pip-UoL4Sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:20 INFO - Running setup.py (path:/tmp/pip-w0uMvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:52:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:52:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:52:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:52:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:52:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:52:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:52: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 11:52: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 11:52: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 11:52: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 11:52: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 11:52: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 11:52:22 INFO - Downloading blessings-1.6.tar.gz 11:52:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:52:22 INFO - Installing collected packages: blessings 11:52:22 INFO - Running setup.py install for blessings 11:52:22 INFO - Successfully installed blessings 11:52:22 INFO - Cleaning up... 11:52:22 INFO - Return code: 0 11:52:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:22 INFO - Reading from file tmpfile_stdout 11:52:22 INFO - Current package versions: 11:52:22 INFO - argparse == 1.2.1 11:52:22 INFO - blessings == 1.6 11:52:22 INFO - blobuploader == 1.2.4 11:52:22 INFO - docopt == 0.6.1 11:52:22 INFO - functools32 == 3.2.3-2 11:52:22 INFO - jsonschema == 2.5.1 11:52:22 INFO - manifestparser == 1.1 11:52:22 INFO - mozInstall == 1.12 11:52:22 INFO - mozcrash == 0.17 11:52:22 INFO - mozdebug == 0.1 11:52:22 INFO - mozdevice == 0.48 11:52:22 INFO - mozfile == 1.2 11:52:22 INFO - mozhttpd == 0.7 11:52:22 INFO - mozinfo == 0.9 11:52:22 INFO - mozleak == 0.1 11:52:22 INFO - mozlog == 3.2 11:52:22 INFO - moznetwork == 0.27 11:52:22 INFO - mozprocess == 0.23 11:52:22 INFO - mozprofile == 0.28 11:52:22 INFO - mozrunner == 6.12 11:52:22 INFO - mozscreenshot == 0.1 11:52:22 INFO - mozsystemmonitor == 0.3 11:52:22 INFO - moztest == 0.7 11:52:22 INFO - mozversion == 1.4 11:52:22 INFO - psutil == 3.1.1 11:52:22 INFO - requests == 1.2.3 11:52:22 INFO - wsgiref == 0.1.2 11:52:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:22 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 0x2342030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2603180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, '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': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:52:22 INFO - Using env: {'DISPLAY': ':0', 11:52:22 INFO - 'HOME': '/home/cltbld', 11:52:22 INFO - 'LANG': 'en_US.UTF-8', 11:52:22 INFO - 'LANGUAGE': 'en_US:en', 11:52:22 INFO - 'LOGNAME': 'cltbld', 11:52:22 INFO - 'MAIL': '/var/mail/cltbld', 11:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:22 INFO - 'MOZ_NO_REMOTE': '1', 11:52:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:22 INFO - 'NO_EM_RESTART': '1', 11:52:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:22 INFO - 'PWD': '/builds/slave/test', 11:52:22 INFO - 'SHELL': '/bin/bash', 11:52:22 INFO - 'SHLVL': '1', 11:52:22 INFO - 'TERM': 'linux', 11:52:22 INFO - 'TMOUT': '86400', 11:52:22 INFO - 'USER': 'cltbld', 11:52:22 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:22 INFO - '_': '/tools/buildbot/bin/python'} 11:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:52:22 INFO - Cleaning up... 11:52:22 INFO - Return code: 0 11:52:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2342030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2603180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, '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': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:52:22 INFO - Using env: {'DISPLAY': ':0', 11:52:22 INFO - 'HOME': '/home/cltbld', 11:52:22 INFO - 'LANG': 'en_US.UTF-8', 11:52:22 INFO - 'LANGUAGE': 'en_US:en', 11:52:22 INFO - 'LOGNAME': 'cltbld', 11:52:22 INFO - 'MAIL': '/var/mail/cltbld', 11:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:22 INFO - 'MOZ_NO_REMOTE': '1', 11:52:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:22 INFO - 'NO_EM_RESTART': '1', 11:52:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:22 INFO - 'PWD': '/builds/slave/test', 11:52:22 INFO - 'SHELL': '/bin/bash', 11:52:22 INFO - 'SHLVL': '1', 11:52:22 INFO - 'TERM': 'linux', 11:52:22 INFO - 'TMOUT': '86400', 11:52:22 INFO - 'USER': 'cltbld', 11:52:22 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:22 INFO - '_': '/tools/buildbot/bin/python'} 11:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:52:22 INFO - Cleaning up... 11:52:22 INFO - Return code: 0 11:52:22 INFO - Running post-action listener: _resource_record_post_action 11:52:22 INFO - Running post-action listener: _start_resource_monitoring 11:52:22 INFO - Starting resource monitoring. 11:52:22 INFO - ##### 11:52:22 INFO - ##### Finished create-virtualenv step (success) 11:52:22 INFO - ##### 11:52:22 INFO - ##### 11:52:22 INFO - ##### Running install step. 11:52:22 INFO - ##### 11:52:22 INFO - Running pre-action listener: _resource_record_pre_action 11:52:22 INFO - Running main action method: install 11:52:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:23 INFO - Reading from file tmpfile_stdout 11:52:23 INFO - Detecting whether we're running mozinstall >=1.0... 11:52:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:52:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:52:23 INFO - Reading from file tmpfile_stdout 11:52:23 INFO - Output received: 11:52:23 INFO - Usage: mozinstall [options] installer 11:52:23 INFO - Options: 11:52:23 INFO - -h, --help show this help message and exit 11:52:23 INFO - -d DEST, --destination=DEST 11:52:23 INFO - Directory to install application into. [default: 11:52:23 INFO - "/builds/slave/test"] 11:52:23 INFO - --app=APP Application being installed. [default: firefox] 11:52:23 INFO - mkdir: /builds/slave/test/build/application 11:52:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:52:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:52:40 INFO - Reading from file tmpfile_stdout 11:52:40 INFO - Output received: 11:52:40 INFO - /builds/slave/test/build/application/firefox/firefox 11:52:40 INFO - Running post-action listener: _resource_record_post_action 11:52:40 INFO - ##### 11:52:40 INFO - ##### Finished install step (success) 11:52:40 INFO - ##### 11:52:40 INFO - ##### 11:52:40 INFO - ##### Running run-tests step. 11:52:40 INFO - ##### 11:52:40 INFO - Running pre-action listener: _resource_record_pre_action 11:52:40 INFO - Running main action method: run_tests 11:52:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:52:40 INFO - Python 2.7.3 11:52:40 INFO - Return code: 0 11:52:40 INFO - grabbing minidump binary from tooltool 11:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:40 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 0x26013b0>, '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 0x26022b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2602740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:52:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:52:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:52:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:52:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:52:40 INFO - Return code: 0 11:52:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:52:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:52:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:52:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:52:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:52:40 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/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:52:40 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/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:52:40 INFO - Using env: {'DISPLAY': ':0', 11:52:40 INFO - 'HOME': '/home/cltbld', 11:52:40 INFO - 'LANG': 'en_US.UTF-8', 11:52:40 INFO - 'LANGUAGE': 'en_US:en', 11:52:40 INFO - 'LOGNAME': 'cltbld', 11:52:40 INFO - 'MAIL': '/var/mail/cltbld', 11:52:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:52:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:40 INFO - 'MOZ_NO_REMOTE': '1', 11:52:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:52:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:40 INFO - 'NO_EM_RESTART': '1', 11:52:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:40 INFO - 'PWD': '/builds/slave/test', 11:52:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:52:40 INFO - 'SHELL': '/bin/bash', 11:52:40 INFO - 'SHLVL': '1', 11:52:40 INFO - 'TERM': 'linux', 11:52:40 INFO - 'TMOUT': '86400', 11:52:40 INFO - 'USER': 'cltbld', 11:52:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223', 11:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:40 INFO - '_': '/tools/buildbot/bin/python'} 11:52:40 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/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:52:40 INFO - mozversion application_buildid: 20160713073201 11:52:40 INFO - mozversion application_changeset: dcb7835534e21a62cebdd5d1205d4e81f6bc6946 11:52:40 INFO - mozversion application_display_name: Nightly 11:52:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:52:40 INFO - mozversion application_name: Firefox 11:52:40 INFO - mozversion application_remotingname: firefox 11:52:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:52:40 INFO - mozversion application_vendor: Mozilla 11:52:40 INFO - mozversion application_version: 50.0a1 11:52:40 INFO - mozversion platform_buildid: 20160713073201 11:52:40 INFO - mozversion platform_changeset: dcb7835534e21a62cebdd5d1205d4e81f6bc6946 11:52:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:52:40 INFO - mozversion platform_version: 50.0a1 11:52:40 INFO - using testdate: 1468435960 11:52:40 INFO - actual date: 1468435960 11:52:40 INFO - starting webserver on 'localhost:53606' 11:52:40 INFO - SUITE-START | Running 2 tests 11:52:40 INFO - TEST-START | dromaeo_css 11:52:40 INFO - operating with platform_type : linux_ 11:52:40 INFO - Initialising browser for dromaeo_css test... 11:52:40 INFO - TEST-INFO | started process 21758 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphECPc3/profile http://localhost:53606/getInfo.html) 11:52:45 INFO - PROCESS | 21758 | __metrics Screen width/height:1600/1200 11:52:45 INFO - PROCESS | 21758 | colorDepth:24 11:52:45 INFO - PROCESS | 21758 | Browser inner width/height: 1024/697 11:52:45 INFO - PROCESS | 21758 | __metrics 11:52:46 INFO - PROCESS | 21758 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:52:46 INFO - PROCESS | 21758 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:52:46 INFO - PROCESS | 21758 | console.error: PushService: 11:52:46 INFO - PROCESS | 21758 | stateChangeProcessEnqueue: Error transitioning state 11:52:46 INFO - PROCESS | 21758 | UnknownError 11:52:47 INFO - TEST-INFO | 21758: exit 0 11:52:47 INFO - Browser initialized. 11:52:47 INFO - Running cycle 1/1 for dromaeo_css test... 11:52:47 INFO - TEST-INFO | started process 21841 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphECPc3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:52:58 INFO - PROCESS | 21841 | RSS: Main: 157687808 11:52:58 INFO - PROCESS | 21841 | 11:53:50 INFO - PROCESS | 21841 | 1468436030551 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:55:58 INFO - PROCESS | 21841 | Cycle 1(1): loaded http://localhost:53606/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53606/tests/dromaeo/cssquery-ext.html) 11:55:59 INFO - PROCESS | 21841 | RSS: Main: 289611776 11:55:59 INFO - PROCESS | 21841 | 11:59:02 INFO - PROCESS | 21841 | Cycle 1(1): loaded http://localhost:53606/tests/dromaeo/cssquery-ext.html (next: http://localhost:53606/tests/dromaeo/cssquery-jquery.html) 11:59:02 INFO - PROCESS | 21841 | RSS: Main: 244535296 11:59:02 INFO - PROCESS | 21841 | 12:02:04 INFO - PROCESS | 21841 | Cycle 1(1): loaded http://localhost:53606/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53606/tests/dromaeo/cssquery-mootools.html) 12:02:05 INFO - PROCESS | 21841 | RSS: Main: 240635904 12:02:05 INFO - PROCESS | 21841 | 12:05:07 INFO - PROCESS | 21841 | Cycle 1(1): loaded http://localhost:53606/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53606/tests/dromaeo/cssquery-prototype.html) 12:05:07 INFO - PROCESS | 21841 | RSS: Main: 243572736 12:05:07 INFO - PROCESS | 21841 | 12:08:10 INFO - PROCESS | 21841 | Cycle 1(1): loaded http://localhost:53606/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53606/tests/dromaeo/cssquery-yui.html) 12:08:11 INFO - PROCESS | 21841 | RSS: Main: 222371840 12:08:11 INFO - PROCESS | 21841 | 12:11:13 INFO - PROCESS | 21841 | Cycle 1(1): loaded http://localhost:53606/tests/dromaeo/cssquery-yui.html (next: http://localhost:53606/tests/dromaeo/cssquery-dojo.html) 12:11:14 INFO - PROCESS | 21841 | RSS: Main: 315289600 12:11:14 INFO - PROCESS | 21841 | 12:11:14 INFO - PROCESS | 21841 | __start_tp_report 12:11:14 INFO - PROCESS | 21841 | _x_x_mozilla_page_load 12:11:14 INFO - PROCESS | 21841 | _x_x_mozilla_page_load_details 12:11:14 INFO - PROCESS | 21841 | |i|pagename|runs| 12:11:14 INFO - PROCESS | 21841 | |0;dojo.html;3033.105233955983;3049.801762885411;3086.8765249390017;3090.7681923855703;3102.922426939326;5196.220566914964;5243.187305967572;5278.340207474066;5374.435684253156;5436.836894893155;5355.3305836770405;5362.731863406833;5364.5708343332535;5397.136458166694;5426.565874730022;4815.494373090819;4862.97568512157;4918.385201849769;4927.236278376858;5083.74581270935;4973.5772459341;4981.800727970877;5019;5039.974800125994;5049.192129259319;4839.9274010889685;4849.854504364875;4887.560119589236;4902.730349830758;4922.729249891255;4820.542048505386;4841.3222148899185;4856.223004319292;4857.951420485785;4960.528749768802;4874.366332376804;4897.069556784199;4908;4908.607311415079;4976.950230497721;5242.423333433319;5312.893742125175;5340.813071542478;5341.332333458317;5380.21986811914;5747.827565173052;5748.338941021752;5751;5801.7389217485315;5813.302403711539;5764.625299355571;5796.043652797283;5823.4467725566;5850.414958504116;5852.356240813507;5236.423993360727;5261.316028916254;5367.141257398797;5372.247885296023;5447.509724124848;5917.704114794329;5932.228810254639;5933.169356290123;5936.079907614327;5937.435943585352;5707.143928410772;5708.40061793514;5721.370649228582;5723.570732195102;5727.284089488814;5508.834734957958;5707.914381284284;5728.312602487728;5806;5808.215890854765;5706.771729130788;5714.314282286069;5716.857078573069;5734.483896449339;5781.6241944274325;5674.460926212003;5686.886262274731;5739.225204597409;5755.62588431759;5773.624714393551;4302.612764851116;4329.19909816685;4351.3472979053395;4354.629856462237;4375.365571992043;3897.1231472918366;3903.2193561287854;3905.1213476967455;3940.7044471664167;3944.36890097583;5424.105022671215;5436.972815135978;5438.374586922476;5448.237246785453;5453.209284653783;5634.915476267777;5714.0571805652435;5760.94239057604;5790.334111577138;5815.94184058154;5419.376771671308;5444.237806707064;5446.346438427414;5449.128139497661;5489.972550137224;4864.489228631013;5005.649604527648;5012.24816277554;5022.447530771612;5066.695998240118;5778.913316300259;5783.768649253982;5810.593258471867;5873.089671100986;5883.941160588425;5721.084626459747;5723.5707321950185;5724.084146536534;5724.227229323988;5724.599278050663;5763.971180144073;5837.620554663933;5857.736401862012;5861.736221869941;5867.853303667443;5621.437856214346;5684.261046063985;5689.857753556194;5693.971530142323;5745.712714364181;5750.769969201352;5765.884682306414;5775.9133612996675;5792.247007888947;5795.420457954339;5545.223668686468;5575.721213939206;5609.691466969356;5618.803341883015;5674.574406919415;5617.101263797773;5660.320761508646;5660.405657406078;5669.489745922887;5689.203511508475;5572.86067848307;5584.581156413366;5586.050371436947;5590.273264475592;5599.944000560105;5515.751791169327;5527.088030475087;5579.888402231852;5657.688827114548;5669.518090962314;5465.098258787256;5467.9453205467435;5475.151351540518;5475.917861232005;5513.117901135799 12:11:14 INFO - PROCESS | 21841 | |1;ext.html;568.6275489554341;619.2012304127676;625.6777759453882;625.7184267079815;633.7116611941567;30005.84997075017;43476;44213.557864421346;44267;44325;45296.54703452973;45563.08873822522;45764.77117614407;45800.541994580126;45847.083058338816;8996.3702540822;9140.131687489711;9143.497107659075;9147.908520914772;9149.862752058725;8967.820643587125;9021.819563608724;9048.095190480932;9057.320700947423;9058.456492610465;9046.276297896153;9104.226140778042;9108.22580080694;9120.589573469177;9120.908790912072;9058.320625953047;9089.091090890925;9090.86363704545;9105.362624616246;9110.27117830572;8925.464472131627;9026.774330641721;9027.909720902773;9028.77428064297;9036.231920286784;43378.56621433777;43790.34314485285;43791.56208437907;43822.78088609537;43825.34261986041;3357.2949680567112;3371.814550199738;3674.889753307405;3683.244934788362;3726.9627303727157;33173.50239746406;33732.83133584316;34004.6599534004;34163.829180853936;34238;4341.804618792162;4387.561243875619;4390.768483599021;4421.403110580062;4502.077074199781;373251;374208;374844;375258;375794.12102939584;61387.38612613817;61916.38083619106;62378.37621623726;62414.375856240855;62420.37579624236;65707;66297.33702662912;66371.33628663748;66519;66582;17301.480955571355;17505.299788008593;17506.562335941704;17524.649507010043;17533.386331478338;13538.255395953318;13589.592312230649;13659.726805464034;13674.93162534201;13677.042607017413;18740.21793698111;19811.504712382306;19831.801681983285;19952.800471995095;19963.60072798536;7145.571265723969;7198.7840364789145;7271.563706177541;7281.563106213538;7464.738734144279;981.293468702533;1007.5214273220308;1030.2376241581176;1085.0560012788767;1103.4096758234307;33321.666783332344;33485;33578;33695.32609347799;33737;1102.597551893569;1110.4059328259505;1128.368113856243;1134.6766171641207;1147.8966892979672;16993.91503042477;17234.310627575007;17291.221895014754;17372.652546949244;17423.912880435517;594684;599705.0265476129;602417;602817;604746;59932.70033649804;60586;60698.69650651718;60810;60833;1249.425264378378;1347.9528216512374;1359.9796003059962;1381.7789153735555;1389.1040279020178;33650.3269934595;34720.652793471745;35121;35245.29509409849;35476.46785298171;56114.43885561092;58051.4194858046;58086.4191358081;62911.685441572496;62983.6850815743;577879;596168;596220;600925;601267;39760.40359394669;44307.11385772202;44689;45958.080838382375;46032.769836150605;49743.50256497389;50106.248406274666;50210;51473.22790158076;51505.48494515007;42592.74072592716;42825.57174428341;42836.571634284504;44003.77998109989;44362;42506.78746606247;42782.57217427786;43028.13943721171;43158.13683726371;43802.78098609487;41967.580324196366;42329.78835105805;42788;44732.8525059524;45040.324395133444;36703.632963670025;38156.42765358577;38160.61839381682;38461.80769096137;38897.41653875138;28525;29114.41771164607;29162.56256156202;29407;29689.406211875208 12:11:14 INFO - PROCESS | 21841 | |2;jquery.html;366.25649930640793;369.5343866727922;373.9626037396259;378.56654131019985;379.37592660074193;18528.07359632018;19919.203231870742;20080.99595020247;20275;20435.386938391835;18807;18966.71549926752;19521.609567808635;19537.804621953775;20066.29767958118;5069.88462538241;5079.6190285728535;5202.843914682566;5209.270702101709;5271.341082364705;5164.9483505165035;5226.529612334889;5233;5264.8683782905355;5270.288511050997;5266.8419947401635;5269.262303277545;5270.077736396134;5273.129933560977;5558.749856256478;5786.247787787603;5858.619189752652;5877.118432235156;5891.793787217448;5895;4867.172580661296;5149.48505149482;5167.302414174113;5167.379914410295;5175.689458632494;13489.797653035212;14354.641133971734;22797.772022279732;22991.425214369607;23011.42471438211;2264.830137739676;2359.7050368703913;2359.8112151027876;2439;2819.252897982016;10181.949090254575;10240.539175737109;10350.171986241083;12478.064145189057;14658.780118298235;1852.2590963614527;1853.9907300463547;1916.1377380178976;1923.422973108062;1941.0876887862682;80802;81563;83357.58321208355;84562.15437845605;84995.15004849934;10531.89468105309;10536.157107431387;10679.78640427187;10681.893181068246;10817.891821081688;14537;14636.853631463762;14656.926715366568;14667.706645867236;14671.486497972519;7746.457747957702;7796.532208067534;7998.880016799752;8033.959830200928;8228.30059444942;7665.23347665229;7794.41541884361;7805.648745806338;7933.098204562068;8022.79943001418;4245.8513952012145;4302.677299202573;4361.280388735887;4363.2364336241035;4444.8444304449185;1776.9822301777076;1806.8102849200645;1889.2159753702335;1946.5814849807077;1950.6781381071964;880.4277219807177;1070.9303895246785;1072.8766191887876;1075.5805235957982;1086.875009373932;19347.903260483607;19994.400167994983;20071.899640501997;20098.09558569868;20133.798662013192;976.2531663277598;1030.175859312547;1050.5062620568322;1060.5280650110626;1063.1707268330715;10642.201834862417;10899.564017439214;10925.672229833117;11008;11144.832827507595;489624;492574;497954;500983;510237;67798.66100669465;87406;93527.53236233912;95291.52354238323;95920.52039739757;968.2205824311357;981.2444417798256;1022.0494939705997;1023.2019025160387;1027.2501074215863;12499.687507812378;17196.14019299005;17408.82591174072;18426.447206583824;18763.437096887115;29920.25199370033;41007.06502215323;41491.7925410371;42670.57329426666;42927.785361072994;56485;57521.71239143777;58185;58210.12684809816;58529;7854.6858125674335;8268.545230012407;8738.038815730137;8816.691475538773;8877.334199934901;8643.697470588499;12674.619761407173;12829.935850321062;12993.610191694264;13194.736105278032;11101.66694999119;11464;11713.88286117128;11983.700407489883;12164.2701437914;11092;11256.662300131009;11438.485268162785;11534;11628.127890408085;11302.43487825589;11865.822012669989;12006.579769708018;12175.086868485076;12236;8213.425060245965;8567.74296771098;8709.085546017577;8832.072632373765;8834.249088827522;11213.775724485302;11552.6534203974;11584.710382240512;11808.645740627795;11815.763684726086 12:11:14 INFO - PROCESS | 21841 | |3;mootools.html;1499.8050253467052;1528.1289664891012;1666.0015961691938;1776.9022703751289;1794.5693033671923;8536.95731521343;9029.232515236214;9119.179273865351;9133.269338452916;9140.26877849772;7520.84958300835;7697.846043079149;7702.0757509098985;7946.92053079469;8961;2092.3618296419586;2172.6197915364746;2182.50893548951;2189.9452513687206;2194.6049711052005;2195.2865318771383;2200.471886747177;2220.322801545528;2220.522587643656;2237.2281562860785;1862.9161687724018;2124.7875212478707;2160.1899287767087;2166.2309879992613;2166.3176099528678;2203.6914831923546;2227.3429338345227;2232.2521955145044;2232.598132336179;2234.474898398867;2152.7416709994745;2162.4053385319007;2184.0936011555345;2188.6060509108356;2191.0468946008536;8996;9049.864252036225;9062.365634405594;9081.772955676095;9083.182513573744;4995.075910956449;5174.611904107207;5216.2958000669805;5225.190095535198;5233.790648374061;3268.9182770430693;3373.4939759036097;3399.524066630649;3408.454647256427;3412.1128506588216;1026.4970164619358;1057.349729916096;1092.7104317355906;1093.0545078507114;1094.4746521669642;12483.750324993449;12532.310722910237;12576.74846503074;12593.118481706284;12600.810987835099;4273.444452221253;4487.955120448819;4568.17772800899;4627.884302892455;4635.327877457749;10566.947165264279;10599.046085852311;10617.84073238902;10618.044376006197;10620.415877126832;5224;5239.580833533309;5253.238280449314;5254.15933450646;5259.0796610593;4703.412073490814;4832.49258827818;4834.371531700856;4835.178019736653;4890.1931181355385;3886.9611303887164;3888.7083468739957;3924.548676902136;3960.5841386653988;4008.6993475489453;1192.4753108632174;1195.7668254690252;1208.510553225945;1222.1200735470447;1234.3334599316447;877.8463768840427;1021.560728886571;1027.8920713325142;1029.2023681646783;1033.1682995188794;6106.358832322631;6251.093591429218;6289.55973081889;6298.937010629927;6359.427651511293;424.55633862613644;425.3194888178951;428.0945799633814;429.467460349171;436.6288654643591;4297.097609501977;4302.569743025735;4344.131173765197;4359.67302452311;4367.061081867414;10649.9467502662;11236.213465057532;11257.437128143723;11293.265937714023;11309.264897781617;10594.894051059391;11121.721956951142;11158.832617510581;11178;11182.384968826793;8233.752987410388;8340.124286949784;8388.790280243044;8398.958005209935;8419.56867332555;6031.547633927561;6307.274663413767;6352.333005034405;6365.61806291624;6384.106225128578;10237.84643230367;10430.791384172426;10433.739156521147;10527.210459215436;10582.947085264525;9051.637934482547;9835.213182945205;10283.845742313722;10286.639967601099;10585.417802020655;6635.767748128792;7923.049234091945;7950.602469876599;7952.483088599222;7953.6818527260575;7549.7357592483995;8594.312455003459;8742.388032837895;8763.3427492937;8769.868451973098;7520.435967302365;7650.846983060419;7728.072631284282;7785.221477852396;8068.636911338886;7841.803954901173;7852.6073696313815;7917.524948503108;7948.562829044226;8026.478278911852;7163.247858974941;7214.567125972459;7217.133943926762;7270;7346.595937223504;5108.795648174031;5149.098907691214;5150.1502252127975;5263.815766448156;5290.44450332725;5236.240745092016;5294.841154765363;5326.547243484192;5360.571154307724;5407.459254074718 12:11:14 INFO - PROCESS | 21841 | |4;prototype.html;309.98605062772174;316.30728704137937;322.4196446396486;322.4325187669701;324.7921330348577;5220.138677118276;5266.3680358357005;5295.629305948585;5320.361556613207;5370.865728356793;5031.440253521402;5152.639315247934;5162.716050617215;5164.586833053356;5297.602679799012;4001.7598944063448;4082.285600019992;4147.979260103695;4162.354835000579;4200.327947528396;4132.690048246393;4169.499660040799;4172;4174.70777045607;4178.623923846853;4024.7786371749544;4049.3318602430563;4105.199486100211;4110.1163249901265;4141.378793181017;4078.163976384864;4090.6727461802984;4108.897277568055;4115.238680844053;4115.518413371184;3622.1850083731215;3640.9453858192146;3641.198936234024;3642.289753498069;3651.3062518121474;4962.602991760687;5106.846794596168;5130.30740849988;5148.279240906276;5190.06578815813;4681.438227412698;4686.36734040907;4703.905921881543;4704.811807527695;4706.576408123286;5516.448355164492;5531.446855314477;5538.335399752015;5540.36285827127;5560.997061586739;3846.6345697158727;3934.390169523728;3936.881893543198;3942.310095733264;3950.1309711863346;5638.407967163471;5724.255846739898;5730.481422423061;5730.971345143247;5747.252857128547;4010.959890401117;4020.778857162884;4021.738586991836;4025.19496100781;4027.154297597479;4059.4722686050623;4072.2873497138476;4073.837046518106;4076.9184616308103;4080.163566468867;4516.774161291923;4646.4191976003;4654.627629789608;4669.879228985019;4678.409340824136;5522.392536820947;5531.585131115183;5549.5837812163445;5550.417206193291;5572.58205634579;1336.1381908668855;1365.3241645385597;1367.9521216757366;1376.8072469854228;1384.7715127004133;914;931.65063101337;934.9205317547949;935.2471260635253;944.5466176235308;5317.760700768475;5322.467753224724;5326.174442961347;5328.6802791832615;5349;5590.021746194399;5596.664200148004;5621.269234999424;5639.718014099279;5642.548596112383;5166.61250406221;5298.788048478094;5305.920411193835;5318.095923692981;5384.623076384615;3016;3019.8037127587077;3021.4108248891694;3035.4822588705647;3044.43211745951;5853.678047707417;5854.648721076749;5860.677662728591;5864.472197502245;5889.146073819272;5640.464155905182;5824;5838.591298609142;5851.268591426072;5851.882962340729;5830.47525722687;5839.240898683144;5845;5857.238558987305;5878.147668587945;5460.726963651881;5670.971645141748;5703.20155178267;5723.227364305765;5795.971020144872;5730.5129064030025;5741.770329186786;5747.442388137239;5752;5753.252077229933;5892.793752218652;5895.263092113486;5906.232189815297;5924.11138329254;5934.851628709317;3907.843686252518;3996.140829721624;4000.4999375078114;4009.8797036088963;4011.7793521356607;5469.671819690831;5684.9017285580285;5689.146628005832;5729.942700572941;5741.167530708108;5459.890802184013;5590.881823635208;5594.496495315441;5637.3235211774845;5638.5489160867855;5595.160725890986;5610.354809196994;5612.579056570692;5614.691191984317;5618.550515958632;5300.70846103468;5524.447555244444;5532.889342213214;5534.308211473566;5549.5837812163445;5594.41258667834;5616.354119276336;5628.662280263197;5634.380218176079;5663.801766938137;5479.506844384025;5488.780448782004;5508.641938274;5525;5544.556435485232 12:11:14 INFO - PROCESS | 21841 | |5;yui.html;716.7777988823468;727.5561907236587;731.1664702239448;755.3994574313421;784.9411294152939;15018.77471837922;16540.09029503377;16633.66732665346;16635.334586616536;16684.916575417104;14627.195504247262;15116;15431.845681543182;15442.073475591473;15477.845221547781;669.2872091222856;805.0701439836997;826.2522417212432;829.1087081387502;831.7380025292044;790.3282210121408;818.8362327534517;820.983580328393;822.0300045945781;822.4859462835727;854.7350321400355;856.2550580994543;856.4090777363599;857.4426622695236;857.9356548258875;686.4679873098339;711.1501755402296;849.7833052571585;856.9185927336893;857.6998050682242;523.0872128136426;597.5637784417356;621.0125899819309;621.2327775197627;624.4192900602438;15332.693346133126;15366.078035317805;15379.077255364715;15393.538193854203;15439.228038598138;14274.286285685674;14558.272086395744;14634.41462341505;14636.780448293284;14654.706905861929;12482.50069997199;12486;12492.75014499705;12526;13166.802497962539;4532.138893610237;4553.68124231304;4665.393498845162;4768.451628062782;4832.540908613676;79014;79131.20868791296;79464.60267698625;80187;80576.19423805746;11502.367369794574;11643.41782910851;11659.941700291443;11690.824637630443;12101.213421127599;21371.572568548854;21455.141794328363;21519.246826361003;21575.2448664296;21608.13567457284;8471.872921906177;8543.974723033152;8545.615447304886;8592.699255526026;8605.182507661762;4686.203345431285;4828.927566086511;4858.562729354375;4886.902261954812;4887.975560122248;3543.309054734302;3638.272345530904;3647.361711700442;3648.8175591220333;3740.3267411866127;671.6809515480107;678.5894533807075;680.8187794177098;685.2359619024752;691.4882986589887;684.2302409788988;700.551646945955;726.9781906542812;748.2666986353404;783.635609441601;9855.45794981283;10249;10467.424291664032;10550.261481696207;10718.732031699114;616.8642898562309;624.372505631837;716.3874886971669;745.8545583611252;761.6800943603699;4565;4701.670883038223;4780.330753694486;4801.591864691471;4825.445073816486;93451;130047;130952.34523827319;132013.33993329972;137347;90975;94973.52513237389;95949;96144.51927740316;96272.51863740636;55951;62178.68910655508;79824.20175798284;86847.56576217078;87001.90986630875;9692.951535242279;9902.653407130716;10216.642417515352;10230.693079207636;10258.487075646337;38865;43005.56994430016;44053.33919991139;53863.46136538585;55215.447845522634;74254.9457801508;74721;78154.2184578147;78455;78619.21380786347;13524.459021639417;13533.458661653818;13813.480517142905;13820.861791381958;14051.156930584199;15134.697306053597;15266.770998435255;15430.151341676315;15532.301046453164;15748.448804292253;10484.895151048697;12871.742565148457;13003.609891703263;13110.147840390344;13398.196108233537;13587.52443664467;14208.147511149364;14267.64330891736;14441.277936103363;14945.850541494447;13443.193408395528;13494.679161372098;14141.717165656835;14226.104317444473;14636.121832690073;10672.039516443558;10720.57988908042;10930.945345273223;10967.067799237155;11066.170037247077;11095.278806877526;11847.76304473923;12049.698757531132;12117.333546655018;12230.266184028987 12:11:14 INFO - PROCESS | 21841 | __end_tp_report 12:11:14 INFO - PROCESS | 21841 | __start_cc_report 12:11:14 INFO - PROCESS | 21841 | _x_x_mozilla_cycle_collect,1046 12:11:14 INFO - PROCESS | 21841 | __end_cc_report 12:11:14 INFO - PROCESS | 21841 | __startTimestamp1468437074202__endTimestamp 12:11:14 INFO - PROCESS | 21841 | 12:11:14 INFO - PROCESS | 21841 | ------- Summary: start ------- 12:11:14 INFO - PROCESS | 21841 | Number of tests: 6 12:11:14 INFO - PROCESS | 21841 | 12:11:14 INFO - PROCESS | 21841 | [#0] dojo.html Cycles:165 Average:5335.81 Median:5527.09 stddev:605.78 (11.0%) stddev-sans-first:580.08 12:11:14 INFO - PROCESS | 21841 | Values: 3033.1 3049.8 3086.9 3090.8 3102.9 5196.2 5243.2 5278.3 5374.4 5436.8 5355.3 5362.7 5364.6 5397.1 5426.6 4815.5 4863.0 4918.4 4927.2 5083.7 4973.6 4981.8 5019.0 5040.0 5049.2 4839.9 4849.9 4887.6 4902.7 4922.7 4820.5 4841.3 4856.2 4858.0 4960.5 4874.4 4897.1 4908.0 4908.6 4977.0 5242.4 5312.9 5340.8 5341.3 5380.2 5747.8 5748.3 5751.0 5801.7 5813.3 5764.6 5796.0 5823.4 5850.4 5852.4 5236.4 5261.3 5367.1 5372.2 5447.5 5917.7 5932.2 5933.2 5936.1 5937.4 5707.1 5708.4 5721.4 5723.6 5727.3 5508.8 5707.9 5728.3 5806.0 5808.2 5706.8 5714.3 5716.9 5734.5 5781.6 5674.5 5686.9 5739.2 5755.6 5773.6 4302.6 4329.2 4351.3 4354.6 4375.4 3897.1 3903.2 3905.1 3940.7 3944.4 5424.1 5437.0 5438.4 5448.2 5453.2 5634.9 5714.1 5760.9 5790.3 5815.9 5419.4 5444.2 5446.3 5449.1 5490.0 4864.5 5005.6 5012.2 5022.4 5066.7 5778.9 5783.8 5810.6 5873.1 5883.9 5721.1 5723.6 5724.1 5724.2 5724.6 5764.0 5837.6 5857.7 5861.7 5867.9 5621.4 5684.3 5689.9 5694.0 5745.7 5750.8 5765.9 5775.9 5792.2 5795.4 5545.2 5575.7 5609.7 5618.8 5674.6 5617.1 5660.3 5660.4 5669.5 5689.2 5572.9 5584.6 5586.1 5590.3 5599.9 5515.8 5527.1 5579.9 5657.7 5669.5 5465.1 5467.9 5475.2 5475.9 5513.1 12:11:14 INFO - PROCESS | 21841 | 12:11:14 INFO - PROCESS | 21841 | [#1] ext.html Cycles:180 Average:68779.05 Median:24197.27 stddev:142154.84 (587.5%) stddev-sans-first:142461.37 12:11:14 INFO - PROCESS | 21841 | Values: 568.6 619.2 625.7 625.7 633.7 30005.8 43476.0 44213.6 44267.0 44325.0 45296.5 45563.1 45764.8 45800.5 45847.1 8996.4 9140.1 9143.5 9147.9 9149.9 8967.8 9021.8 9048.1 9057.3 9058.5 9046.3 9104.2 9108.2 9120.6 9120.9 9058.3 9089.1 9090.9 9105.4 9110.3 8925.5 9026.8 9027.9 9028.8 9036.2 43378.6 43790.3 43791.6 43822.8 43825.3 3357.3 3371.8 3674.9 3683.2 3727.0 33173.5 33732.8 34004.7 34163.8 34238.0 4341.8 4387.6 4390.8 4421.4 4502.1 373251.0 374208.0 374844.0 375258.0 375794.1 61387.4 61916.4 62378.4 62414.4 62420.4 65707.0 66297.3 66371.3 66519.0 66582.0 17301.5 17505.3 17506.6 17524.6 17533.4 13538.3 13589.6 13659.7 13674.9 13677.0 18740.2 19811.5 19831.8 19952.8 19963.6 7145.6 7198.8 7271.6 7281.6 7464.7 981.3 1007.5 1030.2 1085.1 1103.4 33321.7 33485.0 33578.0 33695.3 33737.0 1102.6 1110.4 1128.4 1134.7 1147.9 16993.9 17234.3 17291.2 17372.7 17423.9 594684.0 599705.0 602417.0 602817.0 604746.0 59932.7 60586.0 60698.7 60810.0 60833.0 1249.4 1348.0 1360.0 1381.8 1389.1 33650.3 34720.7 35121.0 35245.3 35476.5 56114.4 58051.4 58086.4 62911.7 62983.7 577879.0 596168.0 596220.0 600925.0 601267.0 39760.4 44307.1 44689.0 45958.1 46032.8 49743.5 50106.2 50210.0 51473.2 51505.5 42592.7 42825.6 42836.6 44003.8 44362.0 42506.8 42782.6 43028.1 43158.1 43802.8 41967.6 42329.8 42788.0 44732.9 45040.3 36703.6 38156.4 38160.6 38461.8 38897.4 28525.0 29114.4 29162.6 29407.0 29689.4 12:11:14 INFO - PROCESS | 21841 | 12:11:14 INFO - PROCESS | 21841 | [#2] jquery.html Cycles:180 Average:28948.72 Median:12685.56 stddev:82119.66 (647.3%) stddev-sans-first:82321.98 12:11:14 INFO - PROCESS | 21841 | Values: 366.3 369.5 374.0 378.6 379.4 18528.1 19919.2 20081.0 20275.0 20435.4 18807.0 18966.7 19521.6 19537.8 20066.3 5069.9 5079.6 5202.8 5209.3 5271.3 5164.9 5226.5 5233.0 5264.9 5270.3 5266.8 5269.3 5270.1 5273.1 5558.7 5786.2 5858.6 5877.1 5891.8 5895.0 4867.2 5149.5 5167.3 5167.4 5175.7 13489.8 14354.6 22797.8 22991.4 23011.4 2264.8 2359.7 2359.8 2439.0 2819.3 10181.9 10240.5 10350.2 12478.1 14658.8 1852.3 1854.0 1916.1 1923.4 1941.1 80802.0 81563.0 83357.6 84562.2 84995.2 10531.9 10536.2 10679.8 10681.9 10817.9 14537.0 14636.9 14656.9 14667.7 14671.5 7746.5 7796.5 7998.9 8034.0 8228.3 7665.2 7794.4 7805.6 7933.1 8022.8 4245.9 4302.7 4361.3 4363.2 4444.8 1777.0 1806.8 1889.2 1946.6 1950.7 880.4 1070.9 1072.9 1075.6 1086.9 19347.9 19994.4 20071.9 20098.1 20133.8 976.3 1030.2 1050.5 1060.5 1063.2 10642.2 10899.6 10925.7 11008.0 11144.8 489624.0 492574.0 497954.0 500983.0 510237.0 67798.7 87406.0 93527.5 95291.5 95920.5 968.2 981.2 1022.0 1023.2 1027.3 12499.7 17196.1 17408.8 18426.4 18763.4 29920.3 41007.1 41491.8 42670.6 42927.8 56485.0 57521.7 58185.0 58210.1 58529.0 7854.7 8268.5 8738.0 8816.7 8877.3 8643.7 12674.6 12829.9 12993.6 13194.7 11101.7 11464.0 11713.9 11983.7 12164.3 11092.0 11256.7 11438.5 11534.0 11628.1 11302.4 11865.8 12006.6 12175.1 12236.0 8213.4 8567.7 8709.1 8832.1 8834.2 11213.8 11552.7 11584.7 11808.6 11815.8 12:11:14 INFO - PROCESS | 21841 | 12:11:14 INFO - PROCESS | 21841 | [#3] mootools.html Cycles:180 Average:5832.15 Median:2398.15 stddev:3395.30 (141.6%) stddev-sans-first:3389.21 12:11:14 INFO - PROCESS | 21841 | Values: 1499.8 1528.1 1666.0 1776.9 1794.6 8537.0 9029.2 9119.2 9133.3 9140.3 7520.8 7697.8 7702.1 7946.9 8961.0 2092.4 2172.6 2182.5 2189.9 2194.6 2195.3 2200.5 2220.3 2220.5 2237.2 1862.9 2124.8 2160.2 2166.2 2166.3 2203.7 2227.3 2232.3 2232.6 2234.5 2152.7 2162.4 2184.1 2188.6 2191.0 8996.0 9049.9 9062.4 9081.8 9083.2 4995.1 5174.6 5216.3 5225.2 5233.8 3268.9 3373.5 3399.5 3408.5 3412.1 1026.5 1057.3 1092.7 1093.1 1094.5 12483.8 12532.3 12576.7 12593.1 12600.8 4273.4 4488.0 4568.2 4627.9 4635.3 10566.9 10599.0 10617.8 10618.0 10620.4 5224.0 5239.6 5253.2 5254.2 5259.1 4703.4 4832.5 4834.4 4835.2 4890.2 3887.0 3888.7 3924.5 3960.6 4008.7 1192.5 1195.8 1208.5 1222.1 1234.3 877.8 1021.6 1027.9 1029.2 1033.2 6106.4 6251.1 6289.6 6298.9 6359.4 424.6 425.3 428.1 429.5 436.6 4297.1 4302.6 4344.1 4359.7 4367.1 10649.9 11236.2 11257.4 11293.3 11309.3 10594.9 11121.7 11158.8 11178.0 11182.4 8233.8 8340.1 8388.8 8399.0 8419.6 6031.5 6307.3 6352.3 6365.6 6384.1 10237.8 10430.8 10433.7 10527.2 10582.9 9051.6 9835.2 10283.8 10286.6 10585.4 6635.8 7923.0 7950.6 7952.5 7953.7 7549.7 8594.3 8742.4 8763.3 8769.9 7520.4 7650.8 7728.1 7785.2 8068.6 7841.8 7852.6 7917.5 7948.6 8026.5 7163.2 7214.6 7217.1 7270.0 7346.6 5108.8 5149.1 5150.2 5263.8 5290.4 5236.2 5294.8 5326.5 5360.6 5407.5 12:11:14 INFO - PROCESS | 21841 | 12:11:14 INFO - PROCESS | 21841 | [#4] prototype.html Cycles:180 Average:4667.09 Median:5324.32 stddev:1384.64 (26.0%) stddev-sans-first:1349.35 12:11:14 INFO - PROCESS | 21841 | Values: 310.0 316.3 322.4 322.4 324.8 5220.1 5266.4 5295.6 5320.4 5370.9 5031.4 5152.6 5162.7 5164.6 5297.6 4001.8 4082.3 4148.0 4162.4 4200.3 4132.7 4169.5 4172.0 4174.7 4178.6 4024.8 4049.3 4105.2 4110.1 4141.4 4078.2 4090.7 4108.9 4115.2 4115.5 3622.2 3640.9 3641.2 3642.3 3651.3 4962.6 5106.8 5130.3 5148.3 5190.1 4681.4 4686.4 4703.9 4704.8 4706.6 5516.4 5531.4 5538.3 5540.4 5561.0 3846.6 3934.4 3936.9 3942.3 3950.1 5638.4 5724.3 5730.5 5731.0 5747.3 4011.0 4020.8 4021.7 4025.2 4027.2 4059.5 4072.3 4073.8 4076.9 4080.2 4516.8 4646.4 4654.6 4669.9 4678.4 5522.4 5531.6 5549.6 5550.4 5572.6 1336.1 1365.3 1368.0 1376.8 1384.8 914.0 931.7 934.9 935.2 944.5 5317.8 5322.5 5326.2 5328.7 5349.0 5590.0 5596.7 5621.3 5639.7 5642.5 5166.6 5298.8 5305.9 5318.1 5384.6 3016.0 3019.8 3021.4 3035.5 3044.4 5853.7 5854.6 5860.7 5864.5 5889.1 5640.5 5824.0 5838.6 5851.3 5851.9 5830.5 5839.2 5845.0 5857.2 5878.1 5460.7 5671.0 5703.2 5723.2 5796.0 5730.5 5741.8 5747.4 5752.0 5753.3 5892.8 5895.3 5906.2 5924.1 5934.9 3907.8 3996.1 4000.5 4009.9 4011.8 5469.7 5684.9 5689.1 5729.9 5741.2 5459.9 5590.9 5594.5 5637.3 5638.5 5595.2 5610.4 5612.6 5614.7 5618.6 5300.7 5524.4 5532.9 5534.3 5549.6 5594.4 5616.4 5628.7 5634.4 5663.8 5479.5 5488.8 5508.6 5525.0 5544.6 12:11:14 INFO - PROCESS | 21841 | 12:11:14 INFO - PROCESS | 21841 | [#5] yui.html Cycles:180 Average:20864.87 Median:4559.34 stddev:30208.78 (662.6%) stddev-sans-first:30255.64 12:11:14 INFO - PROCESS | 21841 | Values: 716.8 727.6 731.2 755.4 784.9 15018.8 16540.1 16633.7 16635.3 16684.9 14627.2 15116.0 15431.8 15442.1 15477.8 669.3 805.1 826.3 829.1 831.7 790.3 818.8 821.0 822.0 822.5 854.7 856.3 856.4 857.4 857.9 686.5 711.2 849.8 856.9 857.7 523.1 597.6 621.0 621.2 624.4 15332.7 15366.1 15379.1 15393.5 15439.2 14274.3 14558.3 14634.4 14636.8 14654.7 12482.5 12486.0 12492.8 12526.0 13166.8 4532.1 4553.7 4665.4 4768.5 4832.5 79014.0 79131.2 79464.6 80187.0 80576.2 11502.4 11643.4 11659.9 11690.8 12101.2 21371.6 21455.1 21519.2 21575.2 21608.1 8471.9 8544.0 8545.6 8592.7 8605.2 4686.2 4828.9 4858.6 4886.9 4888.0 3543.3 3638.3 3647.4 3648.8 3740.3 671.7 678.6 680.8 685.2 691.5 684.2 700.6 727.0 748.3 783.6 9855.5 10249.0 10467.4 10550.3 10718.7 616.9 624.4 716.4 745.9 761.7 4565.0 4701.7 4780.3 4801.6 4825.4 93451.0 130047.0 130952.3 132013.3 137347.0 90975.0 94973.5 95949.0 96144.5 96272.5 55951.0 62178.7 79824.2 86847.6 87001.9 9693.0 9902.7 10216.6 10230.7 10258.5 38865.0 43005.6 44053.3 53863.5 55215.4 74254.9 74721.0 78154.2 78455.0 78619.2 13524.5 13533.5 13813.5 13820.9 14051.2 15134.7 15266.8 15430.2 15532.3 15748.4 10484.9 12871.7 13003.6 13110.1 13398.2 13587.5 14208.1 14267.6 14441.3 14945.9 13443.2 13494.7 14141.7 14226.1 14636.1 10672.0 10720.6 10930.9 10967.1 11066.2 11095.3 11847.8 12049.7 12117.3 12230.3 12:11:14 INFO - PROCESS | 21841 | -------- Summary: end -------- 12:11:14 INFO - PROCESS | 21841 | 12:11:15 INFO - TEST-INFO | 21841: exit 0 12:11:15 INFO - TEST-OK | dromaeo_css | took 1114323ms 12:11:15 INFO - TEST-START | kraken 12:11:15 INFO - operating with platform_type : linux_ 12:11:15 INFO - Initialising browser for kraken test... 12:11:15 INFO - TEST-INFO | started process 22036 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwGz2E5/profile http://localhost:53606/getInfo.html) 12:11:19 INFO - PROCESS | 22036 | __metrics Screen width/height:1600/1200 12:11:19 INFO - PROCESS | 22036 | colorDepth:24 12:11:19 INFO - PROCESS | 22036 | Browser inner width/height: 1024/697 12:11:19 INFO - PROCESS | 22036 | __metrics 12:11:20 INFO - PROCESS | 22036 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:11:20 INFO - PROCESS | 22036 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:11:21 INFO - PROCESS | 22036 | console.error: PushService: 12:11:21 INFO - PROCESS | 22036 | stateChangeProcessEnqueue: Error transitioning state 12:11:21 INFO - PROCESS | 22036 | UnknownError 12:11:21 INFO - TEST-INFO | 22036: exit 0 12:11:21 INFO - Browser initialized. 12:11:21 INFO - Running cycle 1/1 for kraken test... 12:11:21 INFO - TEST-INFO | started process 22121 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwGz2E5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:33 INFO - PROCESS | 22121 | RSS: Main: 157609984 12:11:33 INFO - PROCESS | 22121 | 12:11:35 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53606/tests/kraken/driver.html?testName=audio-beat-detection) 12:11:36 INFO - PROCESS | 22121 | RSS: Main: 215982080 12:11:36 INFO - PROCESS | 22121 | 12:11:39 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53606/tests/kraken/driver.html?testName=audio-dft) 12:11:40 INFO - PROCESS | 22121 | RSS: Main: 204382208 12:11:40 INFO - PROCESS | 22121 | 12:11:42 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53606/tests/kraken/driver.html?testName=audio-fft) 12:11:43 INFO - PROCESS | 22121 | RSS: Main: 269492224 12:11:43 INFO - PROCESS | 22121 | 12:11:45 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53606/tests/kraken/driver.html?testName=audio-oscillator) 12:11:46 INFO - PROCESS | 22121 | RSS: Main: 181075968 12:11:46 INFO - PROCESS | 22121 | 12:11:48 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53606/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:11:49 INFO - PROCESS | 22121 | RSS: Main: 185270272 12:11:49 INFO - PROCESS | 22121 | 12:11:52 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53606/tests/kraken/driver.html?testName=imaging-darkroom) 12:11:52 INFO - PROCESS | 22121 | RSS: Main: 218976256 12:11:52 INFO - PROCESS | 22121 | 12:11:55 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53606/tests/kraken/driver.html?testName=imaging-desaturate) 12:11:56 INFO - PROCESS | 22121 | RSS: Main: 221720576 12:11:56 INFO - PROCESS | 22121 | 12:11:59 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53606/tests/kraken/driver.html?testName=json-parse-financial) 12:11:59 INFO - PROCESS | 22121 | RSS: Main: 221650944 12:11:59 INFO - PROCESS | 22121 | 12:12:01 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53606/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:12:02 INFO - PROCESS | 22121 | RSS: Main: 209285120 12:12:02 INFO - PROCESS | 22121 | 12:12:03 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53606/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:12:04 INFO - PROCESS | 22121 | RSS: Main: 212828160 12:12:04 INFO - PROCESS | 22121 | 12:12:06 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53606/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:12:07 INFO - PROCESS | 22121 | RSS: Main: 210980864 12:12:07 INFO - PROCESS | 22121 | 12:12:09 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53606/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:12:10 INFO - PROCESS | 22121 | RSS: Main: 225775616 12:12:10 INFO - PROCESS | 22121 | 12:12:12 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53606/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:12:13 INFO - PROCESS | 22121 | RSS: Main: 224411648 12:12:13 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | Cycle 1(1): loaded http://localhost:53606/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53606/tests/kraken/driver.html?testName=ai-astar) 12:12:15 INFO - PROCESS | 22121 | RSS: Main: 266366976 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | __start_tp_report 12:12:15 INFO - PROCESS | 22121 | _x_x_mozilla_page_load 12:12:15 INFO - PROCESS | 22121 | _x_x_mozilla_page_load_details 12:12:15 INFO - PROCESS | 22121 | |i|pagename|runs| 12:12:15 INFO - PROCESS | 22121 | |0;ai-astar;103;111;111;109;111;111;109;111;109;109 12:12:15 INFO - PROCESS | 22121 | |1;audio-beat-detection;145;118;120;185;121;122;145;117;169;118 12:12:15 INFO - PROCESS | 22121 | |2;audio-dft;161;159;160;160;160;159;162;160;161;162 12:12:15 INFO - PROCESS | 22121 | |3;audio-fft;75;73;74;111;75;77;75;75;112;76 12:12:15 INFO - PROCESS | 22121 | |4;audio-oscillator;83;84;113;82;82;83;120;84;84;136 12:12:15 INFO - PROCESS | 22121 | |5;imaging-gaussian-blur;117;116;116;116;117;117;117;116;116;117 12:12:15 INFO - PROCESS | 22121 | |6;imaging-darkroom;145;145;145;145;145;145;146;145;145;145 12:12:15 INFO - PROCESS | 22121 | |7;imaging-desaturate;95;94;96;95;94;95;95;95;95;95 12:12:15 INFO - PROCESS | 22121 | |8;json-parse-financial;53;52;51;52;51;52;52;51;51;51 12:12:15 INFO - PROCESS | 22121 | |9;json-stringify-tinderbox;61;62;62;62;62;64;65;62;62;62 12:12:15 INFO - PROCESS | 22121 | |10;stanford-crypto-aes;77;74;74;77;75;76;76;75;74;79 12:12:15 INFO - PROCESS | 22121 | |11;stanford-crypto-ccm;119;129;118;117;133;128;119;133;158;121 12:12:15 INFO - PROCESS | 22121 | |12;stanford-crypto-pbkdf2;162;159;165;166;159;158;160;160;160;159 12:12:15 INFO - PROCESS | 22121 | |13;stanford-crypto-sha256-iterative;74;64;63;64;63;62;62;63;66;63 12:12:15 INFO - PROCESS | 22121 | __end_tp_report 12:12:15 INFO - PROCESS | 22121 | __start_cc_report 12:12:15 INFO - PROCESS | 22121 | _x_x_mozilla_cycle_collect,1667 12:12:15 INFO - PROCESS | 22121 | __end_cc_report 12:12:15 INFO - PROCESS | 22121 | __startTimestamp1468437135396__endTimestamp 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | ------- Summary: start ------- 12:12:15 INFO - PROCESS | 22121 | Number of tests: 14 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#0] ai-astar Cycles:10 Average:109.40 Median:111.00 stddev:2.46 (2.2%) stddev-sans-first:1.05 12:12:15 INFO - PROCESS | 22121 | Values: 103.0 111.0 111.0 109.0 111.0 111.0 109.0 111.0 109.0 109.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:133.50 stddev:24.35 (18.2%) stddev-sans-first:25.61 12:12:15 INFO - PROCESS | 22121 | Values: 145.0 118.0 120.0 185.0 121.0 122.0 145.0 117.0 169.0 118.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.07 (0.7%) stddev-sans-first:1.12 12:12:15 INFO - PROCESS | 22121 | Values: 161.0 159.0 160.0 160.0 160.0 159.0 162.0 160.0 161.0 162.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#3] audio-fft Cycles:10 Average:82.30 Median:75.00 stddev:15.43 (20.6%) stddev-sans-first:16.14 12:12:15 INFO - PROCESS | 22121 | Values: 75.0 73.0 74.0 111.0 75.0 77.0 75.0 75.0 112.0 76.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:83.00 stddev:20.05 (24.2%) stddev-sans-first:20.79 12:12:15 INFO - PROCESS | 22121 | Values: 83.0 84.0 113.0 82.0 82.0 83.0 120.0 84.0 84.0 136.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 12:12:15 INFO - PROCESS | 22121 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 12:12:15 INFO - PROCESS | 22121 | Values: 145.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 12:12:15 INFO - PROCESS | 22121 | Values: 95.0 94.0 96.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:52.00 stddev:0.70 (1.3%) stddev-sans-first:0.53 12:12:15 INFO - PROCESS | 22121 | Values: 53.0 52.0 51.0 52.0 51.0 52.0 52.0 51.0 51.0 51.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#9] json-stringify-tinderbox Cycles:10 Average:62.40 Median:62.00 stddev:1.17 (1.9%) stddev-sans-first:1.13 12:12:15 INFO - PROCESS | 22121 | Values: 61.0 62.0 62.0 62.0 62.0 64.0 65.0 62.0 62.0 62.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:76.00 stddev:1.64 (2.2%) stddev-sans-first:1.67 12:12:15 INFO - PROCESS | 22121 | Values: 77.0 74.0 74.0 77.0 75.0 76.0 76.0 75.0 74.0 79.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#11] stanford-crypto-ccm Cycles:10 Average:127.50 Median:128.50 stddev:12.39 (9.6%) stddev-sans-first:12.75 12:12:15 INFO - PROCESS | 22121 | Values: 119.0 129.0 118.0 117.0 133.0 128.0 119.0 133.0 158.0 121.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:160.00 stddev:2.70 (1.7%) stddev-sans-first:2.83 12:12:15 INFO - PROCESS | 22121 | Values: 162.0 159.0 165.0 166.0 159.0 158.0 160.0 160.0 160.0 159.0 12:12:15 INFO - PROCESS | 22121 | 12:12:15 INFO - PROCESS | 22121 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:63.50 stddev:3.57 (5.6%) stddev-sans-first:1.22 12:12:15 INFO - PROCESS | 22121 | Values: 74.0 64.0 63.0 64.0 63.0 62.0 62.0 63.0 66.0 63.0 12:12:15 INFO - PROCESS | 22121 | -------- Summary: end -------- 12:12:15 INFO - PROCESS | 22121 | 12:12:16 INFO - TEST-INFO | 22121: exit 0 12:12:16 INFO - TEST-OK | kraken | took 61304ms 12:12:16 INFO - SUITE-END | took 1175s 12:12:16 INFO - Completed test suite (00:19:35) 12:12:16 INFO - javascript benchmark 12:12:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3033.105233955983, 3049.801762885411, 3086.8765249390017, 3090.7681923855703, 3102.922426939326, 5196.220566914964, 5243.187305967572, 5278.340207474066, 5374.435684253156, 5436.836894893155, 5355.3305836770405, 5362.731863406833, 5364.5708343332535, 5397.136458166694, 5426.565874730022, 4815.494373090819, 4862.97568512157, 4918.385201849769, 4927.236278376858, 5083.74581270935, 4973.5772459341, 4981.800727970877, 5019.0, 5039.974800125994, 5049.192129259319, 4839.9274010889685, 4849.854504364875, 4887.560119589236, 4902.730349830758, 4922.729249891255, 4820.542048505386, 4841.3222148899185, 4856.223004319292, 4857.951420485785, 4960.528749768802, 4874.366332376804, 4897.069556784199, 4908.0, 4908.607311415079, 4976.950230497721, 5242.423333433319, 5312.893742125175, 5340.813071542478, 5341.332333458317, 5380.21986811914, 5747.827565173052, 5748.338941021752, 5751.0, 5801.7389217485315, 5813.302403711539, 5764.625299355571, 5796.043652797283, 5823.4467725566, 5850.414958504116, 5852.356240813507, 5236.423993360727, 5261.316028916254, 5367.141257398797, 5372.247885296023, 5447.509724124848, 5917.704114794329, 5932.228810254639, 5933.169356290123, 5936.079907614327, 5937.435943585352, 5707.143928410772, 5708.40061793514, 5721.370649228582, 5723.570732195102, 5727.284089488814, 5508.834734957958, 5707.914381284284, 5728.312602487728, 5806.0, 5808.215890854765, 5706.771729130788, 5714.314282286069, 5716.857078573069, 5734.483896449339, 5781.6241944274325, 5674.460926212003, 5686.886262274731, 5739.225204597409, 5755.62588431759, 5773.624714393551, 4302.612764851116, 4329.19909816685, 4351.3472979053395, 4354.629856462237, 4375.365571992043, 3897.1231472918366, 3903.2193561287854, 3905.1213476967455, 3940.7044471664167, 3944.36890097583, 5424.105022671215, 5436.972815135978, 5438.374586922476, 5448.237246785453, 5453.209284653783, 5634.915476267777, 5714.0571805652435, 5760.94239057604, 5790.334111577138, 5815.94184058154, 5419.376771671308, 5444.237806707064, 5446.346438427414, 5449.128139497661, 5489.972550137224, 4864.489228631013, 5005.649604527648, 5012.24816277554, 5022.447530771612, 5066.695998240118, 5778.913316300259, 5783.768649253982, 5810.593258471867, 5873.089671100986, 5883.941160588425, 5721.084626459747, 5723.5707321950185, 5724.084146536534, 5724.227229323988, 5724.599278050663, 5763.971180144073, 5837.620554663933, 5857.736401862012, 5861.736221869941, 5867.853303667443, 5621.437856214346, 5684.261046063985, 5689.857753556194, 5693.971530142323, 5745.712714364181, 5750.769969201352, 5765.884682306414, 5775.9133612996675, 5792.247007888947, 5795.420457954339, 5545.223668686468, 5575.721213939206, 5609.691466969356, 5618.803341883015, 5674.574406919415, 5617.101263797773, 5660.320761508646, 5660.405657406078, 5669.489745922887, 5689.203511508475, 5572.86067848307, 5584.581156413366, 5586.050371436947, 5590.273264475592, 5599.944000560105, 5515.751791169327, 5527.088030475087, 5579.888402231852, 5657.688827114548, 5669.518090962314, 5465.098258787256, 5467.9453205467435, 5475.151351540518, 5475.917861232005, 5513.117901135799], "value": 5294.46701035878, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.6275489554341, 619.2012304127676, 625.6777759453882, 625.7184267079815, 633.7116611941567, 30005.84997075017, 43476.0, 44213.557864421346, 44267.0, 44325.0, 45296.54703452973, 45563.08873822522, 45764.77117614407, 45800.541994580126, 45847.083058338816, 8996.3702540822, 9140.131687489711, 9143.497107659075, 9147.908520914772, 9149.862752058725, 8967.820643587125, 9021.819563608724, 9048.095190480932, 9057.320700947423, 9058.456492610465, 9046.276297896153, 9104.226140778042, 9108.22580080694, 9120.589573469177, 9120.908790912072, 9058.320625953047, 9089.091090890925, 9090.86363704545, 9105.362624616246, 9110.27117830572, 8925.464472131627, 9026.774330641721, 9027.909720902773, 9028.77428064297, 9036.231920286784, 43378.56621433777, 43790.34314485285, 43791.56208437907, 43822.78088609537, 43825.34261986041, 3357.2949680567112, 3371.814550199738, 3674.889753307405, 3683.244934788362, 3726.9627303727157, 33173.50239746406, 33732.83133584316, 34004.6599534004, 34163.829180853936, 34238.0, 4341.804618792162, 4387.561243875619, 4390.768483599021, 4421.403110580062, 4502.077074199781, 373251.0, 374208.0, 374844.0, 375258.0, 375794.12102939584, 61387.38612613817, 61916.38083619106, 62378.37621623726, 62414.375856240855, 62420.37579624236, 65707.0, 66297.33702662912, 66371.33628663748, 66519.0, 66582.0, 17301.480955571355, 17505.299788008593, 17506.562335941704, 17524.649507010043, 17533.386331478338, 13538.255395953318, 13589.592312230649, 13659.726805464034, 13674.93162534201, 13677.042607017413, 18740.21793698111, 19811.504712382306, 19831.801681983285, 19952.800471995095, 19963.60072798536, 7145.571265723969, 7198.7840364789145, 7271.563706177541, 7281.563106213538, 7464.738734144279, 981.293468702533, 1007.5214273220308, 1030.2376241581176, 1085.0560012788767, 1103.4096758234307, 33321.666783332344, 33485.0, 33578.0, 33695.32609347799, 33737.0, 1102.597551893569, 1110.4059328259505, 1128.368113856243, 1134.6766171641207, 1147.8966892979672, 16993.91503042477, 17234.310627575007, 17291.221895014754, 17372.652546949244, 17423.912880435517, 594684.0, 599705.0265476129, 602417.0, 602817.0, 604746.0, 59932.70033649804, 60586.0, 60698.69650651718, 60810.0, 60833.0, 1249.425264378378, 1347.9528216512374, 1359.9796003059962, 1381.7789153735555, 1389.1040279020178, 33650.3269934595, 34720.652793471745, 35121.0, 35245.29509409849, 35476.46785298171, 56114.43885561092, 58051.4194858046, 58086.4191358081, 62911.685441572496, 62983.6850815743, 577879.0, 596168.0, 596220.0, 600925.0, 601267.0, 39760.40359394669, 44307.11385772202, 44689.0, 45958.080838382375, 46032.769836150605, 49743.50256497389, 50106.248406274666, 50210.0, 51473.22790158076, 51505.48494515007, 42592.74072592716, 42825.57174428341, 42836.571634284504, 44003.77998109989, 44362.0, 42506.78746606247, 42782.57217427786, 43028.13943721171, 43158.13683726371, 43802.78098609487, 41967.580324196366, 42329.78835105805, 42788.0, 44732.8525059524, 45040.324395133444, 36703.632963670025, 38156.42765358577, 38160.61839381682, 38461.80769096137, 38897.41653875138, 28525.0, 29114.41771164607, 29162.56256156202, 29407.0, 29689.406211875208], "value": 21347.4087452471, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.25649930640793, 369.5343866727922, 373.9626037396259, 378.56654131019985, 379.37592660074193, 18528.07359632018, 19919.203231870742, 20080.99595020247, 20275.0, 20435.386938391835, 18807.0, 18966.71549926752, 19521.609567808635, 19537.804621953775, 20066.29767958118, 5069.88462538241, 5079.6190285728535, 5202.843914682566, 5209.270702101709, 5271.341082364705, 5164.9483505165035, 5226.529612334889, 5233.0, 5264.8683782905355, 5270.288511050997, 5266.8419947401635, 5269.262303277545, 5270.077736396134, 5273.129933560977, 5558.749856256478, 5786.247787787603, 5858.619189752652, 5877.118432235156, 5891.793787217448, 5895.0, 4867.172580661296, 5149.48505149482, 5167.302414174113, 5167.379914410295, 5175.689458632494, 13489.797653035212, 14354.641133971734, 22797.772022279732, 22991.425214369607, 23011.42471438211, 2264.830137739676, 2359.7050368703913, 2359.8112151027876, 2439.0, 2819.252897982016, 10181.949090254575, 10240.539175737109, 10350.171986241083, 12478.064145189057, 14658.780118298235, 1852.2590963614527, 1853.9907300463547, 1916.1377380178976, 1923.422973108062, 1941.0876887862682, 80802.0, 81563.0, 83357.58321208355, 84562.15437845605, 84995.15004849934, 10531.89468105309, 10536.157107431387, 10679.78640427187, 10681.893181068246, 10817.891821081688, 14537.0, 14636.853631463762, 14656.926715366568, 14667.706645867236, 14671.486497972519, 7746.457747957702, 7796.532208067534, 7998.880016799752, 8033.959830200928, 8228.30059444942, 7665.23347665229, 7794.41541884361, 7805.648745806338, 7933.098204562068, 8022.79943001418, 4245.8513952012145, 4302.677299202573, 4361.280388735887, 4363.2364336241035, 4444.8444304449185, 1776.9822301777076, 1806.8102849200645, 1889.2159753702335, 1946.5814849807077, 1950.6781381071964, 880.4277219807177, 1070.9303895246785, 1072.8766191887876, 1075.5805235957982, 1086.875009373932, 19347.903260483607, 19994.400167994983, 20071.899640501997, 20098.09558569868, 20133.798662013192, 976.2531663277598, 1030.175859312547, 1050.5062620568322, 1060.5280650110626, 1063.1707268330715, 10642.201834862417, 10899.564017439214, 10925.672229833117, 11008.0, 11144.832827507595, 489624.0, 492574.0, 497954.0, 500983.0, 510237.0, 67798.66100669465, 87406.0, 93527.53236233912, 95291.52354238323, 95920.52039739757, 968.2205824311357, 981.2444417798256, 1022.0494939705997, 1023.2019025160387, 1027.2501074215863, 12499.687507812378, 17196.14019299005, 17408.82591174072, 18426.447206583824, 18763.437096887115, 29920.25199370033, 41007.06502215323, 41491.7925410371, 42670.57329426666, 42927.785361072994, 56485.0, 57521.71239143777, 58185.0, 58210.12684809816, 58529.0, 7854.6858125674335, 8268.545230012407, 8738.038815730137, 8816.691475538773, 8877.334199934901, 8643.697470588499, 12674.619761407173, 12829.935850321062, 12993.610191694264, 13194.736105278032, 11101.66694999119, 11464.0, 11713.88286117128, 11983.700407489883, 12164.2701437914, 11092.0, 11256.662300131009, 11438.485268162785, 11534.0, 11628.127890408085, 11302.43487825589, 11865.822012669989, 12006.579769708018, 12175.086868485076, 12236.0, 8213.425060245965, 8567.74296771098, 8709.085546017577, 8832.072632373765, 8834.249088827522, 11213.775724485302, 11552.6534203974, 11584.710382240512, 11808.645740627795, 11815.763684726086], "value": 9051.141848765032, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1499.8050253467052, 1528.1289664891012, 1666.0015961691938, 1776.9022703751289, 1794.5693033671923, 8536.95731521343, 9029.232515236214, 9119.179273865351, 9133.269338452916, 9140.26877849772, 7520.84958300835, 7697.846043079149, 7702.0757509098985, 7946.92053079469, 8961.0, 2092.3618296419586, 2172.6197915364746, 2182.50893548951, 2189.9452513687206, 2194.6049711052005, 2195.2865318771383, 2200.471886747177, 2220.322801545528, 2220.522587643656, 2237.2281562860785, 1862.9161687724018, 2124.7875212478707, 2160.1899287767087, 2166.2309879992613, 2166.3176099528678, 2203.6914831923546, 2227.3429338345227, 2232.2521955145044, 2232.598132336179, 2234.474898398867, 2152.7416709994745, 2162.4053385319007, 2184.0936011555345, 2188.6060509108356, 2191.0468946008536, 8996.0, 9049.864252036225, 9062.365634405594, 9081.772955676095, 9083.182513573744, 4995.075910956449, 5174.611904107207, 5216.2958000669805, 5225.190095535198, 5233.790648374061, 3268.9182770430693, 3373.4939759036097, 3399.524066630649, 3408.454647256427, 3412.1128506588216, 1026.4970164619358, 1057.349729916096, 1092.7104317355906, 1093.0545078507114, 1094.4746521669642, 12483.750324993449, 12532.310722910237, 12576.74846503074, 12593.118481706284, 12600.810987835099, 4273.444452221253, 4487.955120448819, 4568.17772800899, 4627.884302892455, 4635.327877457749, 10566.947165264279, 10599.046085852311, 10617.84073238902, 10618.044376006197, 10620.415877126832, 5224.0, 5239.580833533309, 5253.238280449314, 5254.15933450646, 5259.0796610593, 4703.412073490814, 4832.49258827818, 4834.371531700856, 4835.178019736653, 4890.1931181355385, 3886.9611303887164, 3888.7083468739957, 3924.548676902136, 3960.5841386653988, 4008.6993475489453, 1192.4753108632174, 1195.7668254690252, 1208.510553225945, 1222.1200735470447, 1234.3334599316447, 877.8463768840427, 1021.560728886571, 1027.8920713325142, 1029.2023681646783, 1033.1682995188794, 6106.358832322631, 6251.093591429218, 6289.55973081889, 6298.937010629927, 6359.427651511293, 424.55633862613644, 425.3194888178951, 428.0945799633814, 429.467460349171, 436.6288654643591, 4297.097609501977, 4302.569743025735, 4344.131173765197, 4359.67302452311, 4367.061081867414, 10649.9467502662, 11236.213465057532, 11257.437128143723, 11293.265937714023, 11309.264897781617, 10594.894051059391, 11121.721956951142, 11158.832617510581, 11178.0, 11182.384968826793, 8233.752987410388, 8340.124286949784, 8388.790280243044, 8398.958005209935, 8419.56867332555, 6031.547633927561, 6307.274663413767, 6352.333005034405, 6365.61806291624, 6384.106225128578, 10237.84643230367, 10430.791384172426, 10433.739156521147, 10527.210459215436, 10582.947085264525, 9051.637934482547, 9835.213182945205, 10283.845742313722, 10286.639967601099, 10585.417802020655, 6635.767748128792, 7923.049234091945, 7950.602469876599, 7952.483088599222, 7953.6818527260575, 7549.7357592483995, 8594.312455003459, 8742.388032837895, 8763.3427492937, 8769.868451973098, 7520.435967302365, 7650.846983060419, 7728.072631284282, 7785.221477852396, 8068.636911338886, 7841.803954901173, 7852.6073696313815, 7917.524948503108, 7948.562829044226, 8026.478278911852, 7163.247858974941, 7214.567125972459, 7217.133943926762, 7270.0, 7346.595937223504, 5108.795648174031, 5149.098907691214, 5150.1502252127975, 5263.815766448156, 5290.44450332725, 5236.240745092016, 5294.841154765363, 5326.547243484192, 5360.571154307724, 5407.459254074718], "value": 4538.916481881285, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.98605062772174, 316.30728704137937, 322.4196446396486, 322.4325187669701, 324.7921330348577, 5220.138677118276, 5266.3680358357005, 5295.629305948585, 5320.361556613207, 5370.865728356793, 5031.440253521402, 5152.639315247934, 5162.716050617215, 5164.586833053356, 5297.602679799012, 4001.7598944063448, 4082.285600019992, 4147.979260103695, 4162.354835000579, 4200.327947528396, 4132.690048246393, 4169.499660040799, 4172.0, 4174.70777045607, 4178.623923846853, 4024.7786371749544, 4049.3318602430563, 4105.199486100211, 4110.1163249901265, 4141.378793181017, 4078.163976384864, 4090.6727461802984, 4108.897277568055, 4115.238680844053, 4115.518413371184, 3622.1850083731215, 3640.9453858192146, 3641.198936234024, 3642.289753498069, 3651.3062518121474, 4962.602991760687, 5106.846794596168, 5130.30740849988, 5148.279240906276, 5190.06578815813, 4681.438227412698, 4686.36734040907, 4703.905921881543, 4704.811807527695, 4706.576408123286, 5516.448355164492, 5531.446855314477, 5538.335399752015, 5540.36285827127, 5560.997061586739, 3846.6345697158727, 3934.390169523728, 3936.881893543198, 3942.310095733264, 3950.1309711863346, 5638.407967163471, 5724.255846739898, 5730.481422423061, 5730.971345143247, 5747.252857128547, 4010.959890401117, 4020.778857162884, 4021.738586991836, 4025.19496100781, 4027.154297597479, 4059.4722686050623, 4072.2873497138476, 4073.837046518106, 4076.9184616308103, 4080.163566468867, 4516.774161291923, 4646.4191976003, 4654.627629789608, 4669.879228985019, 4678.409340824136, 5522.392536820947, 5531.585131115183, 5549.5837812163445, 5550.417206193291, 5572.58205634579, 1336.1381908668855, 1365.3241645385597, 1367.9521216757366, 1376.8072469854228, 1384.7715127004133, 914.0, 931.65063101337, 934.9205317547949, 935.2471260635253, 944.5466176235308, 5317.760700768475, 5322.467753224724, 5326.174442961347, 5328.6802791832615, 5349.0, 5590.021746194399, 5596.664200148004, 5621.269234999424, 5639.718014099279, 5642.548596112383, 5166.61250406221, 5298.788048478094, 5305.920411193835, 5318.095923692981, 5384.623076384615, 3016.0, 3019.8037127587077, 3021.4108248891694, 3035.4822588705647, 3044.43211745951, 5853.678047707417, 5854.648721076749, 5860.677662728591, 5864.472197502245, 5889.146073819272, 5640.464155905182, 5824.0, 5838.591298609142, 5851.268591426072, 5851.882962340729, 5830.47525722687, 5839.240898683144, 5845.0, 5857.238558987305, 5878.147668587945, 5460.726963651881, 5670.971645141748, 5703.20155178267, 5723.227364305765, 5795.971020144872, 5730.5129064030025, 5741.770329186786, 5747.442388137239, 5752.0, 5753.252077229933, 5892.793752218652, 5895.263092113486, 5906.232189815297, 5924.11138329254, 5934.851628709317, 3907.843686252518, 3996.140829721624, 4000.4999375078114, 4009.8797036088963, 4011.7793521356607, 5469.671819690831, 5684.9017285580285, 5689.146628005832, 5729.942700572941, 5741.167530708108, 5459.890802184013, 5590.881823635208, 5594.496495315441, 5637.3235211774845, 5638.5489160867855, 5595.160725890986, 5610.354809196994, 5612.579056570692, 5614.691191984317, 5618.550515958632, 5300.70846103468, 5524.447555244444, 5532.889342213214, 5534.308211473566, 5549.5837812163445, 5594.41258667834, 5616.354119276336, 5628.662280263197, 5634.380218176079, 5663.801766938137, 5479.506844384025, 5488.780448782004, 5508.641938274, 5525.0, 5544.556435485232], "value": 4219.544815186902, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.7777988823468, 727.5561907236587, 731.1664702239448, 755.3994574313421, 784.9411294152939, 15018.77471837922, 16540.09029503377, 16633.66732665346, 16635.334586616536, 16684.916575417104, 14627.195504247262, 15116.0, 15431.845681543182, 15442.073475591473, 15477.845221547781, 669.2872091222856, 805.0701439836997, 826.2522417212432, 829.1087081387502, 831.7380025292044, 790.3282210121408, 818.8362327534517, 820.983580328393, 822.0300045945781, 822.4859462835727, 854.7350321400355, 856.2550580994543, 856.4090777363599, 857.4426622695236, 857.9356548258875, 686.4679873098339, 711.1501755402296, 849.7833052571585, 856.9185927336893, 857.6998050682242, 523.0872128136426, 597.5637784417356, 621.0125899819309, 621.2327775197627, 624.4192900602438, 15332.693346133126, 15366.078035317805, 15379.077255364715, 15393.538193854203, 15439.228038598138, 14274.286285685674, 14558.272086395744, 14634.41462341505, 14636.780448293284, 14654.706905861929, 12482.50069997199, 12486.0, 12492.75014499705, 12526.0, 13166.802497962539, 4532.138893610237, 4553.68124231304, 4665.393498845162, 4768.451628062782, 4832.540908613676, 79014.0, 79131.20868791296, 79464.60267698625, 80187.0, 80576.19423805746, 11502.367369794574, 11643.41782910851, 11659.941700291443, 11690.824637630443, 12101.213421127599, 21371.572568548854, 21455.141794328363, 21519.246826361003, 21575.2448664296, 21608.13567457284, 8471.872921906177, 8543.974723033152, 8545.615447304886, 8592.699255526026, 8605.182507661762, 4686.203345431285, 4828.927566086511, 4858.562729354375, 4886.902261954812, 4887.975560122248, 3543.309054734302, 3638.272345530904, 3647.361711700442, 3648.8175591220333, 3740.3267411866127, 671.6809515480107, 678.5894533807075, 680.8187794177098, 685.2359619024752, 691.4882986589887, 684.2302409788988, 700.551646945955, 726.9781906542812, 748.2666986353404, 783.635609441601, 9855.45794981283, 10249.0, 10467.424291664032, 10550.261481696207, 10718.732031699114, 616.8642898562309, 624.372505631837, 716.3874886971669, 745.8545583611252, 761.6800943603699, 4565.0, 4701.670883038223, 4780.330753694486, 4801.591864691471, 4825.445073816486, 93451.0, 130047.0, 130952.34523827319, 132013.33993329972, 137347.0, 90975.0, 94973.52513237389, 95949.0, 96144.51927740316, 96272.51863740636, 55951.0, 62178.68910655508, 79824.20175798284, 86847.56576217078, 87001.90986630875, 9692.951535242279, 9902.653407130716, 10216.642417515352, 10230.693079207636, 10258.487075646337, 38865.0, 43005.56994430016, 44053.33919991139, 53863.46136538585, 55215.447845522634, 74254.9457801508, 74721.0, 78154.2184578147, 78455.0, 78619.21380786347, 13524.459021639417, 13533.458661653818, 13813.480517142905, 13820.861791381958, 14051.156930584199, 15134.697306053597, 15266.770998435255, 15430.151341676315, 15532.301046453164, 15748.448804292253, 10484.895151048697, 12871.742565148457, 13003.609891703263, 13110.147840390344, 13398.196108233537, 13587.52443664467, 14208.147511149364, 14267.64330891736, 14441.277936103363, 14945.850541494447, 13443.193408395528, 13494.679161372098, 14141.717165656835, 14226.104317444473, 14636.121832690073, 10672.039516443558, 10720.57988908042, 10930.945345273223, 10967.067799237155, 11066.170037247077, 11095.278806877526, 11847.76304473923, 12049.698757531132, 12117.333546655018, 12230.266184028987], "value": 7673.144995167138, "unit": "score"}], "name": "dromaeo_css", "value": 7292.084596019758, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 111.0, 109.0, 111.0, 111.0, 109.0, 111.0, 109.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 120.0, 185.0, 121.0, 122.0, 145.0, 117.0, 169.0, 118.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 160.0, 160.0, 159.0, 162.0, 160.0, 161.0, 162.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 111.0, 75.0, 77.0, 75.0, 75.0, 112.0, 76.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 113.0, 82.0, 82.0, 83.0, 120.0, 84.0, 84.0, 136.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 51.0, 52.0, 51.0, 52.0, 52.0, 51.0, 51.0, 51.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 62.0, 64.0, 65.0, 62.0, 62.0, 62.0], "value": 62.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 74.0, 77.0, 75.0, 76.0, 76.0, 75.0, 74.0, 79.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 129.0, 118.0, 117.0, 133.0, 128.0, 119.0, 133.0, 158.0, 121.0], "value": 127.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 165.0, 166.0, 159.0, 158.0, 160.0, 160.0, 160.0, 159.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 64.0, 63.0, 64.0, 63.0, 62.0, 62.0, 63.0, 66.0, 63.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1482.1000000000001, "alertThreshold": 2.0}]} 12:12:16 INFO - Return code: 0 12:12:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:12:16 INFO - # TBPL SUCCESS # 12:12:16 INFO - Running post-action listener: _resource_record_post_action 12:12:16 INFO - ##### 12:12:16 INFO - ##### Finished run-tests step (success) 12:12:16 INFO - ##### 12:12:16 INFO - Running post-run listener: _resource_record_post_run 12:12:16 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 552206336; Read time: 0; Write time: 895932 12:12:16 INFO - TinderboxPrint: CPU usage
13.0% 12:12:16 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 12:12:16 INFO - TinderboxPrint: I/O write bytes / time
552,206,336 / 895,932 12:12:16 INFO - TinderboxPrint: CPU idle
8,281.3 (87.0%) 12:12:16 INFO - TinderboxPrint: CPU user
1,192.7 (12.5%) 12:12:16 INFO - TinderboxPrint: Swap in / out
0 / 0 12:12:16 INFO - install - Wall time: 18s; CPU: 15.0%; Read bytes: 0; Write bytes: 257486848; Read time: 0; Write time: 513012 12:12:16 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 294719488; Read time: 0; Write time: 382920 12:12:17 INFO - Running post-run listener: _upload_blobber_files 12:12:17 INFO - Blob upload gear active. 12:12:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:12:17 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 12:12:17 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'] 12:12:17 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 12:12:17 INFO - (blobuploader) - INFO - Open directory for files ... 12:12:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:12:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:12:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:18 INFO - (blobuploader) - INFO - Done attempting. 12:12:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:12:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:12:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:22 INFO - (blobuploader) - INFO - Done attempting. 12:12:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:12:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:12:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:23 INFO - (blobuploader) - INFO - Done attempting. 12:12:23 INFO - (blobuploader) - INFO - Iteration through files over. 12:12:23 INFO - Return code: 0 12:12:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:12:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:12:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9e5530e9538118e368d935438985e762892b19ec273c81ba7a95cccecca30afa229128317935c94da0dfc2b512da6e7b8a5c1006731ae15d99e0d39040967de9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15c4ff2d20dd734b69bba1a1d55b90c03e51170d457b6f59ea5fd086f0e75212b2b716ef6aae7d852b85d158a764b515a9c711fde02cc2df646068e98b8006b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cbc2aa4649f58b47c683fe80e270b8cea284b48e5439ac908f3605b4753fafe9daea9c268bd0a2337d22cb795c4284ae014276de58b0db6179bc1ed13149e88f"} 12:12:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:12:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:12:23 INFO - Contents: 12:12:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9e5530e9538118e368d935438985e762892b19ec273c81ba7a95cccecca30afa229128317935c94da0dfc2b512da6e7b8a5c1006731ae15d99e0d39040967de9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15c4ff2d20dd734b69bba1a1d55b90c03e51170d457b6f59ea5fd086f0e75212b2b716ef6aae7d852b85d158a764b515a9c711fde02cc2df646068e98b8006b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cbc2aa4649f58b47c683fe80e270b8cea284b48e5439ac908f3605b4753fafe9daea9c268bd0a2337d22cb795c4284ae014276de58b0db6179bc1ed13149e88f"} 12:12:23 INFO - Running post-run listener: copy_logs_to_upload_dir 12:12:23 INFO - Copying logs to upload dir... 12:12:23 INFO - mkdir: /builds/slave/test/build/upload/logs 12:12:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.171446 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-07-13 12:12:22.710585) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 12:12:22.713680) ========= 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=8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223 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/9e5530e9538118e368d935438985e762892b19ec273c81ba7a95cccecca30afa229128317935c94da0dfc2b512da6e7b8a5c1006731ae15d99e0d39040967de9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15c4ff2d20dd734b69bba1a1d55b90c03e51170d457b6f59ea5fd086f0e75212b2b716ef6aae7d852b85d158a764b515a9c711fde02cc2df646068e98b8006b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cbc2aa4649f58b47c683fe80e270b8cea284b48e5439ac908f3605b4753fafe9daea9c268bd0a2337d22cb795c4284ae014276de58b0db6179bc1ed13149e88f"} build_url:https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011268 build_url: 'https://queue.taskcluster.net/v1/task/HdPPIH8NSm6jxsoo4b215Q/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/9e5530e9538118e368d935438985e762892b19ec273c81ba7a95cccecca30afa229128317935c94da0dfc2b512da6e7b8a5c1006731ae15d99e0d39040967de9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/15c4ff2d20dd734b69bba1a1d55b90c03e51170d457b6f59ea5fd086f0e75212b2b716ef6aae7d852b85d158a764b515a9c711fde02cc2df646068e98b8006b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cbc2aa4649f58b47c683fe80e270b8cea284b48e5439ac908f3605b4753fafe9daea9c268bd0a2337d22cb795c4284ae014276de58b0db6179bc1ed13149e88f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 12:12:22.741141) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 12:12:22.741485) ========= 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=8783db0840cc980e80bb0bd400000236-1468435908.588618-1441577223 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004460 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 12:12:22.825794) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 12:12:22.826552) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 12:12:22.827180) ========= ========= Total master_lag: 0.19 =========