builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1468639200.41 results: success (0) buildid: 20160715173018 builduid: 37676dec10e7457f9f78663151f00b6f revision: 0c27d48b278dba5493f9c98581acfd71889f7b63 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:00.415100) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:00.415463) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:00.415718) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:00.430962) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:00.431351) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-15 20:20:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.4M=0s 2016-07-15 20:20:01 (37.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128872 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:00.574073) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:00.574384) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014747 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:00.623675) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:00.623988) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0c27d48b278dba5493f9c98581acfd71889f7b63 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0c27d48b278dba5493f9c98581acfd71889f7b63 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-15 20:20:01,139 truncating revision to first 12 chars 2016-07-15 20:20:01,139 Setting DEBUG logging. 2016-07-15 20:20:01,139 attempt 1/10 2016-07-15 20:20:01,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0c27d48b278d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 20:20:01,445 unpacking tar archive at: fx-team-0c27d48b278d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.719818 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:01.356680) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:01.356944) ========= script_repo_revision: 0c27d48b278dba5493f9c98581acfd71889f7b63 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:01.357328) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:01.357586) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 20:20:01.366663) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-07-15 20:20:01.366924) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:20:01 INFO - MultiFileLogger online at 20160715 20:20:01 in /builds/slave/test 20:20:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:20:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:20:01 INFO - {'append_to_log': False, 20:20:01 INFO - 'base_work_dir': '/builds/slave/test', 20:20:01 INFO - 'blob_upload_branch': 'Fx-Team', 20:20:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:20:01 INFO - 'branch': 'Fx-Team', 20:20:01 INFO - 'buildbot_json_path': 'buildprops.json', 20:20:01 INFO - 'config_files': ('talos/linux_config.py',), 20:20:01 INFO - 'default_actions': ('clobber', 20:20:01 INFO - 'read-buildbot-config', 20:20:01 INFO - 'download-and-extract', 20:20:01 INFO - 'populate-webroot', 20:20:01 INFO - 'create-virtualenv', 20:20:01 INFO - 'install', 20:20:01 INFO - 'run-tests'), 20:20:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:20:01 INFO - 'download_minidump_stackwalk': True, 20:20:01 INFO - 'download_symbols': 'ondemand', 20:20:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:20:01 INFO - 'tooltool.py': '/tools/tooltool.py', 20:20:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:20:01 INFO - '/tools/misc-python/virtualenv.py')}, 20:20:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:20:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:20:01 INFO - 'installer_path': 'installer.exe', 20:20:01 INFO - 'log_level': 'info', 20:20:01 INFO - 'log_name': 'talos', 20:20:01 INFO - 'log_to_console': True, 20:20:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:20:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:20:01 INFO - 'opt_config_files': (), 20:20:01 INFO - 'pip_index': False, 20:20:01 INFO - 'sps_profile': False, 20:20:01 INFO - 'sps_profile_interval': 0, 20:20:01 INFO - 'suite': 'dromaeojs', 20:20:01 INFO - 'system_bits': '32', 20:20:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:20:01 INFO - 'title': 'talos-linux64-ix-041', 20:20:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:20:01 INFO - 'use_talos_json': True, 20:20:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:20:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:20:01 INFO - 'work_dir': 'build'} 20:20:01 INFO - ##### 20:20:01 INFO - ##### Running clobber step. 20:20:01 INFO - ##### 20:20:01 INFO - Running pre-action listener: _resource_record_pre_action 20:20:01 INFO - Running main action method: clobber 20:20:01 INFO - rmtree: /builds/slave/test/build 20:20:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:20:02 INFO - Running post-action listener: _resource_record_post_action 20:20:02 INFO - ##### 20:20:02 INFO - ##### Finished clobber step (success) 20:20:02 INFO - ##### 20:20:02 INFO - ##### 20:20:02 INFO - ##### Running read-buildbot-config step. 20:20:02 INFO - ##### 20:20:02 INFO - Running pre-action listener: _resource_record_pre_action 20:20:02 INFO - Running main action method: read_buildbot_config 20:20:02 INFO - Using buildbot properties: 20:20:02 INFO - { 20:20:02 INFO - "project": "", 20:20:02 INFO - "product": "firefox", 20:20:02 INFO - "script_repo_revision": "production", 20:20:02 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:20:02 INFO - "repository": "", 20:20:02 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:20:02 INFO - "buildid": "20160715173018", 20:20:02 INFO - "pgo_build": "True", 20:20:02 INFO - "basedir": "/builds/slave/test", 20:20:02 INFO - "buildnumber": 292, 20:20:02 INFO - "slavename": "talos-linux64-ix-041", 20:20:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:20:02 INFO - "platform": "ubuntu64_hw", 20:20:02 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:20:02 INFO - "branch": "fx-team", 20:20:02 INFO - "repo_path": "integration/fx-team", 20:20:02 INFO - "revision": "0c27d48b278dba5493f9c98581acfd71889f7b63", 20:20:02 INFO - "stage_platform": "linux64-pgo", 20:20:02 INFO - "builduid": "37676dec10e7457f9f78663151f00b6f", 20:20:02 INFO - "slavebuilddir": "test" 20:20:02 INFO - } 20:20:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 20:20:02 INFO - Running post-action listener: _resource_record_post_action 20:20:02 INFO - ##### 20:20:02 INFO - ##### Finished read-buildbot-config step (success) 20:20:02 INFO - ##### 20:20:02 INFO - ##### 20:20:02 INFO - ##### Running download-and-extract step. 20:20:02 INFO - ##### 20:20:02 INFO - Running pre-action listener: _resource_record_pre_action 20:20:02 INFO - Running main action method: download_and_extract 20:20:02 INFO - mkdir: /builds/slave/test/build/tests 20:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:02 INFO - https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:20:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 20:20:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 20:20:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:04 INFO - Downloaded 1361 bytes. 20:20:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 20:20:04 INFO - Using the following test package requirements: 20:20:04 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 20:20:04 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:20:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:20:04 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:20:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:20:04 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:20:04 INFO - u'jsshell-linux-x86_64.zip'], 20:20:04 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:20:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:20:04 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 20:20:04 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:20:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:20:04 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:20:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:20:04 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:20:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:20:04 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:20:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:20:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:20: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')]} 20:20:04 INFO - https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:20:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 20:20:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 20:20:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:08 INFO - Downloaded 24326907 bytes. 20:20:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:20:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:20:09 INFO - Return code: 0 20:20:09 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 20:20: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')]} 20:20:09 INFO - https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:20:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 20:20:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 20:20:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:20 INFO - Downloaded 24326907 bytes. 20:20:20 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'] 20:20:20 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 20:20:21 INFO - Return code: 0 20:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:20:21 INFO - https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:20:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 20:20:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 20:20:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:24 INFO - Downloaded 14403317 bytes. 20:20:24 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'] 20:20:24 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 20:20:24 INFO - Return code: 0 20:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:24 INFO - https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:20:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 20:20:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 20:20:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QvQKKhWKQVWSDWdei75Ffw/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 20:20:26 INFO - Downloaded 60825994 bytes. 20:20:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 20:20:26 INFO - mkdir: /builds/slave/test/properties 20:20:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:20:26 INFO - Writing to file /builds/slave/test/properties/build_url 20:20:26 INFO - Contents: 20:20:26 INFO - build_url:https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 20:20:26 INFO - Running post-action listener: _resource_record_post_action 20:20:26 INFO - Running post-action listener: set_extra_try_arguments 20:20:26 INFO - ##### 20:20:26 INFO - ##### Finished download-and-extract step (success) 20:20:26 INFO - ##### 20:20:26 INFO - ##### 20:20:26 INFO - ##### Running populate-webroot step. 20:20:26 INFO - ##### 20:20:26 INFO - Running pre-action listener: _resource_record_pre_action 20:20:26 INFO - Running main action method: populate_webroot 20:20:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:20:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:20:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:20:26 INFO - '--tppagecycles', 20:20:26 INFO - '7'], 20:20:26 INFO - 'tests': ['tsvgm']}}, 20:20:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:20:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:20:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:20:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:20:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:26 INFO - 'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:20:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:20:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:26 INFO - 'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['damp', 'tps']}, 20:20:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:26 INFO - 'tests': ['damp', 'tps']}, 20:20:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['dromaeo_dom']}, 20:20:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:20:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['basic_compositor_video']}, 20:20:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:20:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['a11yr', 20:20:26 INFO - 'ts_paint', 20:20:26 INFO - 'tpaint', 20:20:26 INFO - 'sessionrestore', 20:20:26 INFO - 'sessionrestore_no_auto_restore', 20:20:26 INFO - 'tabpaint']}, 20:20:26 INFO - 'other-e10s': {'tests': ['a11yr', 20:20:26 INFO - 'ts_paint', 20:20:26 INFO - 'tpaint', 20:20:26 INFO - 'sessionrestore', 20:20:26 INFO - 'sessionrestore_no_auto_restore', 20:20:26 INFO - 'tabpaint']}, 20:20:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:20:26 INFO - 'ts_paint', 20:20:26 INFO - 'tpaint', 20:20:26 INFO - 'sessionrestore', 20:20:26 INFO - 'sessionrestore_no_auto_restore', 20:20:26 INFO - 'tabpaint']}, 20:20:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:20:26 INFO - 'ts_paint', 20:20:26 INFO - 'tpaint', 20:20:26 INFO - 'sessionrestore', 20:20:26 INFO - 'sessionrestore_no_auto_restore', 20:20:26 INFO - 'tabpaint']}, 20:20:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['a11yr', 20:20:26 INFO - 'ts_paint', 20:20:26 INFO - 'tpaint', 20:20:26 INFO - 'sessionrestore', 20:20:26 INFO - 'sessionrestore_no_auto_restore', 20:20:26 INFO - 'tabpaint']}, 20:20:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['a11yr', 20:20:26 INFO - 'ts_paint', 20:20:26 INFO - 'tpaint', 20:20:26 INFO - 'sessionrestore', 20:20:26 INFO - 'sessionrestore_no_auto_restore', 20:20:26 INFO - 'tabpaint']}, 20:20:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['tsvgx', 20:20:26 INFO - 'tsvgr_opacity', 20:20:26 INFO - 'tart', 20:20:26 INFO - 'tscrollx', 20:20:26 INFO - 'cart']}, 20:20:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:20:26 INFO - 'tsvgr_opacity', 20:20:26 INFO - 'tart', 20:20:26 INFO - 'tscrollx', 20:20:26 INFO - 'cart']}, 20:20:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:26 INFO - 'talos_options': ['--disable-e10s'], 20:20:26 INFO - 'tests': ['tp5o']}, 20:20:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:26 INFO - 'tests': ['tp5o']}, 20:20:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:26 INFO - 'talos_options': ['--disable-e10s', 20:20:26 INFO - '--xperf_path', 20:20:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:20:26 INFO - 'tests': ['tp5n']}, 20:20:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:20:26 INFO - 'talos_options': ['--xperf_path', 20:20:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:20:26 INFO - 'tests': ['tp5n']}}, 20:20:26 INFO - 'talos.zip': {'path': '', 20:20:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:20:26 INFO - Running post-action listener: _resource_record_post_action 20:20:26 INFO - ##### 20:20:26 INFO - ##### Finished populate-webroot step (success) 20:20:26 INFO - ##### 20:20:26 INFO - ##### 20:20:26 INFO - ##### Running create-virtualenv step. 20:20:26 INFO - ##### 20:20:26 INFO - Running pre-action listener: _resource_record_pre_action 20:20:26 INFO - Running main action method: create_virtualenv 20:20:26 INFO - Creating virtualenv /builds/slave/test/build/venv 20:20:26 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 20:20:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:20:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:20:27 INFO - Using real prefix '/usr' 20:20:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:20:27 INFO - Installing distribute.............................................................................................................................................................................................done. 20:20:29 INFO - Installing pip.................done. 20:20:29 INFO - Return code: 0 20:20:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:20:29 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')]} 20:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:29 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')]} 20:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:29 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 0xee5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', '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 20:20:29 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 20:20:29 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 20:20:29 INFO - Using env: {'DISPLAY': ':0', 20:20:29 INFO - 'HOME': '/home/cltbld', 20:20:29 INFO - 'LANG': 'en_US.UTF-8', 20:20:29 INFO - 'LANGUAGE': 'en_US:en', 20:20:29 INFO - 'LOGNAME': 'cltbld', 20:20:29 INFO - 'MAIL': '/var/mail/cltbld', 20:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:29 INFO - 'MOZ_NO_REMOTE': '1', 20:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:29 INFO - 'NO_EM_RESTART': '1', 20:20:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:29 INFO - 'PWD': '/builds/slave/test', 20:20:29 INFO - 'SHELL': '/bin/bash', 20:20:29 INFO - 'SHLVL': '1', 20:20:29 INFO - 'TERM': 'linux', 20:20:29 INFO - 'TMOUT': '86400', 20:20:29 INFO - 'USER': 'cltbld', 20:20:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:29 INFO - '_': '/tools/buildbot/bin/python'} 20:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:29 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 20:20:29 INFO - Cleaning up... 20:20:29 INFO - Return code: 0 20:20:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:20:29 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')]} 20:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:29 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')]} 20:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:29 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 0xee5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', '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 20:20:29 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 20:20:29 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 20:20:29 INFO - Using env: {'DISPLAY': ':0', 20:20:29 INFO - 'HOME': '/home/cltbld', 20:20:29 INFO - 'LANG': 'en_US.UTF-8', 20:20:29 INFO - 'LANGUAGE': 'en_US:en', 20:20:29 INFO - 'LOGNAME': 'cltbld', 20:20:29 INFO - 'MAIL': '/var/mail/cltbld', 20:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:29 INFO - 'MOZ_NO_REMOTE': '1', 20:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:29 INFO - 'NO_EM_RESTART': '1', 20:20:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:29 INFO - 'PWD': '/builds/slave/test', 20:20:29 INFO - 'SHELL': '/bin/bash', 20:20:29 INFO - 'SHLVL': '1', 20:20:29 INFO - 'TERM': 'linux', 20:20:29 INFO - 'TMOUT': '86400', 20:20:29 INFO - 'USER': 'cltbld', 20:20:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:29 INFO - '_': '/tools/buildbot/bin/python'} 20:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:29 INFO - Downloading/unpacking psutil>=3.1.1 20:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:20:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:20:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:20:31 INFO - Installing collected packages: psutil 20:20:31 INFO - Running setup.py install for psutil 20:20:32 INFO - building 'psutil._psutil_linux' extension 20:20:32 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 20:20:32 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 20:20:32 INFO - building 'psutil._psutil_posix' extension 20:20:32 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 20:20:32 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 20:20:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:20:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:20:32 INFO - Successfully installed psutil 20:20:32 INFO - Cleaning up... 20:20:32 INFO - Return code: 0 20:20:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:32 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 0xee5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', '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 20:20:32 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 20:20:32 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 20:20:32 INFO - Using env: {'DISPLAY': ':0', 20:20:32 INFO - 'HOME': '/home/cltbld', 20:20:32 INFO - 'LANG': 'en_US.UTF-8', 20:20:32 INFO - 'LANGUAGE': 'en_US:en', 20:20:32 INFO - 'LOGNAME': 'cltbld', 20:20:32 INFO - 'MAIL': '/var/mail/cltbld', 20:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:32 INFO - 'MOZ_NO_REMOTE': '1', 20:20:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:32 INFO - 'NO_EM_RESTART': '1', 20:20:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:32 INFO - 'PWD': '/builds/slave/test', 20:20:32 INFO - 'SHELL': '/bin/bash', 20:20:32 INFO - 'SHLVL': '1', 20:20:32 INFO - 'TERM': 'linux', 20:20:32 INFO - 'TMOUT': '86400', 20:20:32 INFO - 'USER': 'cltbld', 20:20:32 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:32 INFO - '_': '/tools/buildbot/bin/python'} 20:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:20:32 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 20:20:32 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 20:20:32 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 20:20:32 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 20:20:32 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 20:20:32 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 20:20:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:20:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:20:34 INFO - Installing collected packages: mozsystemmonitor 20:20:34 INFO - Running setup.py install for mozsystemmonitor 20:20:34 INFO - Successfully installed mozsystemmonitor 20:20:34 INFO - Cleaning up... 20:20:34 INFO - Return code: 0 20:20:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xee5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', '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 20:20:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:20:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:20:34 INFO - Using env: {'DISPLAY': ':0', 20:20:34 INFO - 'HOME': '/home/cltbld', 20:20:34 INFO - 'LANG': 'en_US.UTF-8', 20:20:34 INFO - 'LANGUAGE': 'en_US:en', 20:20:34 INFO - 'LOGNAME': 'cltbld', 20:20:34 INFO - 'MAIL': '/var/mail/cltbld', 20:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:34 INFO - 'MOZ_NO_REMOTE': '1', 20:20:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:34 INFO - 'NO_EM_RESTART': '1', 20:20:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:34 INFO - 'PWD': '/builds/slave/test', 20:20:34 INFO - 'SHELL': '/bin/bash', 20:20:34 INFO - 'SHLVL': '1', 20:20:34 INFO - 'TERM': 'linux', 20:20:34 INFO - 'TMOUT': '86400', 20:20:34 INFO - 'USER': 'cltbld', 20:20:34 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:34 INFO - '_': '/tools/buildbot/bin/python'} 20:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:35 INFO - Downloading/unpacking jsonschema==2.5.1 20:20:35 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 20:20:35 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 20:20:35 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 20:20:35 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 20:20:35 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 20:20:35 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 20:20:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:20:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:20:37 INFO - Downloading functools32-3.2.3-2.tar.gz 20:20:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:20:37 INFO - warning: no files found matching '*.txt' 20:20:37 INFO - no previously-included directories found matching 'build' 20:20:37 INFO - no previously-included directories found matching 'dist' 20:20:37 INFO - no previously-included directories found matching '.git*' 20:20:37 INFO - Installing collected packages: jsonschema, functools32 20:20:37 INFO - Running setup.py install for functools32 20:20:37 INFO - warning: no files found matching '*.txt' 20:20:37 INFO - no previously-included directories found matching 'build' 20:20:37 INFO - no previously-included directories found matching 'dist' 20:20:37 INFO - no previously-included directories found matching '.git*' 20:20:37 INFO - Successfully installed jsonschema functools32 20:20:37 INFO - Cleaning up... 20:20:37 INFO - Return code: 0 20:20:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:37 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 0xee5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', '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 20:20:37 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 20:20:37 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 20:20:37 INFO - Using env: {'DISPLAY': ':0', 20:20:37 INFO - 'HOME': '/home/cltbld', 20:20:37 INFO - 'LANG': 'en_US.UTF-8', 20:20:37 INFO - 'LANGUAGE': 'en_US:en', 20:20:37 INFO - 'LOGNAME': 'cltbld', 20:20:37 INFO - 'MAIL': '/var/mail/cltbld', 20:20:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:37 INFO - 'MOZ_NO_REMOTE': '1', 20:20:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:37 INFO - 'NO_EM_RESTART': '1', 20:20:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:37 INFO - 'PWD': '/builds/slave/test', 20:20:37 INFO - 'SHELL': '/bin/bash', 20:20:37 INFO - 'SHLVL': '1', 20:20:37 INFO - 'TERM': 'linux', 20:20:37 INFO - 'TMOUT': '86400', 20:20:37 INFO - 'USER': 'cltbld', 20:20:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:20:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:37 INFO - '_': '/tools/buildbot/bin/python'} 20:20:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:37 INFO - Downloading/unpacking blobuploader==1.2.4 20:20:37 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 20:20:37 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 20:20:37 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 20:20:37 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 20:20:37 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 20:20:37 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 20:20:39 INFO - Downloading blobuploader-1.2.4.tar.gz 20:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:20:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:20:39 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 20:20:39 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 20:20:39 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 20:20:39 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 20:20:39 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 20:20:39 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 20:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:20:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:20:40 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 20:20:40 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 20:20:40 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 20:20:40 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 20:20:40 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 20:20:40 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 20:20:40 INFO - Downloading docopt-0.6.1.tar.gz 20:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:20:40 INFO - Installing collected packages: blobuploader, requests, docopt 20:20:40 INFO - Running setup.py install for blobuploader 20:20:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:20:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:20:40 INFO - Running setup.py install for requests 20:20:40 INFO - Running setup.py install for docopt 20:20:40 INFO - Successfully installed blobuploader requests docopt 20:20:40 INFO - Cleaning up... 20:20:40 INFO - Return code: 0 20:20:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:20: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')]} 20:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20: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')]} 20:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:40 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 0xee5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', '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 20:20:40 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 20:20:40 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 20:20:40 INFO - Using env: {'DISPLAY': ':0', 20:20:40 INFO - 'HOME': '/home/cltbld', 20:20:40 INFO - 'LANG': 'en_US.UTF-8', 20:20:40 INFO - 'LANGUAGE': 'en_US:en', 20:20:40 INFO - 'LOGNAME': 'cltbld', 20:20:40 INFO - 'MAIL': '/var/mail/cltbld', 20:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:40 INFO - 'MOZ_NO_REMOTE': '1', 20:20:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:40 INFO - 'NO_EM_RESTART': '1', 20:20:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:40 INFO - 'PWD': '/builds/slave/test', 20:20:40 INFO - 'SHELL': '/bin/bash', 20:20:40 INFO - 'SHLVL': '1', 20:20:40 INFO - 'TERM': 'linux', 20:20:40 INFO - 'TMOUT': '86400', 20:20:40 INFO - 'USER': 'cltbld', 20:20:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:40 INFO - '_': '/tools/buildbot/bin/python'} 20:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:20:41 INFO - Running setup.py (path:/tmp/pip-jx2fJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:20:41 INFO - Running setup.py (path:/tmp/pip-mSne8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:20:41 INFO - Running setup.py (path:/tmp/pip-xxC6sH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:20:41 INFO - Running setup.py (path:/tmp/pip-eQujvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:20:41 INFO - Running setup.py (path:/tmp/pip-4pBbdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:20:41 INFO - Running setup.py (path:/tmp/pip-o0Nqet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:20:41 INFO - Running setup.py (path:/tmp/pip-SE7jb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:20:41 INFO - Running setup.py (path:/tmp/pip-MWRBXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:20:41 INFO - Running setup.py (path:/tmp/pip-w3y28c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:20:41 INFO - Running setup.py (path:/tmp/pip-mQ2Ia7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:20:41 INFO - Running setup.py (path:/tmp/pip-CsVoUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:20:41 INFO - Running setup.py (path:/tmp/pip-Ovm5_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:20:41 INFO - Running setup.py (path:/tmp/pip-YSF1uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:20:42 INFO - Running setup.py (path:/tmp/pip-O76ESC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:20:42 INFO - Running setup.py (path:/tmp/pip-itTM9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:20:42 INFO - Running setup.py (path:/tmp/pip-U1Eqvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:20:42 INFO - Running setup.py (path:/tmp/pip-403ynr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:20:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:20:42 INFO - Running setup.py install for manifestparser 20:20:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:20:42 INFO - Running setup.py install for mozcrash 20:20:42 INFO - Running setup.py install for mozdebug 20:20:42 INFO - Running setup.py install for mozdevice 20:20:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:20:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:20:42 INFO - Running setup.py install for mozfile 20:20:42 INFO - Running setup.py install for mozhttpd 20:20:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:20:42 INFO - Running setup.py install for mozinfo 20:20:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Running setup.py install for mozInstall 20:20:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Running setup.py install for mozleak 20:20:43 INFO - Running setup.py install for mozlog 20:20:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Running setup.py install for moznetwork 20:20:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Running setup.py install for mozprocess 20:20:43 INFO - Running setup.py install for mozprofile 20:20:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Running setup.py install for mozrunner 20:20:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:20:43 INFO - Running setup.py install for mozscreenshot 20:20:43 INFO - Running setup.py install for moztest 20:20:44 INFO - Running setup.py install for mozversion 20:20:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:20:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:20:44 INFO - Cleaning up... 20:20:44 INFO - Return code: 0 20:20:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:44 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 0xee5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', '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 20:20:44 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 20:20:44 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 20:20:44 INFO - Using env: {'DISPLAY': ':0', 20:20:44 INFO - 'HOME': '/home/cltbld', 20:20:44 INFO - 'LANG': 'en_US.UTF-8', 20:20:44 INFO - 'LANGUAGE': 'en_US:en', 20:20:44 INFO - 'LOGNAME': 'cltbld', 20:20:44 INFO - 'MAIL': '/var/mail/cltbld', 20:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:44 INFO - 'MOZ_NO_REMOTE': '1', 20:20:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:44 INFO - 'NO_EM_RESTART': '1', 20:20:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:44 INFO - 'PWD': '/builds/slave/test', 20:20:44 INFO - 'SHELL': '/bin/bash', 20:20:44 INFO - 'SHLVL': '1', 20:20:44 INFO - 'TERM': 'linux', 20:20:44 INFO - 'TMOUT': '86400', 20:20:44 INFO - 'USER': 'cltbld', 20:20:44 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:44 INFO - '_': '/tools/buildbot/bin/python'} 20:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:20:44 INFO - Running setup.py (path:/tmp/pip-Lc3BiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:20:44 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)) 20:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:20:44 INFO - Running setup.py (path:/tmp/pip-Vto6K_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:20:44 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)) 20:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:20:44 INFO - Running setup.py (path:/tmp/pip-0TppWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:20:44 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)) 20:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:20:44 INFO - Running setup.py (path:/tmp/pip-ndwTeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:20:44 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)) 20:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:20:44 INFO - Running setup.py (path:/tmp/pip-wBYEiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:20:44 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)) 20:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:20:44 INFO - Running setup.py (path:/tmp/pip-xFXzUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:20:44 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)) 20:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:20:44 INFO - Running setup.py (path:/tmp/pip-qUTWKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:20:44 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)) 20:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:20:44 INFO - Running setup.py (path:/tmp/pip-NyQJU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:20:44 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)) 20:20:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:20:44 INFO - Running setup.py (path:/tmp/pip-Crn3Sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:20:45 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)) 20:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:20:45 INFO - Running setup.py (path:/tmp/pip-8PySvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:20:45 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)) 20:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:20:45 INFO - Running setup.py (path:/tmp/pip-N6EUp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:20:45 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)) 20:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:20:45 INFO - Running setup.py (path:/tmp/pip-IZ1OPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:20:45 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)) 20:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:20:45 INFO - Running setup.py (path:/tmp/pip-Q0sG1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:20:45 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)) 20:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:20:45 INFO - Running setup.py (path:/tmp/pip-KUxQzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:20:45 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)) 20:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:20:45 INFO - Running setup.py (path:/tmp/pip-JV6HZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:20:45 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)) 20:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:20:45 INFO - Running setup.py (path:/tmp/pip-EBDOhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:20:45 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)) 20:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:20:45 INFO - Running setup.py (path:/tmp/pip-hzXjNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:20:45 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)) 20:20:45 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)) 20:20:45 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)) 20:20:45 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)) 20:20:45 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)) 20:20:45 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)) 20:20:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:20:45 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 20:20:45 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 20:20:45 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 20:20:45 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 20:20:45 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 20:20:45 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 20:20:47 INFO - Downloading blessings-1.6.tar.gz 20:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:20:47 INFO - Installing collected packages: blessings 20:20:47 INFO - Running setup.py install for blessings 20:20:47 INFO - Successfully installed blessings 20:20:47 INFO - Cleaning up... 20:20:47 INFO - Return code: 0 20:20:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:20:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:20:47 INFO - Reading from file tmpfile_stdout 20:20:47 INFO - Current package versions: 20:20:47 INFO - argparse == 1.2.1 20:20:47 INFO - blessings == 1.6 20:20:47 INFO - blobuploader == 1.2.4 20:20:47 INFO - docopt == 0.6.1 20:20:47 INFO - functools32 == 3.2.3-2 20:20:47 INFO - jsonschema == 2.5.1 20:20:47 INFO - manifestparser == 1.1 20:20:47 INFO - mozInstall == 1.12 20:20:47 INFO - mozcrash == 0.17 20:20:47 INFO - mozdebug == 0.1 20:20:47 INFO - mozdevice == 0.48 20:20:47 INFO - mozfile == 1.2 20:20:47 INFO - mozhttpd == 0.7 20:20:47 INFO - mozinfo == 0.9 20:20:47 INFO - mozleak == 0.1 20:20:47 INFO - mozlog == 3.2 20:20:47 INFO - moznetwork == 0.27 20:20:47 INFO - mozprocess == 0.23 20:20:47 INFO - mozprofile == 0.28 20:20:47 INFO - mozrunner == 6.12 20:20:47 INFO - mozscreenshot == 0.1 20:20:47 INFO - mozsystemmonitor == 0.3 20:20:47 INFO - moztest == 0.7 20:20:47 INFO - mozversion == 1.4 20:20:47 INFO - psutil == 3.1.1 20:20:47 INFO - requests == 1.2.3 20:20:47 INFO - wsgiref == 0.1.2 20:20:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:20:47 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')]} 20:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:47 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')]} 20:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:47 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 0xee5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', '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 20:20:47 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 20:20:47 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 20:20:47 INFO - Using env: {'DISPLAY': ':0', 20:20:47 INFO - 'HOME': '/home/cltbld', 20:20:47 INFO - 'LANG': 'en_US.UTF-8', 20:20:47 INFO - 'LANGUAGE': 'en_US:en', 20:20:47 INFO - 'LOGNAME': 'cltbld', 20:20:47 INFO - 'MAIL': '/var/mail/cltbld', 20:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:47 INFO - 'MOZ_NO_REMOTE': '1', 20:20:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:47 INFO - 'NO_EM_RESTART': '1', 20:20:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:47 INFO - 'PWD': '/builds/slave/test', 20:20:47 INFO - 'SHELL': '/bin/bash', 20:20:47 INFO - 'SHLVL': '1', 20:20:47 INFO - 'TERM': 'linux', 20:20:47 INFO - 'TMOUT': '86400', 20:20:47 INFO - 'USER': 'cltbld', 20:20:47 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:47 INFO - '_': '/tools/buildbot/bin/python'} 20:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:48 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)) 20:20:48 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)) 20:20:48 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)) 20:20:48 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)) 20:20:48 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)) 20:20:48 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)) 20:20:48 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)) 20:20:48 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)) 20:20:48 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)) 20:20:48 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)) 20:20:48 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)) 20:20:48 INFO - Cleaning up... 20:20:48 INFO - Return code: 0 20:20:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:20:48 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')]} 20:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:48 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')]} 20:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:20:48 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 0xee5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', '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 20:20:48 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 20:20:48 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 20:20:48 INFO - Using env: {'DISPLAY': ':0', 20:20:48 INFO - 'HOME': '/home/cltbld', 20:20:48 INFO - 'LANG': 'en_US.UTF-8', 20:20:48 INFO - 'LANGUAGE': 'en_US:en', 20:20:48 INFO - 'LOGNAME': 'cltbld', 20:20:48 INFO - 'MAIL': '/var/mail/cltbld', 20:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:48 INFO - 'MOZ_NO_REMOTE': '1', 20:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:20:48 INFO - 'NO_EM_RESTART': '1', 20:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:48 INFO - 'PWD': '/builds/slave/test', 20:20:48 INFO - 'SHELL': '/bin/bash', 20:20:48 INFO - 'SHLVL': '1', 20:20:48 INFO - 'TERM': 'linux', 20:20:48 INFO - 'TMOUT': '86400', 20:20:48 INFO - 'USER': 'cltbld', 20:20:48 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:48 INFO - '_': '/tools/buildbot/bin/python'} 20:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:20:48 INFO - Cleaning up... 20:20:48 INFO - Return code: 0 20:20:48 INFO - Running post-action listener: _resource_record_post_action 20:20:48 INFO - Running post-action listener: _start_resource_monitoring 20:20:48 INFO - Starting resource monitoring. 20:20:48 INFO - ##### 20:20:48 INFO - ##### Finished create-virtualenv step (success) 20:20:48 INFO - ##### 20:20:48 INFO - ##### 20:20:48 INFO - ##### Running install step. 20:20:48 INFO - ##### 20:20:48 INFO - Running pre-action listener: _resource_record_pre_action 20:20:48 INFO - Running main action method: install 20:20:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:20:48 INFO - Reading from file tmpfile_stdout 20:20:48 INFO - Detecting whether we're running mozinstall >=1.0... 20:20:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:20:48 INFO - Reading from file tmpfile_stdout 20:20:48 INFO - Output received: 20:20:48 INFO - Usage: mozinstall [options] installer 20:20:48 INFO - Options: 20:20:48 INFO - -h, --help show this help message and exit 20:20:48 INFO - -d DEST, --destination=DEST 20:20:48 INFO - Directory to install application into. [default: 20:20:48 INFO - "/builds/slave/test"] 20:20:48 INFO - --app=APP Application being installed. [default: firefox] 20:20:48 INFO - mkdir: /builds/slave/test/build/application 20:20:48 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'] 20:20:48 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 20:21:05 INFO - Reading from file tmpfile_stdout 20:21:05 INFO - Output received: 20:21:05 INFO - /builds/slave/test/build/application/firefox/firefox 20:21:05 INFO - Running post-action listener: _resource_record_post_action 20:21:05 INFO - ##### 20:21:05 INFO - ##### Finished install step (success) 20:21:05 INFO - ##### 20:21:05 INFO - ##### 20:21:05 INFO - ##### Running run-tests step. 20:21:05 INFO - ##### 20:21:05 INFO - Running pre-action listener: _resource_record_pre_action 20:21:05 INFO - Running main action method: run_tests 20:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:21:05 INFO - Python 2.7.3 20:21:05 INFO - Return code: 0 20:21:05 INFO - grabbing minidump binary from tooltool 20:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:05 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 0x11a43b0>, '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 0x11a52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11a5740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:21:05 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 20:21:05 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 20:21:05 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 20:21:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:21:06 INFO - Return code: 0 20:21:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:21:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:21:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:21:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:21:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:21:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 20:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 20:21:06 INFO - Using env: {'DISPLAY': ':0', 20:21:06 INFO - 'HOME': '/home/cltbld', 20:21:06 INFO - 'LANG': 'en_US.UTF-8', 20:21:06 INFO - 'LANGUAGE': 'en_US:en', 20:21:06 INFO - 'LOGNAME': 'cltbld', 20:21:06 INFO - 'MAIL': '/var/mail/cltbld', 20:21:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:21:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:21:06 INFO - 'MOZ_NO_REMOTE': '1', 20:21:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:21:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:21:06 INFO - 'NO_EM_RESTART': '1', 20:21:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:21:06 INFO - 'PWD': '/builds/slave/test', 20:21:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:21:06 INFO - 'SHELL': '/bin/bash', 20:21:06 INFO - 'SHLVL': '1', 20:21:06 INFO - 'TERM': 'linux', 20:21:06 INFO - 'TMOUT': '86400', 20:21:06 INFO - 'USER': 'cltbld', 20:21:06 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894', 20:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:21:06 INFO - '_': '/tools/buildbot/bin/python'} 20:21:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 20:21:06 INFO - mozversion application_buildid: 20160715173018 20:21:06 INFO - mozversion application_changeset: 0c27d48b278dba5493f9c98581acfd71889f7b63 20:21:06 INFO - mozversion application_display_name: Nightly 20:21:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:21:06 INFO - mozversion application_name: Firefox 20:21:06 INFO - mozversion application_remotingname: firefox 20:21:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:21:06 INFO - mozversion application_vendor: Mozilla 20:21:06 INFO - mozversion application_version: 50.0a1 20:21:06 INFO - mozversion platform_buildid: 20160715173018 20:21:06 INFO - mozversion platform_changeset: 0c27d48b278dba5493f9c98581acfd71889f7b63 20:21:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:21:06 INFO - mozversion platform_version: 50.0a1 20:21:06 INFO - using testdate: 1468639266 20:21:06 INFO - actual date: 1468639266 20:21:06 INFO - starting webserver on 'localhost:58145' 20:21:06 INFO - SUITE-START | Running 2 tests 20:21:06 INFO - TEST-START | dromaeo_css 20:21:06 INFO - operating with platform_type : linux_ 20:21:06 INFO - Initialising browser for dromaeo_css test... 20:21:06 INFO - TEST-INFO | started process 15231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0_IrOM/profile http://localhost:58145/getInfo.html) 20:21:10 INFO - PROCESS | 15231 | __metrics Screen width/height:1600/1200 20:21:10 INFO - PROCESS | 15231 | colorDepth:24 20:21:10 INFO - PROCESS | 15231 | Browser inner width/height: 1024/697 20:21:10 INFO - PROCESS | 15231 | __metrics 20:21:11 INFO - PROCESS | 15231 | 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" 20:21:11 INFO - PROCESS | 15231 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:21:11 INFO - PROCESS | 15231 | console.error: PushService: 20:21:11 INFO - PROCESS | 15231 | stateChangeProcessEnqueue: Error transitioning state 20:21:11 INFO - PROCESS | 15231 | UnknownError 20:21:12 INFO - TEST-INFO | 15231: exit 0 20:21:12 INFO - Browser initialized. 20:21:12 INFO - Running cycle 1/1 for dromaeo_css test... 20:21:12 INFO - TEST-INFO | started process 15317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0_IrOM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:21:24 INFO - PROCESS | 15317 | RSS: Main: 157831168 20:21:24 INFO - PROCESS | 15317 | 20:22:15 INFO - PROCESS | 15317 | 1468639335463 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] 20:24:23 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:58145/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58145/tests/dromaeo/cssquery-ext.html) 20:24:24 INFO - PROCESS | 15317 | RSS: Main: 291991552 20:24:24 INFO - PROCESS | 15317 | 20:27:27 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:58145/tests/dromaeo/cssquery-ext.html (next: http://localhost:58145/tests/dromaeo/cssquery-jquery.html) 20:27:27 INFO - PROCESS | 15317 | RSS: Main: 239206400 20:27:27 INFO - PROCESS | 15317 | 20:30:29 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:58145/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58145/tests/dromaeo/cssquery-mootools.html) 20:30:30 INFO - PROCESS | 15317 | RSS: Main: 239386624 20:30:30 INFO - PROCESS | 15317 | 20:33:32 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:58145/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58145/tests/dromaeo/cssquery-prototype.html) 20:33:33 INFO - PROCESS | 15317 | RSS: Main: 239149056 20:33:33 INFO - PROCESS | 15317 | 20:36:35 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:58145/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58145/tests/dromaeo/cssquery-yui.html) 20:36:36 INFO - PROCESS | 15317 | RSS: Main: 224821248 20:36:36 INFO - PROCESS | 15317 | 20:39:39 INFO - PROCESS | 15317 | Cycle 1(1): loaded http://localhost:58145/tests/dromaeo/cssquery-yui.html (next: http://localhost:58145/tests/dromaeo/cssquery-dojo.html) 20:39:39 INFO - PROCESS | 15317 | RSS: Main: 266833920 20:39:39 INFO - PROCESS | 15317 | 20:39:39 INFO - PROCESS | 15317 | __start_tp_report 20:39:39 INFO - PROCESS | 15317 | _x_x_mozilla_page_load 20:39:39 INFO - PROCESS | 15317 | _x_x_mozilla_page_load_details 20:39:39 INFO - PROCESS | 15317 | |i|pagename|runs| 20:39:39 INFO - PROCESS | 15317 | |0;dojo.html;3017.6831432699564;3056.2817737831606;3059.0669845697053;3070.1864006038404;3106.378724255148;5218.086834803904;5280.815171468999;5312.389075256342;5411.972940135299;5466.863328416786;5357.062514060043;5364.785408583662;5460.071787796074;5515.145152501359;5579.804706835262;4864.294677271802;4882.829100981467;4883.731394773286;4889.608831293488;5071.974640126794;4977.601791856661;4977.975110124445;5016.648834581581;5030.949690503103;5038.370203724535;4772.880677983044;4803.807847686088;4850.5877000455;4857.562819346241;4897.436794768611;4824.686395384324;4828.46886842447;4847.466778654345;4916.557509824097;4952.182889823175;4872.049950259665;4887.780049897728;4890.559849613516;4899.118158731427;4902.607791376681;5248.71132087735;5342.626016178869;5405.37838148614;5457.945420545823;5482.479164479368;5851.034579294454;5868.383819698955;5882.205902203191;5885.646861188342;5886.941130588682;5643.266375371176;5904.704764761745;5926.644401335933;5928.584999050068;5934.97032514839;5280;5362.061639213122;5368.006918720011;5424.996375670474;5480.342358916955;6049.1833602463985;6051.800064687898;6057.561756792314;6088.269407671108;6104.145419641254;5822.534197264209;5844.824655260349;5847.415258474203;5849.181114643953;5854.151148083589;5571.582131340166;5752.827415177551;5796.188533605298;5807.477327040587;5916.1421593868645;5755.712214389264;5810.593258471867;5810.6513609183585;5813.88372232553;5867.735951882176;5731.627444216113;5787.913181302284;5802.274715660543;5810.4189581041555;5816.185733997244;4281.336392859112;4387.670924680661;4412.161689279027;4421.226285400042;4421.447319085114;3930.7051971102283;3932.7050471214775;3944.2703099926607;3973.5827738087087;3986.123052928351;5275.60432967529;5545.445455454423;5555.666660000413;5556.638818476786;5557.916631250535;5760.74076666551;5796.652200867961;5810.622309549865;5847.883042339129;5848.853778655568;5406.540444062299;5457.590680698963;5460.235567020621;5463.453654634504;5464.12573988168;4806.711597304173;4993.80024799004;5002.874928126826;5005.099082165172;5050.570701490415;6070.3019152797115;6116.944513560587;6127.250613123855;6127.464420129234;6131.070842498441;5885.352611212848;5889.263842019747;5891.823245302648;5891.970540147272;5923.851903702442;5771.7402716877;5964.821055368346;6025.879482410415;6101.877962440815;6120.938790612215;5727.055035919193;5728.684922329312;5733.111367738007;5733.684647344436;5775.133729940542;5788.247527821524;5794.3626201117;5800.477956983892;5828.94171058284;5840.8247752567495;5485.753141108739;5685.260916081049;5690.687012214368;5737.196792448977;5739.8565035874435;5787.160861674949;5787.826365209051;5794.159846822272;5796.536277097906;5801.448862358069;5701.543876489788;5715.457031581993;5719.0277652798295;5719.599628025998;5720.19917211599;5437.048516509516;5462.754176062007;5527.115661494142;5638.464345887134;5717;5545.7781688732;5546.057170280981;5546.195801608664;5587.525060369914;5591.329040515002 20:39:39 INFO - PROCESS | 15317 | |1;ext.html;583.2650859916504;613.2150846915948;632.7627139822566;643.6942452335144;644.6776611694152;31627.525587116183;32317.51523727143;34531.410144239155;34646.133846653815;45235.54764452355;43060.354094688606;47059.294110588366;47069.29396059062;47140.292895606595;47152;9111.908880911189;9243.445393276425;9248.583813728399;9253.491057991807;9253.676121335755;9076.818463630723;9162.496062716547;9175.357724959256;9176.220021298197;9182.03588623195;9225.123613256763;9253.491057991807;9274.07259274074;9280.535973201348;9283.72148835536;9124.45253284805;9172.174504294611;9202.723918282396;9213.355065145442;9227;9097.63609455621;9134.77163070922;9164.633414663405;9168.220701240336;9177.495237761921;44038.33942490865;44366.11267774626;44400.3339949901;44419.333709994375;44430.77784611056;3584.336897673939;3832.482614846988;3845.7692538447504;3852.8651497197743;4186.937195942063;33743.83128084368;34259.486107708406;34379.14052148716;35054.12364690903;35200.94397168089;4508.661850361236;4569.246074397721;4584.426946631671;4646.570884267327;4689.788959496796;363114;364245;365075;365541;366056;52633;57652.42347576554;60848.69575652182;61884.69057654773;61951;59334.406655932886;63506.68246658737;64536.35463645397;64740.676296618214;64803.351966479735;17688.9115554424;17830.286788528574;17842.46472605824;17956;17969.281228750966;13166.539171128965;13207.207567545978;13208.537701180605;13212.933935330455;13220.867791321964;15787.605309867116;17889.463316100537;18148.63702725938;18391.632167356845;18453.446396608124;5997.640141591519;6035.486983606354;6051.727672254759;6207.162033125561;6269.65516896566;1044.3525014491065;1057.1859668055554;1082.0045558086579;1095.5727266366123;1166.6383421139474;33816.478258478426;34522.48216276758;34962.125946851535;35052;35077.29845403129;1112.2047735868894;1114.9777004459866;1126.3930524156076;1146.0487795130023;1161.7095726068483;16987.13141554428;17599.73600395995;17644.470665880042;17760.55598610045;18205.7269140963;529405;537837;538071;538967;539000;61240;62256;62269.37730622636;62333;62340;1275.2029981261712;1397.175666356834;1407.6691977385306;1408.64783804049;1424.1312799192276;32417.675823241465;35118.64881351256;35218.295634087684;35354.11614709653;35550.12590690348;55348.44651553433;57914.10429478425;60277;60511;64766.67616661887;519911;528696;531952;533213;533765;38540.80729596334;45078.32382514199;45307.7734611338;46357;46650;51452;51963;52451.213231802314;52563.737181313845;53344;43138.352924706785;43875.341869871336;44190.33714494349;44393.33409998917;45283.99792440048;44123;44129.55870441254;44204.557954420045;44490;45035.774821125684;43894;44710.3293450592;44961.77519112384;45057.098858022;45107.54892451034;36343.818280908425;37975.430368543944;38566;38898.4165237516;38915.63992335487;29379.265518362212;29407.235565866118;29451.410971780017;29776.702232977394;29843.701562984093 20:39:39 INFO - PROCESS | 15317 | |2;jquery.html;363.7653713354886;364.5625249700362;364.7082334132694;385.331449934364;387.9185371072075;18904.338348157817;19908.900455497722;20009;20758.27346042889;20785.792142078575;18514.25942962283;19353;19677.70483442746;19910;19976.101075451603;5200.817971371002;5313.123334649778;5353.3843607985;5354.250404943292;5591.189277554752;5824.563157763185;5886.440788125121;5913.231279933602;5920.940790592082;5922.703864806743;5902.144189092579;5902.409759024084;5906.05503119501;7182.46131540134;7461.925380746177;7240.601766902818;7336.486445948785;7431.29402706742;7489.9625501872415;7503.812404689872;7236.204017558064;7278.09023872016;7282.81792955175;7287.6356182190675;7291.234420385889;26316.078937237104;27495.450090998267;27681.723182768117;27686.169414917586;28022.719772802215;2587.728288529715;2794.3992041710926;2806.3264816443907;3035.5598438226552;3403.6085850127056;15337.693246135126;16148.192590370434;16667.33330666784;16670.58323541909;17032.744508832377;1929.6237233739287;2055.4450298419342;2068.2450905419564;2168.1002384010617;2260.7400148982915;94871.52564237134;97777;99421;100982.49508752483;101298.493507532;17661.38185163513;18058;18206.726899096524;18245.452636420927;18347.724784128248;32116;32300.35399292001;32499.837500812344;32519.674803251666;32527.674723252938;9788.608455661837;9998.150157236716;10280.383177009404;10375.118114960162;10443.947780261202;8696.130386961253;8753.868691969625;8767.561621918878;8919.152680495343;8926.794882690883;8913.153250441197;8959.552022398853;9089.81820363589;9093.81748380384;9125.17873391398;3250.8798642138727;3304.686054824788;3444.741644376682;3471.2363280078343;3540.8760693376125;919.4621146629163;961.0869673809908;1001.8547310639916;1029.912457441126;1054.1092776603757;19999.800937584143;20364.18543258253;20449.079791409422;20475;20595.8970205148;990.6037584966072;998.6305067125065;1008.6520150548049;1015.081351377005;1065.3699839245967;10510.369377837354;10564.844192916285;11025.558977640965;11152.275102118336;11162;463021;467247;470103;476595;481243;100994.49502752586;103784;104722.47638761757;105106;105122;1026.1841835740604;1060.6022741471947;1066.648006157966;1072.5763323487022;1082.5128692088592;16974.660506789547;18093.457196284133;18275;18507.167177477317;19024;41586.37620435756;41770.58229417667;41970;44033;46819.72261941768;53434.1984870235;57237;63258.68370658117;64048.67975660092;64530.677346614844;6235.189425374672;7775.650095745818;8553.700620478254;8730.17063378978;8737.43206691563;13026.413811378321;13572.389242484309;13796.103253288504;13843.584692459242;14007.08953917992;12153.088518361335;12200.694982625506;12324.32216228116;12377.504899804268;12477.875221247672;12086.879131208576;12150;12260.938695306466;12322.445489953154;12431.813522797345;12071.155019148575;12194.75610487803;12373.93813030965;12398.690032749253;12695.619131426072;8476.872846907178;8696;8709.869351959598;8757.343199260209;8946.597403116744;8593.398462107592;10901.067743561955;11925.403729813648;11948.163628545919;12222.816657750318 20:39:39 INFO - PROCESS | 15317 | |3;mootools.html;1312.389738771471;1474.3439169569542;1482.8294746104198;1631.9592010199744;1792.6235490546985;8510.531920744357;8859.424137431062;8872.024077351494;9123.908760912389;9128.269738420928;8839.204471597555;9036.548172591378;9038.774030649238;9053.275737940965;9056.637734490629;2037.673972164454;2217.135317226283;2281.4638559938485;2296.540691861626;2320.2575175943703;2375.762423757619;2384.7496012918655;2385.224801939368;2386.9283921482297;2389.8924548395275;2296.242240060776;2306.538692261546;2332.650102484624;2334.614788559894;2336.0655737704883;1950.8634395592314;2222.511047569532;2300.631898896177;2300.7584203658544;2301.919432819843;2300.7929286364306;2343.320437073247;2366.479374537599;2370.3718514593647;2376.0614557249964;8808.339374546935;8867.600957956907;8875.156860098281;8878.644854205888;8880.689175878877;5355.571554275649;5488.670679759187;5494.1758736189495;5494.725263736837;5495.752691128909;3287.6876696713775;3440.9655903440894;3472.75690701651;3478.0609235506527;3487.9825600872086;1144.1190283481756;1151.666016855111;1163.3601519164426;1193.3555879824885;1203.9638810835688;12614.68463288416;12805.167664101804;12815.679608009781;12857.549985750547;12863.485460581565;4724.339216871655;4774.522547745198;4801.4958429365115;4856.757162141878;4908.3619129513645;10709.62516311941;10741.140708743282;10751.569937202581;10758.408287544104;10761.354318740743;5399.811006614749;5419.376771671308;5456.61803673739;5456.645318054314;5465.863353416117;4248.532661407243;4771.308160316735;4879.634027447955;4889.584385327287;4953.529414705597;3906.277338692351;3982.7012974027066;4053.918921621551;4056.411820286042;4057.2494088593703;1155.820847768597;1218.250775772911;1257.6792917806083;1261.5332327039055;1269.289198049077;920.9447433154032;976.2580438866513;999.695092996643;1003.4581326083833;1009.3842755918884;5974.2233509643465;5985.6408615483215;6063.939360606426;6150.477209437158;6157.630542167484;507.3809951858726;668.1180841289548;950.4534892436739;1012.417859730643;1027.984580231297;4273.807678654468;4306;4339.869803905888;4340.283853164256;4380.233459144701;10648;10960;11031.338119712682;11046.889531104585;11052.336859788438;10898.673039808818;10926.453677316102;10927.071198948179;10933;10933.45332733376;7987.041555013436;8317.875231871527;8350.08149103598;8526.062133165222;8560;5794.565407594363;5802.82591522255;5921.940780592311;6161;6274.152989346562;9830.852537212091;9947.303688741718;10111.887692353983;10159.898401015895;10167.44079075658;10130.544125514221;10333.741656458347;10340.689779306633;10378.273520853469;10422.791544168922;8116.2695357418115;8210.425270231026;8236.588170591567;8251.793705157419;8276.29651479631;8622.094680058755;8956.641734330553;8957.372983891066;8974.775630609025;8980.506072166094;7801.141874393698;7976.6410511525955;8011.078725946358;8094.83810323802;8171.918280817354;7689.115751688628;7917.722879699183;7931.246531579491;7993.880091798512;8048.83902321962;7308.671109800178;7350.852982940204;7369.668364923484;7401.259874012556;7401.666924988496;5058.671186372874;5222.529972302511;5306.602004849575;5373.7850485980125;5393.029254734185;5349.919751203657;5362.785488580413;5491.28613280271;5516.668999860021;5663.773449061991 20:39:39 INFO - PROCESS | 15317 | |4;prototype.html;304.7881722203068;317.807726325573;319.94400979828526;322.32955452658473;325.82731152489174;5247.449017853128;5262.605304602151;5299.152135658295;5299.496547827955;5364.846557990031;4993.525615066563;5133.588263227605;5135.589152867771;5138.7173705446185;5320.813771517997;3991.76049437034;3993.3011722948513;4079.5109784928586;4095.6313931746135;4106.8767936961785;4071.491063617048;4076.714629975903;4082.306007978636;4084.97957510212;4085.1829634073306;3893.610638936112;4053.6351728344443;4077;4083.4691490106393;4092.815823287944;4069.755814651101;4069.816858241357;4071.3485842265245;4079.5512493625824;4090.877273681794;3546.627604101558;3578.6600272974024;3589.4206549118303;3592.3892938200306;3595.6875740354844;4869.9269510957365;5195;5200.97399513;5203.791848326063;5206.76569554371;4625.8149674013;4626;4629.328747331618;4637.211674015425;4668.439787225555;5494.725263736797;5527.39198688144;5527.917081243784;5564.527015203713;5569.136783798518;3847.4421208924828;3905.4337121117564;3917.7257591968573;3947.723659343847;3950.940735888964;5688.260526131618;5718.370979192286;5728.541716662657;5729.799457019026;5743.655380677173;3970.225805967866;3976.3041467743606;3978.602139785998;3983.5817239190046;3990.2817492850986;4026.5570787213383;4044.6359827615656;4051.6961227908023;4057.6145266199665;4086.1827634473225;4531.755219352484;4599.2871104978785;4615.746133962597;4626.421697287839;4684.976575117103;5473.17902314656;5489.917651235235;5495.560355171577;5516.475934786269;5520.337559492887;1300.174389262825;1348.3864841497095;1382.2397681275258;1396.8952328575397;1397.2385050147693;931.5761328595473;950.95720692569;955.507913424587;957.4925289596525;960.1550635440767;5344.812931547377;5379.811706590251;5387.676739395649;5395.244665746799;5402.324709411324;5465.836024919259;5643.322801263875;5643.858903527444;5669.914951275734;5672.971635141798;5354.491323324278;5382.515573598395;5399;5417.241586177938;5431.891362172734;2962;2985.477541430241;3007.0678089792236;3007.593974813416;3021.531662592302;5741.19623252745;5824.708764561839;5841.276823337126;5853.239078919798;5872.647641141545;5730.828075157752;5745.9712701437065;5775.5668324875805;5802.158686990447;5832.679202643895;5579.804706835242;5794.768209271582;5826.912596311059;5828.358880523054;5830.679312637845;5580.609357345109;5604.907043126715;5625.184348269396;5646.237757902795;5660.915086273627;5548.611597188319;5566.248556444828;5594.4125866785025;5696.686682232351;5701.802621449542;5566.721663916869;5895.233619629421;5897.697289517883;5918.934591773522;5924.200232968667;3979.602039796113;4011.7392369495888;4025.174839158024;4026.5772093930887;4040.595940405936;5782.277215348082;5783.971080144741;5788.739506722123;5807.82576522705;5821.719221771204;5343.78624855017;5587.55299576041;5590.301212348457;5610.13042978339;5663.631863928831;5465.508104270635;5531.1426728857095;5533.750981205936;5558.194061860927;5587.161925711176;5231.372235331784;5299.708516031656;5460;5470.206820011155;5476.2607048048;5531.446855314436;5535.972320138373;5537.944620553743;5543.390227074938;5617.325920889356;5228.189630607262;5442.782288708408;5454.863628409322;5503.734141147478;5533.833984980457 20:39:39 INFO - PROCESS | 15317 | |5;yui.html;749.306891125709;776.1346099099502;800.31134306613;801.9037715474142;807.3143672240273;15552.222388880553;16179.35282588695;16710.24803883825;16826.074565898885;16833.326666933324;14966.32651530681;15094.094354338748;15110.697786044273;15113.093214407143;15121.773173402407;672.4754691340762;791.268077028749;802.787261375733;819.8032472206658;825.7316372179035;833.2209384225738;835.0688981785296;837.949723016621;838.0488145954325;838.7861095420659;863.1756672377895;866.8179682266732;867.2238346679718;867.3278209387712;867.657275376226;700.9684564194598;838.828040251747;860.7503823891064;861.0399404663817;861.4357595774777;630.3098107572201;634.6953462338104;634.9174607301068;635.0093853588417;636.1062706896818;13572.45710171592;13591.184528928197;13601.931990340085;13623.523176688868;13632.182069075887;13996.93001534996;14279.643008924757;14279.857201427956;14336.28318584077;14529.12825230479;12604.580030586843;12733.745325093447;12830.87169128306;13085;13265;4044.5753195914594;4067.2678917795083;4130.607592278729;4214.36784482327;4215.957840421618;76905;77112.22887771107;77501;77620;77933.61033194911;11237.887621123848;11404.144689148347;11406.543738250542;11494.540218391337;11580.131490138274;18121.275148994155;18229.361972330906;18242.817571824377;18305.90847045756;18419.72370414445;7830.569318687413;7879.684812607432;7916.525008499509;7949.761507154794;7964.2433968772875;4377.78111094444;4415;4572.565606267399;4770.475247722748;4930.2851086592245;3388.423967925459;3489.581250249986;3496.9825150874085;3504.229069604683;3506.0708912138302;564.8051422259396;583.6818933681154;627.1658693937053;631.2425089892148;633.1262857257047;681.9590824550543;690.1132045321738;698.5389642835805;731.8436030440708;752.7741677496839;9829.21366290695;9908.157806586376;9996.600135994624;10149.949250253701;10232.130268927076;649.8472858878159;658.5949640970769;703.6130128429344;733.1019501111202;783.6747749683817;4823.3729615149805;4861.319415281863;4886.926696099561;4945.208766597327;4949.208126699711;72892;107294;125371;127829;129606;107778;108584;109977;112733.43633281944;114407.42796285966;57941.49555792193;73024.63487682528;75060.24939750532;75785.31610129263;90613.54693226624;8875.217493182316;9791.26565507604;10169.898301016896;10639.202059845387;10640.52117654692;44808.551914480435;45136;47268;52428;54127.458725412245;56357.71821140868;60037.699811500665;72959;73864.26135738574;73885.63057184679;12665.430055647334;12839.807402888777;12861.742765144832;12958.157719748186;13434.261115638734;13619.591412257649;13629.25039122818;13696.65758356049;14326.713465730836;14441.638959026108;13222;13721.588352349445;13833.930830345784;13843.377048033064;13970.37133329023;14260.429582816987;14387.568372948828;14571.85428145705;14792.260386980823;14814.48149314812;13949;14222.928885355921;14253.786193206903;14689.559313220621;14886.553403397915;11731.23746956445;11879.109066820196;12016.63428909269;12287.201331913397;12557.309347985949;11945.940270298594;11951.22317049389;12135.575254866037;12380.566680166152;12391.75216495683 20:39:39 INFO - PROCESS | 15317 | __end_tp_report 20:39:39 INFO - PROCESS | 15317 | __start_cc_report 20:39:39 INFO - PROCESS | 15317 | _x_x_mozilla_cycle_collect,1147 20:39:39 INFO - PROCESS | 15317 | __end_cc_report 20:39:39 INFO - PROCESS | 15317 | __startTimestamp1468640379298__endTimestamp 20:39:39 INFO - PROCESS | 15317 | 20:39:39 INFO - PROCESS | 15317 | ------- Summary: start ------- 20:39:39 INFO - PROCESS | 15317 | Number of tests: 6 20:39:39 INFO - PROCESS | 15317 | 20:39:39 INFO - PROCESS | 15317 | [#0] dojo.html Cycles:165 Average:5397.15 Median:5579.80 stddev:645.20 (11.6%) stddev-sans-first:619.59 20:39:39 INFO - PROCESS | 15317 | Values: 3017.7 3056.3 3059.1 3070.2 3106.4 5218.1 5280.8 5312.4 5412.0 5466.9 5357.1 5364.8 5460.1 5515.1 5579.8 4864.3 4882.8 4883.7 4889.6 5072.0 4977.6 4978.0 5016.6 5030.9 5038.4 4772.9 4803.8 4850.6 4857.6 4897.4 4824.7 4828.5 4847.5 4916.6 4952.2 4872.0 4887.8 4890.6 4899.1 4902.6 5248.7 5342.6 5405.4 5457.9 5482.5 5851.0 5868.4 5882.2 5885.6 5886.9 5643.3 5904.7 5926.6 5928.6 5935.0 5280.0 5362.1 5368.0 5425.0 5480.3 6049.2 6051.8 6057.6 6088.3 6104.1 5822.5 5844.8 5847.4 5849.2 5854.2 5571.6 5752.8 5796.2 5807.5 5916.1 5755.7 5810.6 5810.7 5813.9 5867.7 5731.6 5787.9 5802.3 5810.4 5816.2 4281.3 4387.7 4412.2 4421.2 4421.4 3930.7 3932.7 3944.3 3973.6 3986.1 5275.6 5545.4 5555.7 5556.6 5557.9 5760.7 5796.7 5810.6 5847.9 5848.9 5406.5 5457.6 5460.2 5463.5 5464.1 4806.7 4993.8 5002.9 5005.1 5050.6 6070.3 6116.9 6127.3 6127.5 6131.1 5885.4 5889.3 5891.8 5892.0 5923.9 5771.7 5964.8 6025.9 6101.9 6120.9 5727.1 5728.7 5733.1 5733.7 5775.1 5788.2 5794.4 5800.5 5828.9 5840.8 5485.8 5685.3 5690.7 5737.2 5739.9 5787.2 5787.8 5794.2 5796.5 5801.4 5701.5 5715.5 5719.0 5719.6 5720.2 5437.0 5462.8 5527.1 5638.5 5717.0 5545.8 5546.1 5546.2 5587.5 5591.3 20:39:39 INFO - PROCESS | 15317 | 20:39:39 INFO - PROCESS | 15317 | [#1] ext.html Cycles:180 Average:64845.18 Median:44836.05 stddev:128233.10 (286.0%) stddev-sans-first:128502.06 20:39:39 INFO - PROCESS | 15317 | Values: 583.3 613.2 632.8 643.7 644.7 31627.5 32317.5 34531.4 34646.1 45235.5 43060.4 47059.3 47069.3 47140.3 47152.0 9111.9 9243.4 9248.6 9253.5 9253.7 9076.8 9162.5 9175.4 9176.2 9182.0 9225.1 9253.5 9274.1 9280.5 9283.7 9124.5 9172.2 9202.7 9213.4 9227.0 9097.6 9134.8 9164.6 9168.2 9177.5 44038.3 44366.1 44400.3 44419.3 44430.8 3584.3 3832.5 3845.8 3852.9 4186.9 33743.8 34259.5 34379.1 35054.1 35200.9 4508.7 4569.2 4584.4 4646.6 4689.8 363114.0 364245.0 365075.0 365541.0 366056.0 52633.0 57652.4 60848.7 61884.7 61951.0 59334.4 63506.7 64536.4 64740.7 64803.4 17688.9 17830.3 17842.5 17956.0 17969.3 13166.5 13207.2 13208.5 13212.9 13220.9 15787.6 17889.5 18148.6 18391.6 18453.4 5997.6 6035.5 6051.7 6207.2 6269.7 1044.4 1057.2 1082.0 1095.6 1166.6 33816.5 34522.5 34962.1 35052.0 35077.3 1112.2 1115.0 1126.4 1146.0 1161.7 16987.1 17599.7 17644.5 17760.6 18205.7 529405.0 537837.0 538071.0 538967.0 539000.0 61240.0 62256.0 62269.4 62333.0 62340.0 1275.2 1397.2 1407.7 1408.6 1424.1 32417.7 35118.6 35218.3 35354.1 35550.1 55348.4 57914.1 60277.0 60511.0 64766.7 519911.0 528696.0 531952.0 533213.0 533765.0 38540.8 45078.3 45307.8 46357.0 46650.0 51452.0 51963.0 52451.2 52563.7 53344.0 43138.4 43875.3 44190.3 44393.3 45284.0 44123.0 44129.6 44204.6 44490.0 45035.8 43894.0 44710.3 44961.8 45057.1 45107.5 36343.8 37975.4 38566.0 38898.4 38915.6 29379.3 29407.2 29451.4 29776.7 29843.7 20:39:39 INFO - PROCESS | 15317 | 20:39:39 INFO - PROCESS | 15317 | [#2] jquery.html Cycles:180 Average:30894.17 Median:15408.56 stddev:78343.12 (508.4%) stddev-sans-first:78529.36 20:39:39 INFO - PROCESS | 15317 | Values: 363.8 364.6 364.7 385.3 387.9 18904.3 19908.9 20009.0 20758.3 20785.8 18514.3 19353.0 19677.7 19910.0 19976.1 5200.8 5313.1 5353.4 5354.3 5591.2 5824.6 5886.4 5913.2 5920.9 5922.7 5902.1 5902.4 5906.1 7182.5 7461.9 7240.6 7336.5 7431.3 7490.0 7503.8 7236.2 7278.1 7282.8 7287.6 7291.2 26316.1 27495.5 27681.7 27686.2 28022.7 2587.7 2794.4 2806.3 3035.6 3403.6 15337.7 16148.2 16667.3 16670.6 17032.7 1929.6 2055.4 2068.2 2168.1 2260.7 94871.5 97777.0 99421.0 100982.5 101298.5 17661.4 18058.0 18206.7 18245.5 18347.7 32116.0 32300.4 32499.8 32519.7 32527.7 9788.6 9998.2 10280.4 10375.1 10443.9 8696.1 8753.9 8767.6 8919.2 8926.8 8913.2 8959.6 9089.8 9093.8 9125.2 3250.9 3304.7 3444.7 3471.2 3540.9 919.5 961.1 1001.9 1029.9 1054.1 19999.8 20364.2 20449.1 20475.0 20595.9 990.6 998.6 1008.7 1015.1 1065.4 10510.4 10564.8 11025.6 11152.3 11162.0 463021.0 467247.0 470103.0 476595.0 481243.0 100994.5 103784.0 104722.5 105106.0 105122.0 1026.2 1060.6 1066.6 1072.6 1082.5 16974.7 18093.5 18275.0 18507.2 19024.0 41586.4 41770.6 41970.0 44033.0 46819.7 53434.2 57237.0 63258.7 64048.7 64530.7 6235.2 7775.7 8553.7 8730.2 8737.4 13026.4 13572.4 13796.1 13843.6 14007.1 12153.1 12200.7 12324.3 12377.5 12477.9 12086.9 12150.0 12260.9 12322.4 12431.8 12071.2 12194.8 12373.9 12398.7 12695.6 8476.9 8696.0 8709.9 8757.3 8946.6 8593.4 10901.1 11925.4 11948.2 12222.8 20:39:39 INFO - PROCESS | 15317 | 20:39:39 INFO - PROCESS | 15317 | [#3] mootools.html Cycles:180 Average:5933.39 Median:4788.01 stddev:3387.16 (70.7%) stddev-sans-first:3378.85 20:39:39 INFO - PROCESS | 15317 | Values: 1312.4 1474.3 1482.8 1632.0 1792.6 8510.5 8859.4 8872.0 9123.9 9128.3 8839.2 9036.5 9038.8 9053.3 9056.6 2037.7 2217.1 2281.5 2296.5 2320.3 2375.8 2384.7 2385.2 2386.9 2389.9 2296.2 2306.5 2332.7 2334.6 2336.1 1950.9 2222.5 2300.6 2300.8 2301.9 2300.8 2343.3 2366.5 2370.4 2376.1 8808.3 8867.6 8875.2 8878.6 8880.7 5355.6 5488.7 5494.2 5494.7 5495.8 3287.7 3441.0 3472.8 3478.1 3488.0 1144.1 1151.7 1163.4 1193.4 1204.0 12614.7 12805.2 12815.7 12857.5 12863.5 4724.3 4774.5 4801.5 4856.8 4908.4 10709.6 10741.1 10751.6 10758.4 10761.4 5399.8 5419.4 5456.6 5456.6 5465.9 4248.5 4771.3 4879.6 4889.6 4953.5 3906.3 3982.7 4053.9 4056.4 4057.2 1155.8 1218.3 1257.7 1261.5 1269.3 920.9 976.3 999.7 1003.5 1009.4 5974.2 5985.6 6063.9 6150.5 6157.6 507.4 668.1 950.5 1012.4 1028.0 4273.8 4306.0 4339.9 4340.3 4380.2 10648.0 10960.0 11031.3 11046.9 11052.3 10898.7 10926.5 10927.1 10933.0 10933.5 7987.0 8317.9 8350.1 8526.1 8560.0 5794.6 5802.8 5921.9 6161.0 6274.2 9830.9 9947.3 10111.9 10159.9 10167.4 10130.5 10333.7 10340.7 10378.3 10422.8 8116.3 8210.4 8236.6 8251.8 8276.3 8622.1 8956.6 8957.4 8974.8 8980.5 7801.1 7976.6 8011.1 8094.8 8171.9 7689.1 7917.7 7931.2 7993.9 8048.8 7308.7 7350.9 7369.7 7401.3 7401.7 5058.7 5222.5 5306.6 5373.8 5393.0 5349.9 5362.8 5491.3 5516.7 5663.8 20:39:39 INFO - PROCESS | 15317 | 20:39:39 INFO - PROCESS | 15317 | [#4] prototype.html Cycles:180 Average:4642.52 Median:5372.33 stddev:1379.00 (25.7%) stddev-sans-first:1343.88 20:39:39 INFO - PROCESS | 15317 | Values: 304.8 317.8 319.9 322.3 325.8 5247.4 5262.6 5299.2 5299.5 5364.8 4993.5 5133.6 5135.6 5138.7 5320.8 3991.8 3993.3 4079.5 4095.6 4106.9 4071.5 4076.7 4082.3 4085.0 4085.2 3893.6 4053.6 4077.0 4083.5 4092.8 4069.8 4069.8 4071.3 4079.6 4090.9 3546.6 3578.7 3589.4 3592.4 3595.7 4869.9 5195.0 5201.0 5203.8 5206.8 4625.8 4626.0 4629.3 4637.2 4668.4 5494.7 5527.4 5527.9 5564.5 5569.1 3847.4 3905.4 3917.7 3947.7 3950.9 5688.3 5718.4 5728.5 5729.8 5743.7 3970.2 3976.3 3978.6 3983.6 3990.3 4026.6 4044.6 4051.7 4057.6 4086.2 4531.8 4599.3 4615.7 4626.4 4685.0 5473.2 5489.9 5495.6 5516.5 5520.3 1300.2 1348.4 1382.2 1396.9 1397.2 931.6 951.0 955.5 957.5 960.2 5344.8 5379.8 5387.7 5395.2 5402.3 5465.8 5643.3 5643.9 5669.9 5673.0 5354.5 5382.5 5399.0 5417.2 5431.9 2962.0 2985.5 3007.1 3007.6 3021.5 5741.2 5824.7 5841.3 5853.2 5872.6 5730.8 5746.0 5775.6 5802.2 5832.7 5579.8 5794.8 5826.9 5828.4 5830.7 5580.6 5604.9 5625.2 5646.2 5660.9 5548.6 5566.2 5594.4 5696.7 5701.8 5566.7 5895.2 5897.7 5918.9 5924.2 3979.6 4011.7 4025.2 4026.6 4040.6 5782.3 5784.0 5788.7 5807.8 5821.7 5343.8 5587.6 5590.3 5610.1 5663.6 5465.5 5531.1 5533.8 5558.2 5587.2 5231.4 5299.7 5460.0 5470.2 5476.3 5531.4 5536.0 5537.9 5543.4 5617.3 5228.2 5442.8 5454.9 5503.7 5533.8 20:39:39 INFO - PROCESS | 15317 | 20:39:39 INFO - PROCESS | 15317 | [#5] yui.html Cycles:180 Average:20528.15 Median:4098.94 stddev:29894.99 (729.3%) stddev-sans-first:29941.97 20:39:39 INFO - PROCESS | 15317 | Values: 749.3 776.1 800.3 801.9 807.3 15552.2 16179.4 16710.2 16826.1 16833.3 14966.3 15094.1 15110.7 15113.1 15121.8 672.5 791.3 802.8 819.8 825.7 833.2 835.1 837.9 838.0 838.8 863.2 866.8 867.2 867.3 867.7 701.0 838.8 860.8 861.0 861.4 630.3 634.7 634.9 635.0 636.1 13572.5 13591.2 13601.9 13623.5 13632.2 13996.9 14279.6 14279.9 14336.3 14529.1 12604.6 12733.7 12830.9 13085.0 13265.0 4044.6 4067.3 4130.6 4214.4 4216.0 76905.0 77112.2 77501.0 77620.0 77933.6 11237.9 11404.1 11406.5 11494.5 11580.1 18121.3 18229.4 18242.8 18305.9 18419.7 7830.6 7879.7 7916.5 7949.8 7964.2 4377.8 4415.0 4572.6 4770.5 4930.3 3388.4 3489.6 3497.0 3504.2 3506.1 564.8 583.7 627.2 631.2 633.1 682.0 690.1 698.5 731.8 752.8 9829.2 9908.2 9996.6 10149.9 10232.1 649.8 658.6 703.6 733.1 783.7 4823.4 4861.3 4886.9 4945.2 4949.2 72892.0 107294.0 125371.0 127829.0 129606.0 107778.0 108584.0 109977.0 112733.4 114407.4 57941.5 73024.6 75060.2 75785.3 90613.5 8875.2 9791.3 10169.9 10639.2 10640.5 44808.6 45136.0 47268.0 52428.0 54127.5 56357.7 60037.7 72959.0 73864.3 73885.6 12665.4 12839.8 12861.7 12958.2 13434.3 13619.6 13629.3 13696.7 14326.7 14441.6 13222.0 13721.6 13833.9 13843.4 13970.4 14260.4 14387.6 14571.9 14792.3 14814.5 13949.0 14222.9 14253.8 14689.6 14886.6 11731.2 11879.1 12016.6 12287.2 12557.3 11945.9 11951.2 12135.6 12380.6 12391.8 20:39:39 INFO - PROCESS | 15317 | -------- Summary: end -------- 20:39:39 INFO - PROCESS | 15317 | 20:39:40 INFO - TEST-INFO | 15317: exit 0 20:39:40 INFO - TEST-OK | dromaeo_css | took 1113774ms 20:39:40 INFO - TEST-START | kraken 20:39:40 INFO - operating with platform_type : linux_ 20:39:40 INFO - Initialising browser for kraken test... 20:39:40 INFO - TEST-INFO | started process 15509 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZq4uv9/profile http://localhost:58145/getInfo.html) 20:39:44 INFO - PROCESS | 15509 | __metrics Screen width/height:1600/1200 20:39:44 INFO - PROCESS | 15509 | colorDepth:24 20:39:44 INFO - PROCESS | 15509 | Browser inner width/height: 1024/697 20:39:44 INFO - PROCESS | 15509 | __metrics 20:39:45 INFO - PROCESS | 15509 | 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" 20:39:45 INFO - PROCESS | 15509 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:39:45 INFO - PROCESS | 15509 | console.error: PushService: 20:39:45 INFO - PROCESS | 15509 | stateChangeProcessEnqueue: Error transitioning state 20:39:45 INFO - PROCESS | 15509 | UnknownError 20:39:46 INFO - TEST-INFO | 15509: exit 0 20:39:46 INFO - Browser initialized. 20:39:46 INFO - Running cycle 1/1 for kraken test... 20:39:46 INFO - TEST-INFO | started process 15589 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZq4uv9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:39:57 INFO - PROCESS | 15589 | RSS: Main: 157855744 20:39:57 INFO - PROCESS | 15589 | 20:40:00 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58145/tests/kraken/driver.html?testName=audio-beat-detection) 20:40:01 INFO - PROCESS | 15589 | RSS: Main: 216981504 20:40:01 INFO - PROCESS | 15589 | 20:40:03 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58145/tests/kraken/driver.html?testName=audio-dft) 20:40:04 INFO - PROCESS | 15589 | RSS: Main: 205090816 20:40:04 INFO - PROCESS | 15589 | 20:40:07 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58145/tests/kraken/driver.html?testName=audio-fft) 20:40:08 INFO - PROCESS | 15589 | RSS: Main: 270286848 20:40:08 INFO - PROCESS | 15589 | 20:40:10 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58145/tests/kraken/driver.html?testName=audio-oscillator) 20:40:10 INFO - PROCESS | 15589 | RSS: Main: 181030912 20:40:10 INFO - PROCESS | 15589 | 20:40:12 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58145/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:40:13 INFO - PROCESS | 15589 | RSS: Main: 184639488 20:40:13 INFO - PROCESS | 15589 | 20:40:16 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58145/tests/kraken/driver.html?testName=imaging-darkroom) 20:40:17 INFO - PROCESS | 15589 | RSS: Main: 219078656 20:40:17 INFO - PROCESS | 15589 | 20:40:20 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58145/tests/kraken/driver.html?testName=imaging-desaturate) 20:40:21 INFO - PROCESS | 15589 | RSS: Main: 221388800 20:40:21 INFO - PROCESS | 15589 | 20:40:23 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58145/tests/kraken/driver.html?testName=json-parse-financial) 20:40:24 INFO - PROCESS | 15589 | RSS: Main: 221700096 20:40:24 INFO - PROCESS | 15589 | 20:40:25 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58145/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:40:26 INFO - PROCESS | 15589 | RSS: Main: 208871424 20:40:26 INFO - PROCESS | 15589 | 20:40:28 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58145/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:40:28 INFO - PROCESS | 15589 | RSS: Main: 212754432 20:40:28 INFO - PROCESS | 15589 | 20:40:30 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58145/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:40:31 INFO - PROCESS | 15589 | RSS: Main: 209723392 20:40:31 INFO - PROCESS | 15589 | 20:40:34 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:40:34 INFO - PROCESS | 15589 | RSS: Main: 224137216 20:40:34 INFO - PROCESS | 15589 | 20:40:37 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:40:38 INFO - PROCESS | 15589 | RSS: Main: 224866304 20:40:38 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | Cycle 1(1): loaded http://localhost:58145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58145/tests/kraken/driver.html?testName=ai-astar) 20:40:39 INFO - PROCESS | 15589 | RSS: Main: 266493952 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | __start_tp_report 20:40:39 INFO - PROCESS | 15589 | _x_x_mozilla_page_load 20:40:39 INFO - PROCESS | 15589 | _x_x_mozilla_page_load_details 20:40:39 INFO - PROCESS | 15589 | |i|pagename|runs| 20:40:39 INFO - PROCESS | 15589 | |0;ai-astar;102;110;111;110;110;111;111;110;111;111 20:40:39 INFO - PROCESS | 15589 | |1;audio-beat-detection;146;116;120;179;123;120;143;121;153;117 20:40:39 INFO - PROCESS | 15589 | |2;audio-dft;161;161;162;161;161;161;164;162;161;163 20:40:39 INFO - PROCESS | 15589 | |3;audio-fft;74;82;76;109;75;75;75;74;101;74 20:40:39 INFO - PROCESS | 15589 | |4;audio-oscillator;84;84;111;83;83;83;125;84;84;126 20:40:39 INFO - PROCESS | 15589 | |5;imaging-gaussian-blur;116;117;117;116;117;116;117;117;117;117 20:40:39 INFO - PROCESS | 15589 | |6;imaging-darkroom;147;146;145;145;146;146;145;145;146;146 20:40:39 INFO - PROCESS | 15589 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;95 20:40:39 INFO - PROCESS | 15589 | |8;json-parse-financial;51;49;50;50;49;50;51;49;49;51 20:40:39 INFO - PROCESS | 15589 | |9;json-stringify-tinderbox;61;63;63;61;62;61;62;61;61;61 20:40:39 INFO - PROCESS | 15589 | |10;stanford-crypto-aes;75;74;74;75;75;74;73;74;74;78 20:40:39 INFO - PROCESS | 15589 | |11;stanford-crypto-ccm;118;122;117;118;119;127;117;118;167;134 20:40:39 INFO - PROCESS | 15589 | |12;stanford-crypto-pbkdf2;157;160;158;157;158;155;158;157;157;158 20:40:39 INFO - PROCESS | 15589 | |13;stanford-crypto-sha256-iterative;63;63;64;64;62;63;63;62;61;60 20:40:39 INFO - PROCESS | 15589 | __end_tp_report 20:40:39 INFO - PROCESS | 15589 | __start_cc_report 20:40:39 INFO - PROCESS | 15589 | _x_x_mozilla_cycle_collect,1657 20:40:39 INFO - PROCESS | 15589 | __end_cc_report 20:40:39 INFO - PROCESS | 15589 | __startTimestamp1468640439917__endTimestamp 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | ------- Summary: start ------- 20:40:39 INFO - PROCESS | 15589 | Number of tests: 14 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:2.75 (2.5%) stddev-sans-first:0.53 20:40:39 INFO - PROCESS | 15589 | Values: 102.0 110.0 111.0 110.0 110.0 111.0 111.0 110.0 111.0 111.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:133.00 stddev:20.83 (15.7%) stddev-sans-first:21.62 20:40:39 INFO - PROCESS | 15589 | Values: 146.0 116.0 120.0 179.0 123.0 120.0 143.0 121.0 153.0 117.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#2] audio-dft Cycles:10 Average:161.70 Median:161.50 stddev:1.06 (0.7%) stddev-sans-first:1.09 20:40:39 INFO - PROCESS | 15589 | Values: 161.0 161.0 162.0 161.0 161.0 161.0 164.0 162.0 161.0 163.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.00 stddev:12.75 (17.0%) stddev-sans-first:13.23 20:40:39 INFO - PROCESS | 15589 | Values: 74.0 82.0 76.0 109.0 75.0 75.0 75.0 74.0 101.0 74.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:83.50 stddev:18.35 (22.0%) stddev-sans-first:19.06 20:40:39 INFO - PROCESS | 15589 | Values: 84.0 84.0 111.0 83.0 83.0 83.0 125.0 84.0 84.0 126.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 20:40:39 INFO - PROCESS | 15589 | Values: 116.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 20:40:39 INFO - PROCESS | 15589 | Values: 147.0 146.0 145.0 145.0 146.0 146.0 145.0 145.0 146.0 146.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 20:40:39 INFO - PROCESS | 15589 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:0.88 (1.8%) stddev-sans-first:0.83 20:40:39 INFO - PROCESS | 15589 | Values: 51.0 49.0 50.0 50.0 49.0 50.0 51.0 49.0 49.0 51.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:61.50 stddev:0.84 (1.4%) stddev-sans-first:0.87 20:40:39 INFO - PROCESS | 15589 | Values: 61.0 63.0 63.0 61.0 62.0 61.0 62.0 61.0 61.0 61.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.50 stddev:1.35 (1.8%) stddev-sans-first:1.42 20:40:39 INFO - PROCESS | 15589 | Values: 75.0 74.0 74.0 75.0 75.0 74.0 73.0 74.0 74.0 78.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:120.50 stddev:15.51 (12.9%) stddev-sans-first:16.19 20:40:39 INFO - PROCESS | 15589 | Values: 118.0 122.0 117.0 118.0 119.0 127.0 117.0 118.0 167.0 134.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.50 Median:158.00 stddev:1.27 (0.8%) stddev-sans-first:1.33 20:40:39 INFO - PROCESS | 15589 | Values: 157.0 160.0 158.0 157.0 158.0 155.0 158.0 157.0 157.0 158.0 20:40:39 INFO - PROCESS | 15589 | 20:40:39 INFO - PROCESS | 15589 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:63.00 stddev:1.27 (2.0%) stddev-sans-first:1.33 20:40:39 INFO - PROCESS | 15589 | Values: 63.0 63.0 64.0 64.0 62.0 63.0 63.0 62.0 61.0 60.0 20:40:39 INFO - PROCESS | 15589 | -------- Summary: end -------- 20:40:39 INFO - PROCESS | 15589 | 20:40:40 INFO - TEST-INFO | 15589: exit 0 20:40:40 INFO - TEST-OK | kraken | took 60923ms 20:40:40 INFO - SUITE-END | took 1174s 20:40:41 INFO - Completed test suite (00:19:34) 20:40:41 INFO - javascript benchmark 20:40:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3017.6831432699564, 3056.2817737831606, 3059.0669845697053, 3070.1864006038404, 3106.378724255148, 5218.086834803904, 5280.815171468999, 5312.389075256342, 5411.972940135299, 5466.863328416786, 5357.062514060043, 5364.785408583662, 5460.071787796074, 5515.145152501359, 5579.804706835262, 4864.294677271802, 4882.829100981467, 4883.731394773286, 4889.608831293488, 5071.974640126794, 4977.601791856661, 4977.975110124445, 5016.648834581581, 5030.949690503103, 5038.370203724535, 4772.880677983044, 4803.807847686088, 4850.5877000455, 4857.562819346241, 4897.436794768611, 4824.686395384324, 4828.46886842447, 4847.466778654345, 4916.557509824097, 4952.182889823175, 4872.049950259665, 4887.780049897728, 4890.559849613516, 4899.118158731427, 4902.607791376681, 5248.71132087735, 5342.626016178869, 5405.37838148614, 5457.945420545823, 5482.479164479368, 5851.034579294454, 5868.383819698955, 5882.205902203191, 5885.646861188342, 5886.941130588682, 5643.266375371176, 5904.704764761745, 5926.644401335933, 5928.584999050068, 5934.97032514839, 5280.0, 5362.061639213122, 5368.006918720011, 5424.996375670474, 5480.342358916955, 6049.1833602463985, 6051.800064687898, 6057.561756792314, 6088.269407671108, 6104.145419641254, 5822.534197264209, 5844.824655260349, 5847.415258474203, 5849.181114643953, 5854.151148083589, 5571.582131340166, 5752.827415177551, 5796.188533605298, 5807.477327040587, 5916.1421593868645, 5755.712214389264, 5810.593258471867, 5810.6513609183585, 5813.88372232553, 5867.735951882176, 5731.627444216113, 5787.913181302284, 5802.274715660543, 5810.4189581041555, 5816.185733997244, 4281.336392859112, 4387.670924680661, 4412.161689279027, 4421.226285400042, 4421.447319085114, 3930.7051971102283, 3932.7050471214775, 3944.2703099926607, 3973.5827738087087, 3986.123052928351, 5275.60432967529, 5545.445455454423, 5555.666660000413, 5556.638818476786, 5557.916631250535, 5760.74076666551, 5796.652200867961, 5810.622309549865, 5847.883042339129, 5848.853778655568, 5406.540444062299, 5457.590680698963, 5460.235567020621, 5463.453654634504, 5464.12573988168, 4806.711597304173, 4993.80024799004, 5002.874928126826, 5005.099082165172, 5050.570701490415, 6070.3019152797115, 6116.944513560587, 6127.250613123855, 6127.464420129234, 6131.070842498441, 5885.352611212848, 5889.263842019747, 5891.823245302648, 5891.970540147272, 5923.851903702442, 5771.7402716877, 5964.821055368346, 6025.879482410415, 6101.877962440815, 6120.938790612215, 5727.055035919193, 5728.684922329312, 5733.111367738007, 5733.684647344436, 5775.133729940542, 5788.247527821524, 5794.3626201117, 5800.477956983892, 5828.94171058284, 5840.8247752567495, 5485.753141108739, 5685.260916081049, 5690.687012214368, 5737.196792448977, 5739.8565035874435, 5787.160861674949, 5787.826365209051, 5794.159846822272, 5796.536277097906, 5801.448862358069, 5701.543876489788, 5715.457031581993, 5719.0277652798295, 5719.599628025998, 5720.19917211599, 5437.048516509516, 5462.754176062007, 5527.115661494142, 5638.464345887134, 5717.0, 5545.7781688732, 5546.057170280981, 5546.195801608664, 5587.525060369914, 5591.329040515002], "value": 5351.352343466856, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.2650859916504, 613.2150846915948, 632.7627139822566, 643.6942452335144, 644.6776611694152, 31627.525587116183, 32317.51523727143, 34531.410144239155, 34646.133846653815, 45235.54764452355, 43060.354094688606, 47059.294110588366, 47069.29396059062, 47140.292895606595, 47152.0, 9111.908880911189, 9243.445393276425, 9248.583813728399, 9253.491057991807, 9253.676121335755, 9076.818463630723, 9162.496062716547, 9175.357724959256, 9176.220021298197, 9182.03588623195, 9225.123613256763, 9253.491057991807, 9274.07259274074, 9280.535973201348, 9283.72148835536, 9124.45253284805, 9172.174504294611, 9202.723918282396, 9213.355065145442, 9227.0, 9097.63609455621, 9134.77163070922, 9164.633414663405, 9168.220701240336, 9177.495237761921, 44038.33942490865, 44366.11267774626, 44400.3339949901, 44419.333709994375, 44430.77784611056, 3584.336897673939, 3832.482614846988, 3845.7692538447504, 3852.8651497197743, 4186.937195942063, 33743.83128084368, 34259.486107708406, 34379.14052148716, 35054.12364690903, 35200.94397168089, 4508.661850361236, 4569.246074397721, 4584.426946631671, 4646.570884267327, 4689.788959496796, 363114.0, 364245.0, 365075.0, 365541.0, 366056.0, 52633.0, 57652.42347576554, 60848.69575652182, 61884.69057654773, 61951.0, 59334.406655932886, 63506.68246658737, 64536.35463645397, 64740.676296618214, 64803.351966479735, 17688.9115554424, 17830.286788528574, 17842.46472605824, 17956.0, 17969.281228750966, 13166.539171128965, 13207.207567545978, 13208.537701180605, 13212.933935330455, 13220.867791321964, 15787.605309867116, 17889.463316100537, 18148.63702725938, 18391.632167356845, 18453.446396608124, 5997.640141591519, 6035.486983606354, 6051.727672254759, 6207.162033125561, 6269.65516896566, 1044.3525014491065, 1057.1859668055554, 1082.0045558086579, 1095.5727266366123, 1166.6383421139474, 33816.478258478426, 34522.48216276758, 34962.125946851535, 35052.0, 35077.29845403129, 1112.2047735868894, 1114.9777004459866, 1126.3930524156076, 1146.0487795130023, 1161.7095726068483, 16987.13141554428, 17599.73600395995, 17644.470665880042, 17760.55598610045, 18205.7269140963, 529405.0, 537837.0, 538071.0, 538967.0, 539000.0, 61240.0, 62256.0, 62269.37730622636, 62333.0, 62340.0, 1275.2029981261712, 1397.175666356834, 1407.6691977385306, 1408.64783804049, 1424.1312799192276, 32417.675823241465, 35118.64881351256, 35218.295634087684, 35354.11614709653, 35550.12590690348, 55348.44651553433, 57914.10429478425, 60277.0, 60511.0, 64766.67616661887, 519911.0, 528696.0, 531952.0, 533213.0, 533765.0, 38540.80729596334, 45078.32382514199, 45307.7734611338, 46357.0, 46650.0, 51452.0, 51963.0, 52451.213231802314, 52563.737181313845, 53344.0, 43138.352924706785, 43875.341869871336, 44190.33714494349, 44393.33409998917, 45283.99792440048, 44123.0, 44129.55870441254, 44204.557954420045, 44490.0, 45035.774821125684, 43894.0, 44710.3293450592, 44961.77519112384, 45057.098858022, 45107.54892451034, 36343.818280908425, 37975.430368543944, 38566.0, 38898.4165237516, 38915.63992335487, 29379.265518362212, 29407.235565866118, 29451.410971780017, 29776.702232977394, 29843.701562984093], "value": 21206.46479420085, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.7653713354886, 364.5625249700362, 364.7082334132694, 385.331449934364, 387.9185371072075, 18904.338348157817, 19908.900455497722, 20009.0, 20758.27346042889, 20785.792142078575, 18514.25942962283, 19353.0, 19677.70483442746, 19910.0, 19976.101075451603, 5200.817971371002, 5313.123334649778, 5353.3843607985, 5354.250404943292, 5591.189277554752, 5824.563157763185, 5886.440788125121, 5913.231279933602, 5920.940790592082, 5922.703864806743, 5902.144189092579, 5902.409759024084, 5906.05503119501, 7182.46131540134, 7461.925380746177, 7240.601766902818, 7336.486445948785, 7431.29402706742, 7489.9625501872415, 7503.812404689872, 7236.204017558064, 7278.09023872016, 7282.81792955175, 7287.6356182190675, 7291.234420385889, 26316.078937237104, 27495.450090998267, 27681.723182768117, 27686.169414917586, 28022.719772802215, 2587.728288529715, 2794.3992041710926, 2806.3264816443907, 3035.5598438226552, 3403.6085850127056, 15337.693246135126, 16148.192590370434, 16667.33330666784, 16670.58323541909, 17032.744508832377, 1929.6237233739287, 2055.4450298419342, 2068.2450905419564, 2168.1002384010617, 2260.7400148982915, 94871.52564237134, 97777.0, 99421.0, 100982.49508752483, 101298.493507532, 17661.38185163513, 18058.0, 18206.726899096524, 18245.452636420927, 18347.724784128248, 32116.0, 32300.35399292001, 32499.837500812344, 32519.674803251666, 32527.674723252938, 9788.608455661837, 9998.150157236716, 10280.383177009404, 10375.118114960162, 10443.947780261202, 8696.130386961253, 8753.868691969625, 8767.561621918878, 8919.152680495343, 8926.794882690883, 8913.153250441197, 8959.552022398853, 9089.81820363589, 9093.81748380384, 9125.17873391398, 3250.8798642138727, 3304.686054824788, 3444.741644376682, 3471.2363280078343, 3540.8760693376125, 919.4621146629163, 961.0869673809908, 1001.8547310639916, 1029.912457441126, 1054.1092776603757, 19999.800937584143, 20364.18543258253, 20449.079791409422, 20475.0, 20595.8970205148, 990.6037584966072, 998.6305067125065, 1008.6520150548049, 1015.081351377005, 1065.3699839245967, 10510.369377837354, 10564.844192916285, 11025.558977640965, 11152.275102118336, 11162.0, 463021.0, 467247.0, 470103.0, 476595.0, 481243.0, 100994.49502752586, 103784.0, 104722.47638761757, 105106.0, 105122.0, 1026.1841835740604, 1060.6022741471947, 1066.648006157966, 1072.5763323487022, 1082.5128692088592, 16974.660506789547, 18093.457196284133, 18275.0, 18507.167177477317, 19024.0, 41586.37620435756, 41770.58229417667, 41970.0, 44033.0, 46819.72261941768, 53434.1984870235, 57237.0, 63258.68370658117, 64048.67975660092, 64530.677346614844, 6235.189425374672, 7775.650095745818, 8553.700620478254, 8730.17063378978, 8737.43206691563, 13026.413811378321, 13572.389242484309, 13796.103253288504, 13843.584692459242, 14007.08953917992, 12153.088518361335, 12200.694982625506, 12324.32216228116, 12377.504899804268, 12477.875221247672, 12086.879131208576, 12150.0, 12260.938695306466, 12322.445489953154, 12431.813522797345, 12071.155019148575, 12194.75610487803, 12373.93813030965, 12398.690032749253, 12695.619131426072, 8476.872846907178, 8696.0, 8709.869351959598, 8757.343199260209, 8946.597403116744, 8593.398462107592, 10901.067743561955, 11925.403729813648, 11948.163628545919, 12222.816657750318], "value": 10586.176969196493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1312.389738771471, 1474.3439169569542, 1482.8294746104198, 1631.9592010199744, 1792.6235490546985, 8510.531920744357, 8859.424137431062, 8872.024077351494, 9123.908760912389, 9128.269738420928, 8839.204471597555, 9036.548172591378, 9038.774030649238, 9053.275737940965, 9056.637734490629, 2037.673972164454, 2217.135317226283, 2281.4638559938485, 2296.540691861626, 2320.2575175943703, 2375.762423757619, 2384.7496012918655, 2385.224801939368, 2386.9283921482297, 2389.8924548395275, 2296.242240060776, 2306.538692261546, 2332.650102484624, 2334.614788559894, 2336.0655737704883, 1950.8634395592314, 2222.511047569532, 2300.631898896177, 2300.7584203658544, 2301.919432819843, 2300.7929286364306, 2343.320437073247, 2366.479374537599, 2370.3718514593647, 2376.0614557249964, 8808.339374546935, 8867.600957956907, 8875.156860098281, 8878.644854205888, 8880.689175878877, 5355.571554275649, 5488.670679759187, 5494.1758736189495, 5494.725263736837, 5495.752691128909, 3287.6876696713775, 3440.9655903440894, 3472.75690701651, 3478.0609235506527, 3487.9825600872086, 1144.1190283481756, 1151.666016855111, 1163.3601519164426, 1193.3555879824885, 1203.9638810835688, 12614.68463288416, 12805.167664101804, 12815.679608009781, 12857.549985750547, 12863.485460581565, 4724.339216871655, 4774.522547745198, 4801.4958429365115, 4856.757162141878, 4908.3619129513645, 10709.62516311941, 10741.140708743282, 10751.569937202581, 10758.408287544104, 10761.354318740743, 5399.811006614749, 5419.376771671308, 5456.61803673739, 5456.645318054314, 5465.863353416117, 4248.532661407243, 4771.308160316735, 4879.634027447955, 4889.584385327287, 4953.529414705597, 3906.277338692351, 3982.7012974027066, 4053.918921621551, 4056.411820286042, 4057.2494088593703, 1155.820847768597, 1218.250775772911, 1257.6792917806083, 1261.5332327039055, 1269.289198049077, 920.9447433154032, 976.2580438866513, 999.695092996643, 1003.4581326083833, 1009.3842755918884, 5974.2233509643465, 5985.6408615483215, 6063.939360606426, 6150.477209437158, 6157.630542167484, 507.3809951858726, 668.1180841289548, 950.4534892436739, 1012.417859730643, 1027.984580231297, 4273.807678654468, 4306.0, 4339.869803905888, 4340.283853164256, 4380.233459144701, 10648.0, 10960.0, 11031.338119712682, 11046.889531104585, 11052.336859788438, 10898.673039808818, 10926.453677316102, 10927.071198948179, 10933.0, 10933.45332733376, 7987.041555013436, 8317.875231871527, 8350.08149103598, 8526.062133165222, 8560.0, 5794.565407594363, 5802.82591522255, 5921.940780592311, 6161.0, 6274.152989346562, 9830.852537212091, 9947.303688741718, 10111.887692353983, 10159.898401015895, 10167.44079075658, 10130.544125514221, 10333.741656458347, 10340.689779306633, 10378.273520853469, 10422.791544168922, 8116.2695357418115, 8210.425270231026, 8236.588170591567, 8251.793705157419, 8276.29651479631, 8622.094680058755, 8956.641734330553, 8957.372983891066, 8974.775630609025, 8980.506072166094, 7801.141874393698, 7976.6410511525955, 8011.078725946358, 8094.83810323802, 8171.918280817354, 7689.115751688628, 7917.722879699183, 7931.246531579491, 7993.880091798512, 8048.83902321962, 7308.671109800178, 7350.852982940204, 7369.668364923484, 7401.259874012556, 7401.666924988496, 5058.671186372874, 5222.529972302511, 5306.602004849575, 5373.7850485980125, 5393.029254734185, 5349.919751203657, 5362.785488580413, 5491.28613280271, 5516.668999860021, 5663.773449061991], "value": 4711.995533873347, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.7881722203068, 317.807726325573, 319.94400979828526, 322.32955452658473, 325.82731152489174, 5247.449017853128, 5262.605304602151, 5299.152135658295, 5299.496547827955, 5364.846557990031, 4993.525615066563, 5133.588263227605, 5135.589152867771, 5138.7173705446185, 5320.813771517997, 3991.76049437034, 3993.3011722948513, 4079.5109784928586, 4095.6313931746135, 4106.8767936961785, 4071.491063617048, 4076.714629975903, 4082.306007978636, 4084.97957510212, 4085.1829634073306, 3893.610638936112, 4053.6351728344443, 4077.0, 4083.4691490106393, 4092.815823287944, 4069.755814651101, 4069.816858241357, 4071.3485842265245, 4079.5512493625824, 4090.877273681794, 3546.627604101558, 3578.6600272974024, 3589.4206549118303, 3592.3892938200306, 3595.6875740354844, 4869.9269510957365, 5195.0, 5200.97399513, 5203.791848326063, 5206.76569554371, 4625.8149674013, 4626.0, 4629.328747331618, 4637.211674015425, 4668.439787225555, 5494.725263736797, 5527.39198688144, 5527.917081243784, 5564.527015203713, 5569.136783798518, 3847.4421208924828, 3905.4337121117564, 3917.7257591968573, 3947.723659343847, 3950.940735888964, 5688.260526131618, 5718.370979192286, 5728.541716662657, 5729.799457019026, 5743.655380677173, 3970.225805967866, 3976.3041467743606, 3978.602139785998, 3983.5817239190046, 3990.2817492850986, 4026.5570787213383, 4044.6359827615656, 4051.6961227908023, 4057.6145266199665, 4086.1827634473225, 4531.755219352484, 4599.2871104978785, 4615.746133962597, 4626.421697287839, 4684.976575117103, 5473.17902314656, 5489.917651235235, 5495.560355171577, 5516.475934786269, 5520.337559492887, 1300.174389262825, 1348.3864841497095, 1382.2397681275258, 1396.8952328575397, 1397.2385050147693, 931.5761328595473, 950.95720692569, 955.507913424587, 957.4925289596525, 960.1550635440767, 5344.812931547377, 5379.811706590251, 5387.676739395649, 5395.244665746799, 5402.324709411324, 5465.836024919259, 5643.322801263875, 5643.858903527444, 5669.914951275734, 5672.971635141798, 5354.491323324278, 5382.515573598395, 5399.0, 5417.241586177938, 5431.891362172734, 2962.0, 2985.477541430241, 3007.0678089792236, 3007.593974813416, 3021.531662592302, 5741.19623252745, 5824.708764561839, 5841.276823337126, 5853.239078919798, 5872.647641141545, 5730.828075157752, 5745.9712701437065, 5775.5668324875805, 5802.158686990447, 5832.679202643895, 5579.804706835242, 5794.768209271582, 5826.912596311059, 5828.358880523054, 5830.679312637845, 5580.609357345109, 5604.907043126715, 5625.184348269396, 5646.237757902795, 5660.915086273627, 5548.611597188319, 5566.248556444828, 5594.4125866785025, 5696.686682232351, 5701.802621449542, 5566.721663916869, 5895.233619629421, 5897.697289517883, 5918.934591773522, 5924.200232968667, 3979.602039796113, 4011.7392369495888, 4025.174839158024, 4026.5772093930887, 4040.595940405936, 5782.277215348082, 5783.971080144741, 5788.739506722123, 5807.82576522705, 5821.719221771204, 5343.78624855017, 5587.55299576041, 5590.301212348457, 5610.13042978339, 5663.631863928831, 5465.508104270635, 5531.1426728857095, 5533.750981205936, 5558.194061860927, 5587.161925711176, 5231.372235331784, 5299.708516031656, 5460.0, 5470.206820011155, 5476.2607048048, 5531.446855314436, 5535.972320138373, 5537.944620553743, 5543.390227074938, 5617.325920889356, 5228.189630607262, 5442.782288708408, 5454.863628409322, 5503.734141147478, 5533.833984980457], "value": 4199.219100309041, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.306891125709, 776.1346099099502, 800.31134306613, 801.9037715474142, 807.3143672240273, 15552.222388880553, 16179.35282588695, 16710.24803883825, 16826.074565898885, 16833.326666933324, 14966.32651530681, 15094.094354338748, 15110.697786044273, 15113.093214407143, 15121.773173402407, 672.4754691340762, 791.268077028749, 802.787261375733, 819.8032472206658, 825.7316372179035, 833.2209384225738, 835.0688981785296, 837.949723016621, 838.0488145954325, 838.7861095420659, 863.1756672377895, 866.8179682266732, 867.2238346679718, 867.3278209387712, 867.657275376226, 700.9684564194598, 838.828040251747, 860.7503823891064, 861.0399404663817, 861.4357595774777, 630.3098107572201, 634.6953462338104, 634.9174607301068, 635.0093853588417, 636.1062706896818, 13572.45710171592, 13591.184528928197, 13601.931990340085, 13623.523176688868, 13632.182069075887, 13996.93001534996, 14279.643008924757, 14279.857201427956, 14336.28318584077, 14529.12825230479, 12604.580030586843, 12733.745325093447, 12830.87169128306, 13085.0, 13265.0, 4044.5753195914594, 4067.2678917795083, 4130.607592278729, 4214.36784482327, 4215.957840421618, 76905.0, 77112.22887771107, 77501.0, 77620.0, 77933.61033194911, 11237.887621123848, 11404.144689148347, 11406.543738250542, 11494.540218391337, 11580.131490138274, 18121.275148994155, 18229.361972330906, 18242.817571824377, 18305.90847045756, 18419.72370414445, 7830.569318687413, 7879.684812607432, 7916.525008499509, 7949.761507154794, 7964.2433968772875, 4377.78111094444, 4415.0, 4572.565606267399, 4770.475247722748, 4930.2851086592245, 3388.423967925459, 3489.581250249986, 3496.9825150874085, 3504.229069604683, 3506.0708912138302, 564.8051422259396, 583.6818933681154, 627.1658693937053, 631.2425089892148, 633.1262857257047, 681.9590824550543, 690.1132045321738, 698.5389642835805, 731.8436030440708, 752.7741677496839, 9829.21366290695, 9908.157806586376, 9996.600135994624, 10149.949250253701, 10232.130268927076, 649.8472858878159, 658.5949640970769, 703.6130128429344, 733.1019501111202, 783.6747749683817, 4823.3729615149805, 4861.319415281863, 4886.926696099561, 4945.208766597327, 4949.208126699711, 72892.0, 107294.0, 125371.0, 127829.0, 129606.0, 107778.0, 108584.0, 109977.0, 112733.43633281944, 114407.42796285966, 57941.49555792193, 73024.63487682528, 75060.24939750532, 75785.31610129263, 90613.54693226624, 8875.217493182316, 9791.26565507604, 10169.898301016896, 10639.202059845387, 10640.52117654692, 44808.551914480435, 45136.0, 47268.0, 52428.0, 54127.458725412245, 56357.71821140868, 60037.699811500665, 72959.0, 73864.26135738574, 73885.63057184679, 12665.430055647334, 12839.807402888777, 12861.742765144832, 12958.157719748186, 13434.261115638734, 13619.591412257649, 13629.25039122818, 13696.65758356049, 14326.713465730836, 14441.638959026108, 13222.0, 13721.588352349445, 13833.930830345784, 13843.377048033064, 13970.37133329023, 14260.429582816987, 14387.568372948828, 14571.85428145705, 14792.260386980823, 14814.48149314812, 13949.0, 14222.928885355921, 14253.786193206903, 14689.559313220621, 14886.553403397915, 11731.23746956445, 11879.109066820196, 12016.63428909269, 12287.201331913397, 12557.309347985949, 11945.940270298594, 11951.22317049389, 12135.575254866037, 12380.566680166152, 12391.75216495683], "value": 7565.070015775571, "unit": "score"}], "name": "dromaeo_css", "value": 7513.07441959347, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 111.0, 110.0, 110.0, 111.0, 111.0, 110.0, 111.0, 111.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 120.0, 179.0, 123.0, 120.0, 143.0, 121.0, 153.0, 117.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 161.0, 161.0, 164.0, 162.0, 161.0, 163.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 76.0, 109.0, 75.0, 75.0, 75.0, 74.0, 101.0, 74.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 111.0, 83.0, 83.0, 83.0, 125.0, 84.0, 84.0, 126.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 50.0, 49.0, 50.0, 51.0, 49.0, 49.0, 51.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 63.0, 61.0, 62.0, 61.0, 62.0, 61.0, 61.0, 61.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 75.0, 75.0, 74.0, 73.0, 74.0, 74.0, 78.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 122.0, 117.0, 118.0, 119.0, 127.0, 117.0, 118.0, 167.0, 134.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 160.0, 158.0, 157.0, 158.0, 155.0, 158.0, 157.0, 157.0, 158.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 64.0, 62.0, 63.0, 63.0, 62.0, 61.0, 60.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1470.4999999999998, "alertThreshold": 2.0}]} 20:40:41 INFO - Return code: 0 20:40:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:40:41 INFO - # TBPL SUCCESS # 20:40:41 INFO - Running post-action listener: _resource_record_post_action 20:40:41 INFO - ##### 20:40:41 INFO - ##### Finished run-tests step (success) 20:40:41 INFO - ##### 20:40:41 INFO - Running post-run listener: _resource_record_post_run 20:40:41 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 12288; Write bytes: 553607168; Read time: 148; Write time: 398804 20:40:41 INFO - TinderboxPrint: CPU usage
13.0% 20:40:41 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 148 20:40:41 INFO - TinderboxPrint: I/O write bytes / time
553,607,168 / 398,804 20:40:41 INFO - TinderboxPrint: CPU idle
8,276.1 (87.0%) 20:40:41 INFO - TinderboxPrint: CPU user
1,194.5 (12.6%) 20:40:41 INFO - TinderboxPrint: Swap in / out
0 / 0 20:40:41 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 256548864; Read time: 0; Write time: 190644 20:40:41 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 296771584; Read time: 136; Write time: 208140 20:40:41 INFO - Running post-run listener: _upload_blobber_files 20:40:41 INFO - Blob upload gear active. 20:40:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:40:41 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 20:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:40:42 INFO - (blobuploader) - INFO - Open directory for files ... 20:40:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:40:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:40:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:40:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:40:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:40:51 INFO - (blobuploader) - INFO - Done attempting. 20:40:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:40:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:40:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:40:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:40:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:40:52 INFO - (blobuploader) - INFO - Done attempting. 20:40:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:40:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:40:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:40:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:40:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:40:53 INFO - (blobuploader) - INFO - Done attempting. 20:40:53 INFO - (blobuploader) - INFO - Iteration through files over. 20:40:53 INFO - Return code: 0 20:40:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:40:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:40:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b84b26b98666dcb0848bddb481ec049c71624001acebc08aea0c54fe27b4d7c07fbb7c1ddc6e0f7d294e1f3fabb9ed40b34f995f4f6934e494acee6a0a983959", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/94f55bdb284e3351ae90045b97dee43a393299103cd49e40e93f7dd5339d6a7e27f960a11344b2ea9c0a9da34bcbb1120695c11a38fc1260e7e96250227e7e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2758148d8d64c9e7137a590730ea41f707913be1ed8543f01dcc54ef513e40308e0cbe8dfed032ccdae3f5f1a449c5b7de535d1fc7935043345903dc01cf34be"} 20:40:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:40:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:40:53 INFO - Contents: 20:40:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b84b26b98666dcb0848bddb481ec049c71624001acebc08aea0c54fe27b4d7c07fbb7c1ddc6e0f7d294e1f3fabb9ed40b34f995f4f6934e494acee6a0a983959", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/94f55bdb284e3351ae90045b97dee43a393299103cd49e40e93f7dd5339d6a7e27f960a11344b2ea9c0a9da34bcbb1120695c11a38fc1260e7e96250227e7e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2758148d8d64c9e7137a590730ea41f707913be1ed8543f01dcc54ef513e40308e0cbe8dfed032ccdae3f5f1a449c5b7de535d1fc7935043345903dc01cf34be"} 20:40:53 INFO - Running post-run listener: copy_logs_to_upload_dir 20:40:53 INFO - Copying logs to upload dir... 20:40:53 INFO - mkdir: /builds/slave/test/build/upload/logs 20:40:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.982778 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-07-15 20:40:53.361516) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 20:40:53.364599) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b84b26b98666dcb0848bddb481ec049c71624001acebc08aea0c54fe27b4d7c07fbb7c1ddc6e0f7d294e1f3fabb9ed40b34f995f4f6934e494acee6a0a983959", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/94f55bdb284e3351ae90045b97dee43a393299103cd49e40e93f7dd5339d6a7e27f960a11344b2ea9c0a9da34bcbb1120695c11a38fc1260e7e96250227e7e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2758148d8d64c9e7137a590730ea41f707913be1ed8543f01dcc54ef513e40308e0cbe8dfed032ccdae3f5f1a449c5b7de535d1fc7935043345903dc01cf34be"} build_url:https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011224 build_url: 'https://queue.taskcluster.net/v1/task/QvQKKhWKQVWSDWdei75Ffw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b84b26b98666dcb0848bddb481ec049c71624001acebc08aea0c54fe27b4d7c07fbb7c1ddc6e0f7d294e1f3fabb9ed40b34f995f4f6934e494acee6a0a983959", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/94f55bdb284e3351ae90045b97dee43a393299103cd49e40e93f7dd5339d6a7e27f960a11344b2ea9c0a9da34bcbb1120695c11a38fc1260e7e96250227e7e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2758148d8d64c9e7137a590730ea41f707913be1ed8543f01dcc54ef513e40308e0cbe8dfed032ccdae3f5f1a449c5b7de535d1fc7935043345903dc01cf34be"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 20:40:53.389627) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 20:40:53.389985) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1468634249.902508-357451894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 20:40:53.472990) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 20:40:53.473302) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 20:40:53.473582) ========= ========= Total master_lag: 0.17 =========