builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1469114160.14 results: success (0) buildid: 20160721053033 builduid: 5e064fda2b6645a79cfd45b6d8d7995e revision: d8a5ea157504793ff2bb2b5161103154a31f08e3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:00.135511) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:00.135973) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:00.136457) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:00.151946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:00.152239) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 08:16: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2016-07-21 08:16:00 (46.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114202 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:00.290725) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:00.291035) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014783 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:00.347537) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:00.348041) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d8a5ea157504793ff2bb2b5161103154a31f08e3 --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 d8a5ea157504793ff2bb2b5161103154a31f08e3 --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=9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 08:16:00,707 truncating revision to first 12 chars 2016-07-21 08:16:00,707 Setting DEBUG logging. 2016-07-21 08:16:00,707 attempt 1/10 2016-07-21 08:16:00,707 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d8a5ea157504?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 08:16:01,031 unpacking tar archive at: fx-team-d8a5ea157504/testing/mozharness/ program finished with exit code 0 elapsedTime=0.651706 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:01.014315) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:01.014660) ========= script_repo_revision: d8a5ea157504793ff2bb2b5161103154a31f08e3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:01.015128) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:01.015471) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 08:16:01.025121) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-21 08:16:01.025462) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:16:01 INFO - MultiFileLogger online at 20160721 08:16:01 in /builds/slave/test 08:16: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 08:16:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:16:01 INFO - {'append_to_log': False, 08:16:01 INFO - 'base_work_dir': '/builds/slave/test', 08:16:01 INFO - 'blob_upload_branch': 'Fx-Team', 08:16:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:16:01 INFO - 'branch': 'Fx-Team', 08:16:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:16:01 INFO - 'config_files': ('talos/linux_config.py',), 08:16:01 INFO - 'default_actions': ('clobber', 08:16:01 INFO - 'read-buildbot-config', 08:16:01 INFO - 'download-and-extract', 08:16:01 INFO - 'populate-webroot', 08:16:01 INFO - 'create-virtualenv', 08:16:01 INFO - 'install', 08:16:01 INFO - 'run-tests'), 08:16:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:16:01 INFO - 'download_minidump_stackwalk': True, 08:16:01 INFO - 'download_symbols': 'ondemand', 08:16:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:16:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:16:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:16:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:16:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:16:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:16:01 INFO - 'installer_path': 'installer.exe', 08:16:01 INFO - 'log_level': 'info', 08:16:01 INFO - 'log_name': 'talos', 08:16:01 INFO - 'log_to_console': True, 08:16:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:16:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:16:01 INFO - 'opt_config_files': (), 08:16:01 INFO - 'pip_index': False, 08:16:01 INFO - 'sps_profile': False, 08:16:01 INFO - 'sps_profile_interval': 0, 08:16:01 INFO - 'suite': 'dromaeojs', 08:16:01 INFO - 'system_bits': '32', 08:16:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:16:01 INFO - 'title': 'talos-linux64-ix-040', 08:16:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:16:01 INFO - 'use_talos_json': True, 08:16:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:16:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:16:01 INFO - 'work_dir': 'build'} 08:16:01 INFO - [mozharness: 2016-07-21 15:16:01.471380Z] Running clobber step. 08:16:01 INFO - Running pre-action listener: _resource_record_pre_action 08:16:01 INFO - Running main action method: clobber 08:16:01 INFO - rmtree: /builds/slave/test/build 08:16:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:16:01 INFO - Running post-action listener: _resource_record_post_action 08:16:01 INFO - [mozharness: 2016-07-21 15:16:01.943885Z] Finished clobber step (success) 08:16:01 INFO - [mozharness: 2016-07-21 15:16:01.943987Z] Running read-buildbot-config step. 08:16:01 INFO - Running pre-action listener: _resource_record_pre_action 08:16:01 INFO - Running main action method: read_buildbot_config 08:16:01 INFO - Using buildbot properties: 08:16:01 INFO - { 08:16:01 INFO - "project": "", 08:16:01 INFO - "product": "firefox", 08:16:01 INFO - "script_repo_revision": "production", 08:16:01 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:16:01 INFO - "repository": "", 08:16:01 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 08:16:01 INFO - "buildid": "20160721053033", 08:16:01 INFO - "pgo_build": "True", 08:16:01 INFO - "basedir": "/builds/slave/test", 08:16:01 INFO - "buildnumber": 310, 08:16:01 INFO - "slavename": "talos-linux64-ix-040", 08:16:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:16:01 INFO - "platform": "ubuntu64_hw", 08:16:01 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:16:01 INFO - "branch": "fx-team", 08:16:01 INFO - "repo_path": "integration/fx-team", 08:16:01 INFO - "revision": "d8a5ea157504793ff2bb2b5161103154a31f08e3", 08:16:01 INFO - "stage_platform": "linux64-pgo", 08:16:01 INFO - "builduid": "5e064fda2b6645a79cfd45b6d8d7995e", 08:16:01 INFO - "slavebuilddir": "test" 08:16:01 INFO - } 08:16:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 08:16:01 INFO - Running post-action listener: _resource_record_post_action 08:16:01 INFO - [mozharness: 2016-07-21 15:16:01.946403Z] Finished read-buildbot-config step (success) 08:16:01 INFO - [mozharness: 2016-07-21 15:16:01.946492Z] Running download-and-extract step. 08:16:01 INFO - Running pre-action listener: _resource_record_pre_action 08:16:01 INFO - Running main action method: download_and_extract 08:16:01 INFO - mkdir: /builds/slave/test/build/tests 08:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:16:01 INFO - https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:16:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:16:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:16:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:02 INFO - Downloaded 1361 bytes. 08:16:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:16:02 INFO - Using the following test package requirements: 08:16:02 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:16:02 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:16:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:16:02 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:16:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:16:02 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:16:02 INFO - u'jsshell-linux-x86_64.zip'], 08:16:02 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:16:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:16:02 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:16:02 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:16:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:16:02 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:16:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:16:02 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:16:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:16:02 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:16:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:16:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:16: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')]} 08:16:02 INFO - https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:16:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:16:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:16:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:06 INFO - Downloaded 24342878 bytes. 08:16:06 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'] 08:16:06 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 08:16:08 INFO - Return code: 0 08:16:08 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 08:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:08 INFO - https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:16:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:16:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:16:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:09 INFO - Downloaded 24342878 bytes. 08:16:09 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'] 08:16:09 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 08:16:10 INFO - Return code: 0 08:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:10 INFO - https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:16:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:16:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:16:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:11 INFO - Downloaded 14403317 bytes. 08:16:11 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'] 08:16:11 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 08:16:11 INFO - Return code: 0 08:16:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:11 INFO - https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:16:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:16:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:16:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9p2TwMLRE68OP2iMqXDrA/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 08:16:12 INFO - Downloaded 60892912 bytes. 08:16:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:16:12 INFO - mkdir: /builds/slave/test/properties 08:16:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:16:12 INFO - Writing to file /builds/slave/test/properties/build_url 08:16:12 INFO - Contents: 08:16:12 INFO - build_url:https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:16:13 INFO - Running post-action listener: _resource_record_post_action 08:16:13 INFO - Running post-action listener: set_extra_try_arguments 08:16:13 INFO - [mozharness: 2016-07-21 15:16:13.766650Z] Finished download-and-extract step (success) 08:16:13 INFO - [mozharness: 2016-07-21 15:16:13.766920Z] Running populate-webroot step. 08:16:13 INFO - Running pre-action listener: _resource_record_pre_action 08:16:13 INFO - Running main action method: populate_webroot 08:16:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:16:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:16:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:16:13 INFO - '--tppagecycles', 08:16:13 INFO - '7'], 08:16:13 INFO - 'tests': ['tsvgm']}}, 08:16:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:16:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:16:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:16:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:16:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:13 INFO - 'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:13 INFO - 'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['damp', 'tps']}, 08:16:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:13 INFO - 'tests': ['damp', 'tps']}, 08:16:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['dromaeo_dom']}, 08:16:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:16:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['basic_compositor_video']}, 08:16:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:16:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['a11yr', 08:16:13 INFO - 'ts_paint', 08:16:13 INFO - 'tpaint', 08:16:13 INFO - 'sessionrestore', 08:16:13 INFO - 'sessionrestore_no_auto_restore', 08:16:13 INFO - 'tabpaint']}, 08:16:13 INFO - 'other-e10s': {'tests': ['a11yr', 08:16:13 INFO - 'ts_paint', 08:16:13 INFO - 'tpaint', 08:16:13 INFO - 'sessionrestore', 08:16:13 INFO - 'sessionrestore_no_auto_restore', 08:16:13 INFO - 'tabpaint']}, 08:16:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:16:13 INFO - 'ts_paint', 08:16:13 INFO - 'tpaint', 08:16:13 INFO - 'sessionrestore', 08:16:13 INFO - 'sessionrestore_no_auto_restore', 08:16:13 INFO - 'tabpaint']}, 08:16:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:16:13 INFO - 'ts_paint', 08:16:13 INFO - 'tpaint', 08:16:13 INFO - 'sessionrestore', 08:16:13 INFO - 'sessionrestore_no_auto_restore', 08:16:13 INFO - 'tabpaint']}, 08:16:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['a11yr', 08:16:13 INFO - 'ts_paint', 08:16:13 INFO - 'tpaint', 08:16:13 INFO - 'sessionrestore', 08:16:13 INFO - 'sessionrestore_no_auto_restore', 08:16:13 INFO - 'tabpaint']}, 08:16:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['a11yr', 08:16:13 INFO - 'ts_paint', 08:16:13 INFO - 'tpaint', 08:16:13 INFO - 'sessionrestore', 08:16:13 INFO - 'sessionrestore_no_auto_restore', 08:16:13 INFO - 'tabpaint']}, 08:16:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['tsvgx', 08:16:13 INFO - 'tsvgr_opacity', 08:16:13 INFO - 'tart', 08:16:13 INFO - 'tscrollx', 08:16:13 INFO - 'cart']}, 08:16:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:16:13 INFO - 'tsvgr_opacity', 08:16:13 INFO - 'tart', 08:16:13 INFO - 'tscrollx', 08:16:13 INFO - 'cart']}, 08:16:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:13 INFO - 'talos_options': ['--disable-e10s'], 08:16:13 INFO - 'tests': ['tp5o']}, 08:16:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:13 INFO - 'tests': ['tp5o']}, 08:16:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:13 INFO - 'talos_options': ['--disable-e10s', 08:16:13 INFO - '--xperf_path', 08:16:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:13 INFO - 'tests': ['tp5n']}, 08:16:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:16:13 INFO - 'talos_options': ['--xperf_path', 08:16:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:13 INFO - 'tests': ['tp5n']}}, 08:16:13 INFO - 'talos.zip': {'path': '', 08:16:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:16:13 INFO - Running post-action listener: _resource_record_post_action 08:16:13 INFO - [mozharness: 2016-07-21 15:16:13.787649Z] Finished populate-webroot step (success) 08:16:13 INFO - [mozharness: 2016-07-21 15:16:13.787737Z] Running create-virtualenv step. 08:16:13 INFO - Running pre-action listener: _resource_record_pre_action 08:16:13 INFO - Running main action method: create_virtualenv 08:16:13 INFO - Creating virtualenv /builds/slave/test/build/venv 08:16:13 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 08:16:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:16:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:16:13 INFO - Using real prefix '/usr' 08:16:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:16:14 INFO - Installing distribute.............................................................................................................................................................................................done. 08:16:16 INFO - Installing pip.................done. 08:16:16 INFO - Return code: 0 08:16:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:16 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 0x10cf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', '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 08:16:16 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 08:16:16 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 08:16:16 INFO - Using env: {'DISPLAY': ':0', 08:16:16 INFO - 'HOME': '/home/cltbld', 08:16:16 INFO - 'LANG': 'en_US.UTF-8', 08:16:16 INFO - 'LANGUAGE': 'en_US:en', 08:16:16 INFO - 'LOGNAME': 'cltbld', 08:16:16 INFO - 'MAIL': '/var/mail/cltbld', 08:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:16 INFO - 'MOZ_NO_REMOTE': '1', 08:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:16 INFO - 'NO_EM_RESTART': '1', 08:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:16 INFO - 'PWD': '/builds/slave/test', 08:16:16 INFO - 'SHELL': '/bin/bash', 08:16:16 INFO - 'SHLVL': '1', 08:16:16 INFO - 'TERM': 'linux', 08:16:16 INFO - 'TMOUT': '86400', 08:16:16 INFO - 'USER': 'cltbld', 08:16:16 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:16 INFO - '_': '/tools/buildbot/bin/python'} 08:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:16 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 08:16:16 INFO - Cleaning up... 08:16:16 INFO - Return code: 0 08:16:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:16 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 0x10cf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', '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 08:16:16 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 08:16:16 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 08:16:16 INFO - Using env: {'DISPLAY': ':0', 08:16:16 INFO - 'HOME': '/home/cltbld', 08:16:16 INFO - 'LANG': 'en_US.UTF-8', 08:16:16 INFO - 'LANGUAGE': 'en_US:en', 08:16:16 INFO - 'LOGNAME': 'cltbld', 08:16:16 INFO - 'MAIL': '/var/mail/cltbld', 08:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:16 INFO - 'MOZ_NO_REMOTE': '1', 08:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:16 INFO - 'NO_EM_RESTART': '1', 08:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:16 INFO - 'PWD': '/builds/slave/test', 08:16:16 INFO - 'SHELL': '/bin/bash', 08:16:16 INFO - 'SHLVL': '1', 08:16:16 INFO - 'TERM': 'linux', 08:16:16 INFO - 'TMOUT': '86400', 08:16:16 INFO - 'USER': 'cltbld', 08:16:16 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:16 INFO - '_': '/tools/buildbot/bin/python'} 08:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:16 INFO - Downloading/unpacking psutil>=3.1.1 08:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:16:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:18 INFO - Installing collected packages: psutil 08:16:18 INFO - Running setup.py install for psutil 08:16:18 INFO - building 'psutil._psutil_linux' extension 08:16:18 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 08:16:19 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 08:16:19 INFO - building 'psutil._psutil_posix' extension 08:16:19 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 08:16:19 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 08:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:19 INFO - Successfully installed psutil 08:16:19 INFO - Cleaning up... 08:16:19 INFO - Return code: 0 08:16:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:16:19 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')]} 08:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:19 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')]} 08:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:19 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 0x10cf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', '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 08:16:19 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 08:16:19 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 08:16:19 INFO - Using env: {'DISPLAY': ':0', 08:16:19 INFO - 'HOME': '/home/cltbld', 08:16:19 INFO - 'LANG': 'en_US.UTF-8', 08:16:19 INFO - 'LANGUAGE': 'en_US:en', 08:16:19 INFO - 'LOGNAME': 'cltbld', 08:16:19 INFO - 'MAIL': '/var/mail/cltbld', 08:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:19 INFO - 'MOZ_NO_REMOTE': '1', 08:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:19 INFO - 'NO_EM_RESTART': '1', 08:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:19 INFO - 'PWD': '/builds/slave/test', 08:16:19 INFO - 'SHELL': '/bin/bash', 08:16:19 INFO - 'SHLVL': '1', 08:16:19 INFO - 'TERM': 'linux', 08:16:19 INFO - 'TMOUT': '86400', 08:16:19 INFO - 'USER': 'cltbld', 08:16:19 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:19 INFO - '_': '/tools/buildbot/bin/python'} 08:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:16:19 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 08:16:19 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 08:16:19 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 08:16:19 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 08:16:19 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 08:16:19 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 08:16:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:16:21 INFO - Installing collected packages: mozsystemmonitor 08:16:21 INFO - Running setup.py install for mozsystemmonitor 08:16:21 INFO - Successfully installed mozsystemmonitor 08:16:21 INFO - Cleaning up... 08:16:21 INFO - Return code: 0 08:16:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:16: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')]} 08:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16: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')]} 08:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:21 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 0x10cf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', '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 08:16:21 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 08:16:21 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 08:16:21 INFO - Using env: {'DISPLAY': ':0', 08:16:21 INFO - 'HOME': '/home/cltbld', 08:16:21 INFO - 'LANG': 'en_US.UTF-8', 08:16:21 INFO - 'LANGUAGE': 'en_US:en', 08:16:21 INFO - 'LOGNAME': 'cltbld', 08:16:21 INFO - 'MAIL': '/var/mail/cltbld', 08:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:21 INFO - 'MOZ_NO_REMOTE': '1', 08:16:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:21 INFO - 'NO_EM_RESTART': '1', 08:16:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:21 INFO - 'PWD': '/builds/slave/test', 08:16:21 INFO - 'SHELL': '/bin/bash', 08:16:21 INFO - 'SHLVL': '1', 08:16:21 INFO - 'TERM': 'linux', 08:16:21 INFO - 'TMOUT': '86400', 08:16:21 INFO - 'USER': 'cltbld', 08:16:21 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:21 INFO - '_': '/tools/buildbot/bin/python'} 08:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:21 INFO - Downloading/unpacking jsonschema==2.5.1 08:16:21 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 08:16:21 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 08:16:21 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 08:16:21 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 08:16:21 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 08:16:21 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 08:16:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:16:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:23 INFO - Downloading functools32-3.2.3-2.tar.gz 08:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:16:24 INFO - warning: no files found matching '*.txt' 08:16:24 INFO - no previously-included directories found matching 'build' 08:16:24 INFO - no previously-included directories found matching 'dist' 08:16:24 INFO - no previously-included directories found matching '.git*' 08:16:24 INFO - Installing collected packages: jsonschema, functools32 08:16:24 INFO - Running setup.py install for functools32 08:16:24 INFO - warning: no files found matching '*.txt' 08:16:24 INFO - no previously-included directories found matching 'build' 08:16:24 INFO - no previously-included directories found matching 'dist' 08:16:24 INFO - no previously-included directories found matching '.git*' 08:16:24 INFO - Successfully installed jsonschema functools32 08:16:24 INFO - Cleaning up... 08:16:24 INFO - Return code: 0 08:16:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:16: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')]} 08:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16: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')]} 08:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:24 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 0x10cf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', '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 08:16:24 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 08:16:24 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 08:16:24 INFO - Using env: {'DISPLAY': ':0', 08:16:24 INFO - 'HOME': '/home/cltbld', 08:16:24 INFO - 'LANG': 'en_US.UTF-8', 08:16:24 INFO - 'LANGUAGE': 'en_US:en', 08:16:24 INFO - 'LOGNAME': 'cltbld', 08:16:24 INFO - 'MAIL': '/var/mail/cltbld', 08:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:24 INFO - 'MOZ_NO_REMOTE': '1', 08:16:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:24 INFO - 'NO_EM_RESTART': '1', 08:16:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:24 INFO - 'PWD': '/builds/slave/test', 08:16:24 INFO - 'SHELL': '/bin/bash', 08:16:24 INFO - 'SHLVL': '1', 08:16:24 INFO - 'TERM': 'linux', 08:16:24 INFO - 'TMOUT': '86400', 08:16:24 INFO - 'USER': 'cltbld', 08:16:24 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:24 INFO - '_': '/tools/buildbot/bin/python'} 08:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:24 INFO - Downloading/unpacking blobuploader==1.2.4 08:16:24 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 08:16:24 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 08:16:24 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 08:16:24 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 08:16:24 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 08:16:24 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 08:16:26 INFO - Downloading blobuploader-1.2.4.tar.gz 08:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:16:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:16:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:16:27 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 08:16:27 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 08:16:27 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 08:16:27 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 08:16:27 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 08:16:27 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 08:16:27 INFO - Downloading docopt-0.6.1.tar.gz 08:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:16:27 INFO - Installing collected packages: blobuploader, requests, docopt 08:16:27 INFO - Running setup.py install for blobuploader 08:16:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:16:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:16:27 INFO - Running setup.py install for requests 08:16:27 INFO - Running setup.py install for docopt 08:16:27 INFO - Successfully installed blobuploader requests docopt 08:16:27 INFO - Cleaning up... 08:16:28 INFO - Return code: 0 08:16:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:28 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 0x10cf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', '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 08:16:28 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 08:16:28 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 08:16:28 INFO - Using env: {'DISPLAY': ':0', 08:16:28 INFO - 'HOME': '/home/cltbld', 08:16:28 INFO - 'LANG': 'en_US.UTF-8', 08:16:28 INFO - 'LANGUAGE': 'en_US:en', 08:16:28 INFO - 'LOGNAME': 'cltbld', 08:16:28 INFO - 'MAIL': '/var/mail/cltbld', 08:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:28 INFO - 'MOZ_NO_REMOTE': '1', 08:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:28 INFO - 'NO_EM_RESTART': '1', 08:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:28 INFO - 'PWD': '/builds/slave/test', 08:16:28 INFO - 'SHELL': '/bin/bash', 08:16:28 INFO - 'SHLVL': '1', 08:16:28 INFO - 'TERM': 'linux', 08:16:28 INFO - 'TMOUT': '86400', 08:16:28 INFO - 'USER': 'cltbld', 08:16:28 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:28 INFO - '_': '/tools/buildbot/bin/python'} 08:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:28 INFO - Running setup.py (path:/tmp/pip-ODFequ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:28 INFO - Running setup.py (path:/tmp/pip-L3U3Vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:28 INFO - Running setup.py (path:/tmp/pip-qTiD7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:28 INFO - Running setup.py (path:/tmp/pip-BqjJ71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:28 INFO - Running setup.py (path:/tmp/pip-xID2BL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:28 INFO - Running setup.py (path:/tmp/pip-x8f1hT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:28 INFO - Running setup.py (path:/tmp/pip-PrhmPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:28 INFO - Running setup.py (path:/tmp/pip-j7pvIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:28 INFO - Running setup.py (path:/tmp/pip-BKptAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:28 INFO - Running setup.py (path:/tmp/pip-VS_SAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:28 INFO - Running setup.py (path:/tmp/pip-VfPbHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:29 INFO - Running setup.py (path:/tmp/pip-59vymo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:29 INFO - Running setup.py (path:/tmp/pip-roLbJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:29 INFO - Running setup.py (path:/tmp/pip-0HmAVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:29 INFO - Running setup.py (path:/tmp/pip-3naYaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:29 INFO - Running setup.py (path:/tmp/pip-zwLYiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:29 INFO - Running setup.py (path:/tmp/pip-xQdXOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:16:29 INFO - Running setup.py install for manifestparser 08:16:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:16:29 INFO - Running setup.py install for mozcrash 08:16:29 INFO - Running setup.py install for mozdebug 08:16:29 INFO - Running setup.py install for mozdevice 08:16:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:16:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:16:29 INFO - Running setup.py install for mozfile 08:16:29 INFO - Running setup.py install for mozhttpd 08:16:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Running setup.py install for mozinfo 08:16:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Running setup.py install for mozInstall 08:16:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Running setup.py install for mozleak 08:16:30 INFO - Running setup.py install for mozlog 08:16:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Running setup.py install for moznetwork 08:16:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Running setup.py install for mozprocess 08:16:30 INFO - Running setup.py install for mozprofile 08:16:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Running setup.py install for mozrunner 08:16:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:16:30 INFO - Running setup.py install for mozscreenshot 08:16:31 INFO - Running setup.py install for moztest 08:16:31 INFO - Running setup.py install for mozversion 08:16:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:16:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:16:31 INFO - Cleaning up... 08:16:31 INFO - Return code: 0 08:16:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:31 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 0x10cf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', '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 08:16:31 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 08:16:31 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 08:16:31 INFO - Using env: {'DISPLAY': ':0', 08:16:31 INFO - 'HOME': '/home/cltbld', 08:16:31 INFO - 'LANG': 'en_US.UTF-8', 08:16:31 INFO - 'LANGUAGE': 'en_US:en', 08:16:31 INFO - 'LOGNAME': 'cltbld', 08:16:31 INFO - 'MAIL': '/var/mail/cltbld', 08:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:31 INFO - 'MOZ_NO_REMOTE': '1', 08:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:31 INFO - 'NO_EM_RESTART': '1', 08:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:31 INFO - 'PWD': '/builds/slave/test', 08:16:31 INFO - 'SHELL': '/bin/bash', 08:16:31 INFO - 'SHLVL': '1', 08:16:31 INFO - 'TERM': 'linux', 08:16:31 INFO - 'TMOUT': '86400', 08:16:31 INFO - 'USER': 'cltbld', 08:16:31 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:31 INFO - '_': '/tools/buildbot/bin/python'} 08:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:31 INFO - Running setup.py (path:/tmp/pip-FfDFzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:31 INFO - Running setup.py (path:/tmp/pip-qz15he-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:31 INFO - Running setup.py (path:/tmp/pip-QtGoqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:31 INFO - Running setup.py (path:/tmp/pip-ZuPuEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:31 INFO - Running setup.py (path:/tmp/pip-31dipz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:31 INFO - Running setup.py (path:/tmp/pip-2FyyH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:31 INFO - Running setup.py (path:/tmp/pip-UwA6IR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:31 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)) 08:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:31 INFO - Running setup.py (path:/tmp/pip-x2nh8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:32 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)) 08:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:32 INFO - Running setup.py (path:/tmp/pip-C8btuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:32 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)) 08:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:32 INFO - Running setup.py (path:/tmp/pip-Ap7JYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:32 INFO - Running setup.py (path:/tmp/pip-5y2BRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:32 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)) 08:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:32 INFO - Running setup.py (path:/tmp/pip-JMAkiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:32 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)) 08:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:32 INFO - Running setup.py (path:/tmp/pip-00OMpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:32 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)) 08:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:32 INFO - Running setup.py (path:/tmp/pip-kGIne5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:32 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)) 08:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:32 INFO - Running setup.py (path:/tmp/pip-nTJ_Al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:32 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)) 08:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:32 INFO - Running setup.py (path:/tmp/pip-7yY51f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:32 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)) 08:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:32 INFO - Running setup.py (path:/tmp/pip-CgLuQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:32 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)) 08:16:32 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)) 08:16:32 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)) 08:16:32 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)) 08:16:32 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)) 08:16:32 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)) 08:16:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16:34 INFO - Downloading blessings-1.6.tar.gz 08:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:16:34 INFO - Installing collected packages: blessings 08:16:34 INFO - Running setup.py install for blessings 08:16:34 INFO - Successfully installed blessings 08:16:34 INFO - Cleaning up... 08:16:34 INFO - Return code: 0 08:16:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:16:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:35 INFO - Reading from file tmpfile_stdout 08:16:35 INFO - Current package versions: 08:16:35 INFO - argparse == 1.2.1 08:16:35 INFO - blessings == 1.6 08:16:35 INFO - blobuploader == 1.2.4 08:16:35 INFO - docopt == 0.6.1 08:16:35 INFO - functools32 == 3.2.3-2 08:16:35 INFO - jsonschema == 2.5.1 08:16:35 INFO - manifestparser == 1.1 08:16:35 INFO - mozInstall == 1.12 08:16:35 INFO - mozcrash == 0.17 08:16:35 INFO - mozdebug == 0.1 08:16:35 INFO - mozdevice == 0.48 08:16:35 INFO - mozfile == 1.2 08:16:35 INFO - mozhttpd == 0.7 08:16:35 INFO - mozinfo == 0.9 08:16:35 INFO - mozleak == 0.1 08:16:35 INFO - mozlog == 3.3 08:16:35 INFO - moznetwork == 0.27 08:16:35 INFO - mozprocess == 0.23 08:16:35 INFO - mozprofile == 0.28 08:16:35 INFO - mozrunner == 6.12 08:16:35 INFO - mozscreenshot == 0.1 08:16:35 INFO - mozsystemmonitor == 0.3 08:16:35 INFO - moztest == 0.7 08:16:35 INFO - mozversion == 1.4 08:16:35 INFO - psutil == 3.1.1 08:16:35 INFO - requests == 1.2.3 08:16:35 INFO - wsgiref == 0.1.2 08:16:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:35 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')]} 08:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:35 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')]} 08:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:35 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 0x10cf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', '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 08:16:35 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 08:16:35 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 08:16:35 INFO - Using env: {'DISPLAY': ':0', 08:16:35 INFO - 'HOME': '/home/cltbld', 08:16:35 INFO - 'LANG': 'en_US.UTF-8', 08:16:35 INFO - 'LANGUAGE': 'en_US:en', 08:16:35 INFO - 'LOGNAME': 'cltbld', 08:16:35 INFO - 'MAIL': '/var/mail/cltbld', 08:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:35 INFO - 'MOZ_NO_REMOTE': '1', 08:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:35 INFO - 'NO_EM_RESTART': '1', 08:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:35 INFO - 'PWD': '/builds/slave/test', 08:16:35 INFO - 'SHELL': '/bin/bash', 08:16:35 INFO - 'SHLVL': '1', 08:16:35 INFO - 'TERM': 'linux', 08:16:35 INFO - 'TMOUT': '86400', 08:16:35 INFO - 'USER': 'cltbld', 08:16:35 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:35 INFO - '_': '/tools/buildbot/bin/python'} 08:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 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)) 08:16:35 INFO - Cleaning up... 08:16:35 INFO - Return code: 0 08:16:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:16:35 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')]} 08:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:35 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')]} 08:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:35 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 0x10cf030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12328a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1390180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', '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 08:16:35 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 08:16:35 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 08:16:35 INFO - Using env: {'DISPLAY': ':0', 08:16:35 INFO - 'HOME': '/home/cltbld', 08:16:35 INFO - 'LANG': 'en_US.UTF-8', 08:16:35 INFO - 'LANGUAGE': 'en_US:en', 08:16:35 INFO - 'LOGNAME': 'cltbld', 08:16:35 INFO - 'MAIL': '/var/mail/cltbld', 08:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:35 INFO - 'MOZ_NO_REMOTE': '1', 08:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:35 INFO - 'NO_EM_RESTART': '1', 08:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:35 INFO - 'PWD': '/builds/slave/test', 08:16:35 INFO - 'SHELL': '/bin/bash', 08:16:35 INFO - 'SHLVL': '1', 08:16:35 INFO - 'TERM': 'linux', 08:16:35 INFO - 'TMOUT': '86400', 08:16:35 INFO - 'USER': 'cltbld', 08:16:35 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:35 INFO - '_': '/tools/buildbot/bin/python'} 08:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:16:35 INFO - Cleaning up... 08:16:35 INFO - Return code: 0 08:16:35 INFO - Running post-action listener: _resource_record_post_action 08:16:35 INFO - Running post-action listener: _start_resource_monitoring 08:16:35 INFO - Starting resource monitoring. 08:16:35 INFO - [mozharness: 2016-07-21 15:16:35.549658Z] Finished create-virtualenv step (success) 08:16:35 INFO - [mozharness: 2016-07-21 15:16:35.550087Z] Running install step. 08:16:35 INFO - Running pre-action listener: _resource_record_pre_action 08:16:35 INFO - Running main action method: install 08:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:35 INFO - Reading from file tmpfile_stdout 08:16:35 INFO - Detecting whether we're running mozinstall >=1.0... 08:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:16:35 INFO - Reading from file tmpfile_stdout 08:16:35 INFO - Output received: 08:16:35 INFO - Usage: mozinstall [options] installer 08:16:35 INFO - Options: 08:16:35 INFO - -h, --help show this help message and exit 08:16:35 INFO - -d DEST, --destination=DEST 08:16:35 INFO - Directory to install application into. [default: 08:16:35 INFO - "/builds/slave/test"] 08:16:35 INFO - --app=APP Application being installed. [default: firefox] 08:16:35 INFO - mkdir: /builds/slave/test/build/application 08:16:35 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'] 08:16:35 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 08:16:53 INFO - Reading from file tmpfile_stdout 08:16:53 INFO - Output received: 08:16:53 INFO - /builds/slave/test/build/application/firefox/firefox 08:16:53 INFO - Running post-action listener: _resource_record_post_action 08:16:53 INFO - [mozharness: 2016-07-21 15:16:53.074347Z] Finished install step (success) 08:16:53 INFO - [mozharness: 2016-07-21 15:16:53.074628Z] Running run-tests step. 08:16:53 INFO - Running pre-action listener: _resource_record_pre_action 08:16:53 INFO - Running main action method: run_tests 08:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:16:53 INFO - Python 2.7.3 08:16:53 INFO - Return code: 0 08:16:53 INFO - grabbing minidump binary from tooltool 08:16:53 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')]} 08:16:53 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 0x138e3b0>, '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 0x138f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x138f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:16:53 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 08:16:53 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 08:16:53 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 08:16:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:16:53 INFO - Return code: 0 08:16:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:16:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:16:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:16:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:16:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:16:53 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/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 08:16:53 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/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 08:16:53 INFO - Using env: {'DISPLAY': ':0', 08:16:53 INFO - 'HOME': '/home/cltbld', 08:16:53 INFO - 'LANG': 'en_US.UTF-8', 08:16:53 INFO - 'LANGUAGE': 'en_US:en', 08:16:53 INFO - 'LOGNAME': 'cltbld', 08:16:53 INFO - 'MAIL': '/var/mail/cltbld', 08:16:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:16:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:53 INFO - 'MOZ_NO_REMOTE': '1', 08:16:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:16:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:16:53 INFO - 'NO_EM_RESTART': '1', 08:16:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:53 INFO - 'PWD': '/builds/slave/test', 08:16:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:16:53 INFO - 'SHELL': '/bin/bash', 08:16:53 INFO - 'SHLVL': '1', 08:16:53 INFO - 'TERM': 'linux', 08:16:53 INFO - 'TMOUT': '86400', 08:16:53 INFO - 'USER': 'cltbld', 08:16:53 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351', 08:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:53 INFO - '_': '/tools/buildbot/bin/python'} 08:16:53 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/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 08:16:53 INFO - mozversion application_buildid: 20160721053033 08:16:53 INFO - mozversion application_changeset: d8a5ea157504793ff2bb2b5161103154a31f08e3 08:16:53 INFO - mozversion application_display_name: Nightly 08:16:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:16:53 INFO - mozversion application_name: Firefox 08:16:53 INFO - mozversion application_remotingname: firefox 08:16:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:16:53 INFO - mozversion application_vendor: Mozilla 08:16:53 INFO - mozversion application_version: 50.0a1 08:16:53 INFO - mozversion platform_buildid: 20160721053033 08:16:53 INFO - mozversion platform_changeset: d8a5ea157504793ff2bb2b5161103154a31f08e3 08:16:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:16:53 INFO - mozversion platform_version: 50.0a1 08:16:53 INFO - using testdate: 1469114213 08:16:53 INFO - actual date: 1469114213 08:16:53 INFO - starting webserver on 'localhost:35967' 08:16:53 INFO - SUITE-START | Running 2 tests 08:16:53 INFO - TEST-START | dromaeo_css 08:16:53 INFO - operating with platform_type : linux_ 08:16:53 INFO - Initialising browser for dromaeo_css test... 08:16:53 INFO - TEST-INFO | started process 17548 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEO3ZKw/profile http://localhost:35967/getInfo.html) 08:16:57 INFO - PROCESS | 17548 | __metrics Screen width/height:1600/1200 08:16:57 INFO - PROCESS | 17548 | colorDepth:24 08:16:57 INFO - PROCESS | 17548 | Browser inner width/height: 1024/697 08:16:57 INFO - PROCESS | 17548 | __metrics 08:16:58 INFO - PROCESS | 17548 | 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" 08:16:58 INFO - PROCESS | 17548 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:16:58 INFO - PROCESS | 17548 | console.error: PushService: 08:16:58 INFO - PROCESS | 17548 | stateChangeProcessEnqueue: Error transitioning state 08:16:58 INFO - PROCESS | 17548 | UnknownError 08:16:59 INFO - TEST-INFO | 17548: exit 0 08:16:59 INFO - Browser initialized. 08:16:59 INFO - Running cycle 1/1 for dromaeo_css test... 08:16:59 INFO - TEST-INFO | started process 17633 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEO3ZKw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:17:11 INFO - PROCESS | 17633 | RSS: Main: 157696000 08:17:11 INFO - PROCESS | 17633 | 08:18:02 INFO - PROCESS | 17633 | 1469114282826 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] 08:20:10 INFO - PROCESS | 17633 | Cycle 1(1): loaded http://localhost:35967/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35967/tests/dromaeo/cssquery-ext.html) 08:20:12 INFO - PROCESS | 17633 | RSS: Main: 263798784 08:20:12 INFO - PROCESS | 17633 | 08:23:14 INFO - PROCESS | 17633 | Cycle 1(1): loaded http://localhost:35967/tests/dromaeo/cssquery-ext.html (next: http://localhost:35967/tests/dromaeo/cssquery-jquery.html) 08:23:15 INFO - PROCESS | 17633 | RSS: Main: 244297728 08:23:15 INFO - PROCESS | 17633 | 08:26:17 INFO - PROCESS | 17633 | Cycle 1(1): loaded http://localhost:35967/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35967/tests/dromaeo/cssquery-mootools.html) 08:26:17 INFO - PROCESS | 17633 | RSS: Main: 184844288 08:26:17 INFO - PROCESS | 17633 | 08:29:19 INFO - PROCESS | 17633 | Cycle 1(1): loaded http://localhost:35967/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35967/tests/dromaeo/cssquery-prototype.html) 08:29:20 INFO - PROCESS | 17633 | RSS: Main: 225898496 08:29:20 INFO - PROCESS | 17633 | 08:32:23 INFO - PROCESS | 17633 | Cycle 1(1): loaded http://localhost:35967/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35967/tests/dromaeo/cssquery-yui.html) 08:32:23 INFO - PROCESS | 17633 | RSS: Main: 187129856 08:32:23 INFO - PROCESS | 17633 | 08:35:26 INFO - PROCESS | 17633 | Cycle 1(1): loaded http://localhost:35967/tests/dromaeo/cssquery-yui.html (next: http://localhost:35967/tests/dromaeo/cssquery-dojo.html) 08:35:27 INFO - PROCESS | 17633 | RSS: Main: 269139968 08:35:27 INFO - PROCESS | 17633 | 08:35:27 INFO - PROCESS | 17633 | __start_tp_report 08:35:27 INFO - PROCESS | 17633 | _x_x_mozilla_page_load 08:35:27 INFO - PROCESS | 17633 | _x_x_mozilla_page_load_details 08:35:27 INFO - PROCESS | 17633 | |i|pagename|runs| 08:35:27 INFO - PROCESS | 17633 | |0;dojo.html;3030.666626671067;3030.909072727818;3062.433449811785;3066.1874603230135;3074.523448865425;5304.257403963448;5323.653962492435;5327.334083239595;5407.351117865852;5458.7543560539825;5375.704336261514;5400.945990540094;5413.810516631918;5470.507654311111;5507.917381239285;4824.107540105092;4906.509349065101;4917.311576379309;4920.655554111214;5081.136206844845;4988.625853061017;5031.798728050873;5046.041252162096;5064.316317297173;5065.189569668853;4824.300476430898;4839.685420447659;4842.055799119174;4856.320115183859;4960.851174464772;4856.781444834991;4864.294677271776;4886.169351210267;4968.453470118284;4984.152694042022;4866;4895.2167653175675;4905.607551395879;4927.605791536705;4948.802047918079;5307.814226502054;5322.2548843161985;5398.838034858961;5418.593605479566;5453.618246722731;5770.336411312669;5850.707464626752;5858.12128180772;5862.20860183874;5866.882662346729;5817.418258174149;5836.854078648025;5880.794172203995;5880.8235752927485;5890.175375447441;5242.79028838846;5295.046891559479;5342.091844386464;5500.669959802384;5501.312335958005;5894.852628684232;5896.498797602166;5929.466348028698;5938.910916336258;5954.76180952757;5626.803061892896;5633.436656334416;5679.801206957819;5681.147827825859;5681.176229446707;5597.608167428241;5692.743826527816;5728.140778883201;5785.623934444247;5786.218860453869;5741.425857414225;5744.052231381859;5744.626599271034;5783.334916484574;5795;5671.291088613923;5718.228039214736;5729.856753581111;5731.570132240098;5749.597528172988;4311.9784401077795;4349.652027837766;4353.651707863363;4394.406755088086;4402.075564131568;3925.0579860833195;3942.5466071402157;3987.043109653662;3993.4608827808456;4029.4560234367987;5451.700156491352;5455.236266922555;5460.453954604508;5461.617686761968;5462.2079798429;5670.5180059695385;5735.971320143456;5820.214271073351;5828.329742079631;5870.471657550841;5405.891882162335;5446.972765136228;5454.372747134051;5486.1222204447895;5498.615096943176;5018.974905125451;5024.949750502448;5025.572826309804;5031.6729412588065;5074.238864170404;5820.039693450618;5959.463648271676;5969.104634304889;6012.699365031818;6036.456718895232;5821.388754180919;5855.970720146372;5870.735816888336;5910.852228694317;5913.556483263858;6009.2188015557695;6027.849303767265;6028.668423236764;6030.005049167014;6040.063790112364;5538.806141785019;5659.462351076641;5713.05734553794;5756.481916627524;5759.510441612342;5798.59409841307;5822.155787410717;5839.0949402842625;5874.500667443315;5876.853078672896;5675.347335056356;5708.286464191976;5708.4862362387585;5725.0267454531995;5731.19763233156;5563.193336966036;5635.239242702182;5718.142278658234;5725.6850873202375;5728.770849165987;5606.299212598426;5664.603477756682;5665.546756259407;5667.518260947865;5721;5748.223989761329;5751.1373294006235;5763.5965482417505;5815.505682017076;5816.767329306949;5383.380911195262;5503.477169668875;5640.85897852557;5649.406812284651;5650.378458369494 08:35:27 INFO - PROCESS | 17633 | |1;ext.html;598.2432223237603;615.2125279642058;635.6853357587995;636.9745210191589;643.62991280014;30921.072367828972;45068.32397514036;45588.54411455884;45608.54391456085;45667.54332456679;46017;46370.536294637044;46488.53511464885;46534.30198547024;46542.30186547204;8988.235999940012;9108.407953483002;9110.180083792457;9139.223166030863;9141.908580914172;8959.5968181432;9032.819343613124;9060.139286767746;9063.410878292923;9078.682246121387;9079.636814527412;9136.771580710501;9142.954285228565;9143.954280228589;9146.862797058016;9065.18413342799;9099.408538444979;9111.635534578609;9112.407693499901;9118.680846170419;8913.153250441197;8970.596323165408;8971.506567138806;8975.192232699088;8978.86531702025;43023.35464968028;43196;43347.78326108381;43354.34968475475;43397;3371.7471189660628;3434.0899661589697;4038.1318016626565;4185.686073544496;4255.255330317213;32398.83800580982;33055.50416743751;33346.3330733384;33561.160970975674;34523.48214776755;4432.911341773179;4549.158405694924;4551.527567471273;4596.218642830727;4703.294505824119;365370;366032;367030.1648491767;368035;369743;60109.69945150334;60352.698236508535;60455.69772151111;60486.39513604896;60589.697051515344;63422.68288658527;63625.36374636287;63714;63743.68128159329;63745.68127159335;15016.699666006618;15035.323410446337;15055.924720376328;15220.923895380452;15238.238088095552;11447.713807154722;11614;11621.53513859453;11632.243904146204;11634.53461861533;14999.925000375146;17007.574810629834;17074.74387884158;17078;17127.314907403565;6287.748490060347;6343.429091381798;6390.904136437957;6510.804675859732;6574.276829548746;747.6488023317165;1049.7323182588543;1072.9302595331278;1078.1321036565482;1109.2789686703545;33795;34441;34596.30807383838;34686.13284667904;34717.47923781145;1086.7283179205199;1139.128566646516;1142.7600203957263;1159.287038471351;1168.865580458241;17501.912490437466;17737.911310443364;17839.375621853174;17863.821361786217;17933.910330448263;518244;520986;521554;524331;527065;61127.08309375363;61338.38661613327;61362.38637613656;61409.07886381708;61411.38588614146;1236.258245052961;1363.5091367107834;1371.766799644063;1373.1623709537175;1405.1569058564983;31344.216394589406;35375.82312088526;36155;36320.81839590785;36378.45432318465;53775.19337209867;54431;54513.18230226471;54635.453645463036;54646.726766365915;525393;529821;530298;535185;535253;39282;44520.33219501645;45267.547324526335;45945;49236;49550;50031.24953125633;50520.49479505305;50862.49137508726;50976.74511627418;42186.78906605447;42223.15553688847;42559.57440425556;42799.004069716066;44354.55645443504;42725.57274427216;42962.57037429586;43440.78279608582;44191.779041104586;44578.77710611426;42496.5750342505;43456.34815477707;44209.778951105036;44511.332330014426;44595;35686.821565892;38863.2227355457;39183.97810672969;39193.608063919;39597.60402396054;29151.416971660023;29386.412271754874;29629.25926851846;29670.851645741634;29776.553351700175 08:35:27 INFO - PROCESS | 17633 | |2;jquery.html;370.3112211287006;373.2497679663875;374.52248383311286;379.5729803970533;392.23318412503556;19229.423117306487;20289.49276268094;20989.47526311843;21011.99999999998;21139.894300528515;11536.423178841045;11861.52553897843;11866.466009029593;11882.287062776219;14335.569932902004;5317.415084340729;5556;5560.443955604427;5561.749721262553;5562.638428502155;4045.110075783323;5441.83674489764;7401.592912389817;7854.567998760094;7926.643301051437;7663.540187588735;7863.056433228021;7897.644605992716;8041.798955026113;8043.55760433176;7593.658285377116;7773.533587984739;7788.221177882223;7789.026371703537;7804.414668899855;7558.924410755877;7630.427717921122;7631.046119235096;7632.274933881328;7634.6182690865235;25741.227763167135;26517.3370665733;26581;26941.46117077667;26983.865080674666;2311.318161142467;2463.5811911975006;2890.132960111958;3371.866579672163;3494.4932984717075;16156.788240881864;16444.373452599626;16563.337466501325;16807;17084.743728844078;2040.6020825938947;2127.7978592033733;2131.1688441507827;2160.8163306119;2192.210804110519;103401.99999999924;104126.47936760343;104979.47510262477;105627.47186264096;106270.46864765704;17781.7332740009;18686.07592405583;18832;18846.811531884505;18868.716969245474;33226.50160247598;33253.66746332554;33434;33466.66533334684;33489.497657535154;8871.955640221757;9268.814623707489;9685.515724213901;9722.416655000723;9934.652287170058;7861;7984.6806127754235;8140.470869393471;8190.467619604707;8233.629486673113;8584.184502472255;8888;9080.636774528944;9241.861372079424;9341.90658093424;2897.406031763526;3389.4237979543536;3483.676203042878;3545.166885781839;3636.9272614547563;921.9585118669676;1022.1567207054061;1038.55342202852;1090.0843291635065;1099.5436893689189;19803.60392792165;20257.797422025887;20345.89827050885;20404.897975510026;20651.793482065288;974.4883935933692;978.1490103609763;998.9850152247722;1008.9545970431349;1014.0012088093222;10313.690589282334;11150.721231969103;11172.776544469065;11208.55165793359;11234.438278086063;468617;479774;482537;483353;485266;87812.99999999872;88979;90409;90859.09140908638;91156;980.7989362180735;1018.6638409324905;1041.578160844859;1052.3422860712055;1059.0627294843964;17207.655846882742;17645.245953791622;17984.640307194044;18001.639967200845;18084.09579521045;43825.123497530505;43842.56157438385;43981.56018439903;44912.550874492146;45238.77380613076;58493;58650;59510.10734838894;60572.697136514034;60920.39079609325;6568.981807819796;7528.322450979219;8170.918290817016;8175.305099066408;8346.081930987466;10420.50544544801;12595.93702031484;12799.168054076456;12825.422855971317;13286.136401133896;11220.55117795279;11307;11543;11591.6522504325;11707.531698732297;11194.216404851582;11255.212135150789;11269.380184089954;11641.06871450299;11769.411529423665;10749.677509674722;11237.60668376603;11243.212975091665;11894.167408281337;11944.701382465508;7156.570605763671;7470.775876723707;7489.21363256872;7828.217178282126;8442.451240669337;10420.270581059252;11259.662210133709;11326.32042077478;11394.202405831844;11836.881631183578 08:35:27 INFO - PROCESS | 17633 | |3;mootools.html;1302.8501722301937;1487.6355292953224;1520.7414739494284;1652.8264532224116;1802.161994672478;8363.498190108598;8826.735197944065;8888.244499217575;9066.818663626724;9158.358914875962;8974.192322690957;9034.960979487361;9071.228945539637;9075.319351048682;9091.77270568236;2058.238451772846;2251.9774802252014;2355.034435881289;2372.8101751859895;2376.8217383696206;2396.8561886286793;2399.8200134989947;2400.59189937711;2405.7714517120844;2407.2898494944034;2304.665823455598;2320.1879342230254;2337.5324935013064;2338.462153704649;2343.5898717724417;2003.4991252186953;2250.324902529251;2305.204704376996;2315.235972129202;2315.409570559513;2356.9410764730846;2365;2369.4194922243987;2370.0638247892157;2371.454565449939;8709.129087091304;8710.390272680916;8726.952765668097;8782.473051616924;8804.515751633657;5205.765740541685;5338.439463856278;5339.5728341732565;5341.626086173969;5366.892662146735;3208.855601497938;3320;3381.1885147564653;3383.4079036168573;3387.5257463955068;1053.9473026348728;1088.3306766338724;1111.77208672222;1120.6974116988367;1127.5433449452985;12228.327441990687;12233.571824986082;12329.136960412774;12367.381630918508;12368.1960672556;4538.11506756186;4720.858374248778;4739.478657347689;4750.192467280571;4762.52374762521;10436.373817570819;10505.00202480763;10555.94722026385;10569.260151789455;10603.522841472151;5160.767765450564;5162.948370516322;5166.302549155891;5173.611979101583;5177.741112944338;4728.196206644879;4728.219843725747;4857.417109946829;4900.4854490278;4984.950150498448;4116.064357774965;4138.337865941435;4184.451752851464;4211.789410529461;4237.152569486054;1278.4055414232241;1295.5077070713069;1301.0502333296747;1301.8112373705949;1308.685915380321;1019.4953498018529;1034.2294990232174;1044.8850626431085;1047.1622701838498;1060.236629626683;6054.273487181566;6141.877162456726;6202.4417802398;6351.777687780906;6362.331955144643;461.1169610196503;462.0966011447679;465.97204167750044;467.5698357511098;477.29122253453596;4218.873433796991;4277.657787377002;4332.176886391639;4418.33724941255;4467.776611169428;10422.21833362501;10846.511906964206;10853;10863.239573229797;10879.347239165676;10183.236257280585;10518.842217366746;10532.210084243712;10578;10725.463726813628;7807.531548107132;8010.559419231999;8019.478733882279;8025.839483210303;8042.195780421911;6259.311475737574;6364.87270254583;6418.935810641834;6449.580777249652;6601.075849381186;10178.592856285963;10218.335808172445;10226;10319.793604128026;10339.534720937425;9008.819823603622;9533.427994320364;9718.659846905324;9771.12059914611;9989.60041598328;7240.637968101679;7387.482876198615;7487.775366739006;7572.318491335806;7595.620218989139;8156.143604921635;8249.216324449168;8301.045379781403;8304.169583041888;8375.288100511525;7094.787156385317;7245.275472452712;7454.254574542502;7456.552606843607;7476.289752473506;7240.348368646847;7268.0914885639295;7288.963555182402;7477.102747670315;7604.087509498895;7014.473914456538;7015.36861682451;7110.217876033736;7126;7144.464165187528;4995.725235112103;5094.6688465249645;5102.311187989574;5109.616778741683;5328.6802791832615;5256.290400795843;5272.920906186334;5289.391719952255;5314.256004159498;5514.062609356339 08:35:27 INFO - PROCESS | 17633 | |4;prototype.html;307.3744929069343;314.3194982862104;319.8496706547925;322.11739832857967;322.21379833206976;5105.744712764356;5205.193195054763;5286.31277933869;5317.9202311965355;5347.41178470368;5178.637495375326;5193.480651934805;5235.450277720832;5237;5249.685018898859;4069.267531844267;4073.5722749111437;4073.877783666495;4092.6930480213955;4101.938470922939;3944.1322908959983;4106.445629839978;4108.732932359387;4110.568390319018;4115.54728979812;4067.776272305037;4070.9389359159636;4074.6129117733767;4076.001379661987;4078.9796051019853;4062.6952978526583;4064.1058967027207;4081.795910204478;4086.9182616347803;4099.1801639672185;3499.475078738184;3562.9109272268142;3593.2094939113613;3602.1354874830113;3612.6026137124895;5054.9494505054845;5078.64449488536;5080.949190508121;5084.389873215201;5115.872103197413;4641.864742218583;4646.884747660571;4655.3947986761505;4674.602658774008;4682.648801339913;5338.279332290169;5356.517913387775;5403.351597808249;5471.699056551888;5489.094299440588;3911.2764138634443;3923.333033384303;3924.058226025762;3933.0953880607335;3935.0949281665376;5650.6892120933335;5656.151577263402;5670.461306175906;5719.027765279912;5758.884822303572;3939;3967.781772002568;3977.900552486211;4081.20416518776;4118.217538667644;4100.097978444797;4148.979255103705;4149.502059752849;4160.396742472324;4183.477065366829;4713.929291060636;4734.928976065362;4737.597304229179;4762.499937506581;4776.116418462596;5295.7087360195555;5520.089185284383;5536.833894983157;5552.472515111058;5573.860653483614;1377.793331000338;1417.7235439089484;1425.7932599772976;1432.534426311453;1452.3609611770787;939.7885475767897;973.8101070291225;977.6117912564176;991.0436428846198;995.9701208963743;5177.611679124081;5189.481051894856;5199.948000519947;5230.607704422184;5239.73801309933;5474.534664553478;5529.834104976857;5542.032578434598;5578.693171875586;5661.405552417019;5007.223880298559;5172.120739474299;5216.504432078947;5218.947810521846;5265.657732247391;2930.5164647833294;2967.759341697005;2974.1375001249826;2992.192108130836;2999.7750168737434;5834.387389324144;5837.760043175993;5838.970805146032;5841.561882858802;5859.121131830174;5646.689432081275;5698;5702.287214098238;5729.312482502127;5758.193852860602;5770.509506691894;5778.797742079007;5788.305403351541;5810.227880496404;5817.447342502455;5329.573634109184;5584.664920104807;5642.266505354278;5647.971760141173;5681.715914204356;5486.478784515464;5565.30433695788;5571.4428557143965;5584.944150558443;5587;5735.225744524602;5796.536277097738;5802.709864506842;5803.361630220588;5805.535557155502;3787.1289603391924;3952.1700442907313;4017.4174744661277;4021.5174179098694;4026.2953983053435;5379.220013098001;5559.6664200148125;5567.747256867173;5595.832125036255;5628.690422026828;5392.973035134799;5498.175273708815;5502.339719233718;5502.81689436773;5508.283923090133;5491.121420572689;5506.944930550643;5512.145617429305;5521.475459831309;5552.861178470409;5278.630495865253;5371.731413429391;5431.185322201543;5486.79290556077;5511.200875873056;5472.616916815785;5509.669419834822;5529.33647962231;5530.723463826712;5534.861628459321;5426.240326354235;5463.180522921593;5493.148561972901;5495.395506494203;5496.148097044965 08:35:27 INFO - PROCESS | 17633 | |5;yui.html;797.0753925446484;808.6522795198065;822.5147163173734;838.8825564420978;848.448508469495;13593.66015849603;14203.928980355084;14556.781648275271;14559.490417835377;15879.364825407;14042.297885105729;14326.068805527633;14354.354054067566;14386.856131438682;14420.567382978501;668.7325069972026;801.7193982106274;810.0117856215408;810.9918900811005;811.0348685064754;797.9680812767482;830.4021104804531;830.489249111795;831.6673330667751;833.5582141465034;857.4298091768998;862.7282406042108;863.4301361101674;864.4294765454802;864.6714248585529;694.3091623834267;852.0201767966826;855.2217482091298;855.4995327733284;856.4518708026868;602.8944934636421;616.294342977293;618.542278713753;622.7695752571577;623.4233334165879;14330.211838348887;14354.354054067591;14370.13779173253;14385.568432947028;14387.496437624633;13079.149855259378;13367.598972030757;13807.309634518233;13831.377588008463;13865.792013119812;11906.821397679041;12091.395430228453;12232.449539770643;12548.309842958633;12793.616191514177;4095.795210239506;4136.400221967798;4184.4351012613215;4215.241256573816;4234.640055595247;68865.65567172182;70754.64622676854;70949;80367.5981620094;80688.59655701743;10793.136549076055;11012.39431831241;11051.502682379312;11252.66242012741;11363.943180284046;18502.722459163124;18590.628187436174;18600.906995464935;18644.161012754186;18651.067446627614;8185.508869467851;8235.629396677165;8239.546824924566;8334.6666133354;8758.255548278468;4424.75663838492;4472.08322293929;4497.595216430537;4559.498455169929;4619.3071039343695;3283.540304357392;3384.7461440392067;3387.6442973487924;3415.573053368329;3416.590009198912;604.9122877182872;605.6638565596137;606.5753972219464;607.4108115128319;622.4242575617536;748.6256871564218;749.5015814483417;787.3347021766598;795.2087672765635;818.5661599352353;8655.52394618302;9474.95262523683;9742.95128524353;9781.608735650636;9932.255080868963;720.204174387305;724.0478770416885;736.1938677148514;745.1468069060895;747.7756672998079;4475.098083314579;4691.554302341272;4710.426622427995;4741.810327586927;4772.49888761682;109423;110154.44922775496;114901;118675;119961;61980.66135370481;81844.18155818485;99715.50142249388;101376.49311753541;104159.47920260501;75717.24282757212;77802.61098694583;78654.99999999885;84676.57661711652;89425;8841.381103322963;8993.730188094367;9243.537823108953;9382.296327775308;9452.43285402878;51938;52807;53328.73335633297;53855.73072134614;54846;41491.58508414877;69136.65431672809;73943.26056739363;74889.25110748822;76412.23587764274;11975;12471.127021108436;12498.437570309175;12504.124711270124;12863.485460581473;13931.930340348234;13971.301434928417;14686.853131468975;14691.191984440959;14752.262386880828;12767.61697149087;12835.35823208817;12930.460780516041;13547.661308467366;13599.320033998458;13125.409356579168;13177.868221318047;13589.184648921097;14032.438702451787;14174.787378189129;13231.735365292448;13678.179309241477;13702.794458083335;14012.439502420197;14133.505327313494;10082.949585252027;10166.390016599027;10838.132949363875;10862.13102951746;11151.72120696989;10314.226433017404;10554.68335949892;11316.15128865322;11510.712232194262;11662.94168529152 08:35:27 INFO - PROCESS | 17633 | __end_tp_report 08:35:27 INFO - PROCESS | 17633 | __start_cc_report 08:35:27 INFO - PROCESS | 17633 | _x_x_mozilla_cycle_collect,1043 08:35:27 INFO - PROCESS | 17633 | __end_cc_report 08:35:27 INFO - PROCESS | 17633 | __startTimestamp1469115327149__endTimestamp 08:35:27 INFO - PROCESS | 17633 | 08:35:27 INFO - PROCESS | 17633 | ------- Summary: start ------- 08:35:27 INFO - PROCESS | 17633 | Number of tests: 6 08:35:27 INFO - PROCESS | 17633 | 08:35:27 INFO - PROCESS | 17633 | [#0] dojo.html Cycles:165 Average:5381.97 Median:5633.44 stddev:624.77 (11.1%) stddev-sans-first:598.84 08:35:27 INFO - PROCESS | 17633 | Values: 3030.7 3030.9 3062.4 3066.2 3074.5 5304.3 5323.7 5327.3 5407.4 5458.8 5375.7 5400.9 5413.8 5470.5 5507.9 4824.1 4906.5 4917.3 4920.7 5081.1 4988.6 5031.8 5046.0 5064.3 5065.2 4824.3 4839.7 4842.1 4856.3 4960.9 4856.8 4864.3 4886.2 4968.5 4984.2 4866.0 4895.2 4905.6 4927.6 4948.8 5307.8 5322.3 5398.8 5418.6 5453.6 5770.3 5850.7 5858.1 5862.2 5866.9 5817.4 5836.9 5880.8 5880.8 5890.2 5242.8 5295.0 5342.1 5500.7 5501.3 5894.9 5896.5 5929.5 5938.9 5954.8 5626.8 5633.4 5679.8 5681.1 5681.2 5597.6 5692.7 5728.1 5785.6 5786.2 5741.4 5744.1 5744.6 5783.3 5795.0 5671.3 5718.2 5729.9 5731.6 5749.6 4312.0 4349.7 4353.7 4394.4 4402.1 3925.1 3942.5 3987.0 3993.5 4029.5 5451.7 5455.2 5460.5 5461.6 5462.2 5670.5 5736.0 5820.2 5828.3 5870.5 5405.9 5447.0 5454.4 5486.1 5498.6 5019.0 5024.9 5025.6 5031.7 5074.2 5820.0 5959.5 5969.1 6012.7 6036.5 5821.4 5856.0 5870.7 5910.9 5913.6 6009.2 6027.8 6028.7 6030.0 6040.1 5538.8 5659.5 5713.1 5756.5 5759.5 5798.6 5822.2 5839.1 5874.5 5876.9 5675.3 5708.3 5708.5 5725.0 5731.2 5563.2 5635.2 5718.1 5725.7 5728.8 5606.3 5664.6 5665.5 5667.5 5721.0 5748.2 5751.1 5763.6 5815.5 5816.8 5383.4 5503.5 5640.9 5649.4 5650.4 08:35:27 INFO - PROCESS | 17633 | 08:35:27 INFO - PROCESS | 17633 | [#1] ext.html Cycles:180 Average:64299.28 Median:44549.55 stddev:127174.86 (285.5%) stddev-sans-first:127441.68 08:35:27 INFO - PROCESS | 17633 | Values: 598.2 615.2 635.7 637.0 643.6 30921.1 45068.3 45588.5 45608.5 45667.5 46017.0 46370.5 46488.5 46534.3 46542.3 8988.2 9108.4 9110.2 9139.2 9141.9 8959.6 9032.8 9060.1 9063.4 9078.7 9079.6 9136.8 9143.0 9144.0 9146.9 9065.2 9099.4 9111.6 9112.4 9118.7 8913.2 8970.6 8971.5 8975.2 8978.9 43023.4 43196.0 43347.8 43354.3 43397.0 3371.7 3434.1 4038.1 4185.7 4255.3 32398.8 33055.5 33346.3 33561.2 34523.5 4432.9 4549.2 4551.5 4596.2 4703.3 365370.0 366032.0 367030.2 368035.0 369743.0 60109.7 60352.7 60455.7 60486.4 60589.7 63422.7 63625.4 63714.0 63743.7 63745.7 15016.7 15035.3 15055.9 15220.9 15238.2 11447.7 11614.0 11621.5 11632.2 11634.5 14999.9 17007.6 17074.7 17078.0 17127.3 6287.7 6343.4 6390.9 6510.8 6574.3 747.6 1049.7 1072.9 1078.1 1109.3 33795.0 34441.0 34596.3 34686.1 34717.5 1086.7 1139.1 1142.8 1159.3 1168.9 17501.9 17737.9 17839.4 17863.8 17933.9 518244.0 520986.0 521554.0 524331.0 527065.0 61127.1 61338.4 61362.4 61409.1 61411.4 1236.3 1363.5 1371.8 1373.2 1405.2 31344.2 35375.8 36155.0 36320.8 36378.5 53775.2 54431.0 54513.2 54635.5 54646.7 525393.0 529821.0 530298.0 535185.0 535253.0 39282.0 44520.3 45267.5 45945.0 49236.0 49550.0 50031.2 50520.5 50862.5 50976.7 42186.8 42223.2 42559.6 42799.0 44354.6 42725.6 42962.6 43440.8 44191.8 44578.8 42496.6 43456.3 44209.8 44511.3 44595.0 35686.8 38863.2 39184.0 39193.6 39597.6 29151.4 29386.4 29629.3 29670.9 29776.6 08:35:27 INFO - PROCESS | 17633 | 08:35:27 INFO - PROCESS | 17633 | [#2] jquery.html Cycles:180 Average:30634.84 Median:33343.83 stddev:79513.53 (238.5%) stddev-sans-first:79704.11 08:35:27 INFO - PROCESS | 17633 | Values: 370.3 373.2 374.5 379.6 392.2 19229.4 20289.5 20989.5 21012.0 21139.9 11536.4 11861.5 11866.5 11882.3 14335.6 5317.4 5556.0 5560.4 5561.7 5562.6 4045.1 5441.8 7401.6 7854.6 7926.6 7663.5 7863.1 7897.6 8041.8 8043.6 7593.7 7773.5 7788.2 7789.0 7804.4 7558.9 7630.4 7631.0 7632.3 7634.6 25741.2 26517.3 26581.0 26941.5 26983.9 2311.3 2463.6 2890.1 3371.9 3494.5 16156.8 16444.4 16563.3 16807.0 17084.7 2040.6 2127.8 2131.2 2160.8 2192.2 103402.0 104126.5 104979.5 105627.5 106270.5 17781.7 18686.1 18832.0 18846.8 18868.7 33226.5 33253.7 33434.0 33466.7 33489.5 8872.0 9268.8 9685.5 9722.4 9934.7 7861.0 7984.7 8140.5 8190.5 8233.6 8584.2 8888.0 9080.6 9241.9 9341.9 2897.4 3389.4 3483.7 3545.2 3636.9 922.0 1022.2 1038.6 1090.1 1099.5 19803.6 20257.8 20345.9 20404.9 20651.8 974.5 978.1 999.0 1009.0 1014.0 10313.7 11150.7 11172.8 11208.6 11234.4 468617.0 479774.0 482537.0 483353.0 485266.0 87813.0 88979.0 90409.0 90859.1 91156.0 980.8 1018.7 1041.6 1052.3 1059.1 17207.7 17645.2 17984.6 18001.6 18084.1 43825.1 43842.6 43981.6 44912.6 45238.8 58493.0 58650.0 59510.1 60572.7 60920.4 6569.0 7528.3 8170.9 8175.3 8346.1 10420.5 12595.9 12799.2 12825.4 13286.1 11220.6 11307.0 11543.0 11591.7 11707.5 11194.2 11255.2 11269.4 11641.1 11769.4 10749.7 11237.6 11243.2 11894.2 11944.7 7156.6 7470.8 7489.2 7828.2 8442.5 10420.3 11259.7 11326.3 11394.2 11836.9 08:35:27 INFO - PROCESS | 17633 | 08:35:27 INFO - PROCESS | 17633 | [#3] mootools.html Cycles:180 Average:5783.76 Median:4724.53 stddev:3266.28 (69.1%) stddev-sans-first:3258.08 08:35:27 INFO - PROCESS | 17633 | Values: 1302.9 1487.6 1520.7 1652.8 1802.2 8363.5 8826.7 8888.2 9066.8 9158.4 8974.2 9035.0 9071.2 9075.3 9091.8 2058.2 2252.0 2355.0 2372.8 2376.8 2396.9 2399.8 2400.6 2405.8 2407.3 2304.7 2320.2 2337.5 2338.5 2343.6 2003.5 2250.3 2305.2 2315.2 2315.4 2356.9 2365.0 2369.4 2370.1 2371.5 8709.1 8710.4 8727.0 8782.5 8804.5 5205.8 5338.4 5339.6 5341.6 5366.9 3208.9 3320.0 3381.2 3383.4 3387.5 1053.9 1088.3 1111.8 1120.7 1127.5 12228.3 12233.6 12329.1 12367.4 12368.2 4538.1 4720.9 4739.5 4750.2 4762.5 10436.4 10505.0 10555.9 10569.3 10603.5 5160.8 5162.9 5166.3 5173.6 5177.7 4728.2 4728.2 4857.4 4900.5 4985.0 4116.1 4138.3 4184.5 4211.8 4237.2 1278.4 1295.5 1301.1 1301.8 1308.7 1019.5 1034.2 1044.9 1047.2 1060.2 6054.3 6141.9 6202.4 6351.8 6362.3 461.1 462.1 466.0 467.6 477.3 4218.9 4277.7 4332.2 4418.3 4467.8 10422.2 10846.5 10853.0 10863.2 10879.3 10183.2 10518.8 10532.2 10578.0 10725.5 7807.5 8010.6 8019.5 8025.8 8042.2 6259.3 6364.9 6418.9 6449.6 6601.1 10178.6 10218.3 10226.0 10319.8 10339.5 9008.8 9533.4 9718.7 9771.1 9989.6 7240.6 7387.5 7487.8 7572.3 7595.6 8156.1 8249.2 8301.0 8304.2 8375.3 7094.8 7245.3 7454.3 7456.6 7476.3 7240.3 7268.1 7289.0 7477.1 7604.1 7014.5 7015.4 7110.2 7126.0 7144.5 4995.7 5094.7 5102.3 5109.6 5328.7 5256.3 5272.9 5289.4 5314.3 5514.1 08:35:27 INFO - PROCESS | 17633 | 08:35:27 INFO - PROCESS | 17633 | [#4] prototype.html Cycles:180 Average:4620.59 Median:5257.67 stddev:1352.22 (25.7%) stddev-sans-first:1316.69 08:35:27 INFO - PROCESS | 17633 | Values: 307.4 314.3 319.8 322.1 322.2 5105.7 5205.2 5286.3 5317.9 5347.4 5178.6 5193.5 5235.5 5237.0 5249.7 4069.3 4073.6 4073.9 4092.7 4101.9 3944.1 4106.4 4108.7 4110.6 4115.5 4067.8 4070.9 4074.6 4076.0 4079.0 4062.7 4064.1 4081.8 4086.9 4099.2 3499.5 3562.9 3593.2 3602.1 3612.6 5054.9 5078.6 5080.9 5084.4 5115.9 4641.9 4646.9 4655.4 4674.6 4682.6 5338.3 5356.5 5403.4 5471.7 5489.1 3911.3 3923.3 3924.1 3933.1 3935.1 5650.7 5656.2 5670.5 5719.0 5758.9 3939.0 3967.8 3977.9 4081.2 4118.2 4100.1 4149.0 4149.5 4160.4 4183.5 4713.9 4734.9 4737.6 4762.5 4776.1 5295.7 5520.1 5536.8 5552.5 5573.9 1377.8 1417.7 1425.8 1432.5 1452.4 939.8 973.8 977.6 991.0 996.0 5177.6 5189.5 5199.9 5230.6 5239.7 5474.5 5529.8 5542.0 5578.7 5661.4 5007.2 5172.1 5216.5 5218.9 5265.7 2930.5 2967.8 2974.1 2992.2 2999.8 5834.4 5837.8 5839.0 5841.6 5859.1 5646.7 5698.0 5702.3 5729.3 5758.2 5770.5 5778.8 5788.3 5810.2 5817.4 5329.6 5584.7 5642.3 5648.0 5681.7 5486.5 5565.3 5571.4 5584.9 5587.0 5735.2 5796.5 5802.7 5803.4 5805.5 3787.1 3952.2 4017.4 4021.5 4026.3 5379.2 5559.7 5567.7 5595.8 5628.7 5393.0 5498.2 5502.3 5502.8 5508.3 5491.1 5506.9 5512.1 5521.5 5552.9 5278.6 5371.7 5431.2 5486.8 5511.2 5472.6 5509.7 5529.3 5530.7 5534.9 5426.2 5463.2 5493.1 5495.4 5496.1 08:35:27 INFO - PROCESS | 17633 | 08:35:27 INFO - PROCESS | 17633 | [#5] yui.html Cycles:180 Average:19974.83 Median:4486.35 stddev:28813.26 (642.2%) stddev-sans-first:28858.11 08:35:27 INFO - PROCESS | 17633 | Values: 797.1 808.7 822.5 838.9 848.4 13593.7 14203.9 14556.8 14559.5 15879.4 14042.3 14326.1 14354.4 14386.9 14420.6 668.7 801.7 810.0 811.0 811.0 798.0 830.4 830.5 831.7 833.6 857.4 862.7 863.4 864.4 864.7 694.3 852.0 855.2 855.5 856.5 602.9 616.3 618.5 622.8 623.4 14330.2 14354.4 14370.1 14385.6 14387.5 13079.1 13367.6 13807.3 13831.4 13865.8 11906.8 12091.4 12232.4 12548.3 12793.6 4095.8 4136.4 4184.4 4215.2 4234.6 68865.7 70754.6 70949.0 80367.6 80688.6 10793.1 11012.4 11051.5 11252.7 11363.9 18502.7 18590.6 18600.9 18644.2 18651.1 8185.5 8235.6 8239.5 8334.7 8758.3 4424.8 4472.1 4497.6 4559.5 4619.3 3283.5 3384.7 3387.6 3415.6 3416.6 604.9 605.7 606.6 607.4 622.4 748.6 749.5 787.3 795.2 818.6 8655.5 9475.0 9743.0 9781.6 9932.3 720.2 724.0 736.2 745.1 747.8 4475.1 4691.6 4710.4 4741.8 4772.5 109423.0 110154.4 114901.0 118675.0 119961.0 61980.7 81844.2 99715.5 101376.5 104159.5 75717.2 77802.6 78655.0 84676.6 89425.0 8841.4 8993.7 9243.5 9382.3 9452.4 51938.0 52807.0 53328.7 53855.7 54846.0 41491.6 69136.7 73943.3 74889.3 76412.2 11975.0 12471.1 12498.4 12504.1 12863.5 13931.9 13971.3 14686.9 14691.2 14752.3 12767.6 12835.4 12930.5 13547.7 13599.3 13125.4 13177.9 13589.2 14032.4 14174.8 13231.7 13678.2 13702.8 14012.4 14133.5 10082.9 10166.4 10838.1 10862.1 11151.7 10314.2 10554.7 11316.2 11510.7 11662.9 08:35:27 INFO - PROCESS | 17633 | -------- Summary: end -------- 08:35:27 INFO - PROCESS | 17633 | 08:35:27 INFO - TEST-INFO | 17633: exit 0 08:35:27 INFO - TEST-OK | dromaeo_css | took 1114544ms 08:35:27 INFO - TEST-START | kraken 08:35:27 INFO - operating with platform_type : linux_ 08:35:27 INFO - Initialising browser for kraken test... 08:35:27 INFO - TEST-INFO | started process 17823 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFT6W_2/profile http://localhost:35967/getInfo.html) 08:35:31 INFO - PROCESS | 17823 | __metrics Screen width/height:1600/1200 08:35:31 INFO - PROCESS | 17823 | colorDepth:24 08:35:31 INFO - PROCESS | 17823 | Browser inner width/height: 1024/697 08:35:31 INFO - PROCESS | 17823 | __metrics 08:35:32 INFO - PROCESS | 17823 | 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" 08:35:32 INFO - PROCESS | 17823 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:35:33 INFO - PROCESS | 17823 | console.error: PushService: 08:35:33 INFO - PROCESS | 17823 | stateChangeProcessEnqueue: Error transitioning state 08:35:33 INFO - PROCESS | 17823 | UnknownError 08:35:33 INFO - TEST-INFO | 17823: exit 0 08:35:33 INFO - Browser initialized. 08:35:33 INFO - Running cycle 1/1 for kraken test... 08:35:33 INFO - TEST-INFO | started process 17902 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFT6W_2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:35:45 INFO - PROCESS | 17902 | RSS: Main: 157302784 08:35:45 INFO - PROCESS | 17902 | 08:35:48 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35967/tests/kraken/driver.html?testName=audio-beat-detection) 08:35:48 INFO - PROCESS | 17902 | RSS: Main: 213655552 08:35:48 INFO - PROCESS | 17902 | 08:35:51 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35967/tests/kraken/driver.html?testName=audio-dft) 08:35:52 INFO - PROCESS | 17902 | RSS: Main: 192839680 08:35:52 INFO - PROCESS | 17902 | 08:35:55 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35967/tests/kraken/driver.html?testName=audio-fft) 08:35:55 INFO - PROCESS | 17902 | RSS: Main: 269729792 08:35:55 INFO - PROCESS | 17902 | 08:35:57 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35967/tests/kraken/driver.html?testName=audio-oscillator) 08:35:58 INFO - PROCESS | 17902 | RSS: Main: 180031488 08:35:58 INFO - PROCESS | 17902 | 08:36:00 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35967/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:36:01 INFO - PROCESS | 17902 | RSS: Main: 184705024 08:36:01 INFO - PROCESS | 17902 | 08:36:04 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35967/tests/kraken/driver.html?testName=imaging-darkroom) 08:36:04 INFO - PROCESS | 17902 | RSS: Main: 219680768 08:36:04 INFO - PROCESS | 17902 | 08:36:08 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35967/tests/kraken/driver.html?testName=imaging-desaturate) 08:36:08 INFO - PROCESS | 17902 | RSS: Main: 220250112 08:36:08 INFO - PROCESS | 17902 | 08:36:11 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35967/tests/kraken/driver.html?testName=json-parse-financial) 08:36:12 INFO - PROCESS | 17902 | RSS: Main: 220528640 08:36:12 INFO - PROCESS | 17902 | 08:36:13 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35967/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:36:14 INFO - PROCESS | 17902 | RSS: Main: 184537088 08:36:14 INFO - PROCESS | 17902 | 08:36:16 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35967/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:36:16 INFO - PROCESS | 17902 | RSS: Main: 189091840 08:36:16 INFO - PROCESS | 17902 | 08:36:18 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35967/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:36:19 INFO - PROCESS | 17902 | RSS: Main: 195248128 08:36:19 INFO - PROCESS | 17902 | 08:36:22 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35967/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:36:22 INFO - PROCESS | 17902 | RSS: Main: 189825024 08:36:22 INFO - PROCESS | 17902 | 08:36:25 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35967/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:36:25 INFO - PROCESS | 17902 | RSS: Main: 193425408 08:36:25 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | Cycle 1(1): loaded http://localhost:35967/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35967/tests/kraken/driver.html?testName=ai-astar) 08:36:27 INFO - PROCESS | 17902 | RSS: Main: 244084736 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | __start_tp_report 08:36:27 INFO - PROCESS | 17902 | _x_x_mozilla_page_load 08:36:27 INFO - PROCESS | 17902 | _x_x_mozilla_page_load_details 08:36:27 INFO - PROCESS | 17902 | |i|pagename|runs| 08:36:27 INFO - PROCESS | 17902 | |0;ai-astar;104;112;112;110;113;112;111;112;110;109 08:36:27 INFO - PROCESS | 17902 | |1;audio-beat-detection;151;119;123;181;119;118;142;116;152;115 08:36:27 INFO - PROCESS | 17902 | |2;audio-dft;160;161;159;160;161;160;161;161;160;162 08:36:27 INFO - PROCESS | 17902 | |3;audio-fft;74;74;76;114;74;76;75;75;105;75 08:36:27 INFO - PROCESS | 17902 | |4;audio-oscillator;84;84;133;83;82;84;129;83;84;124 08:36:27 INFO - PROCESS | 17902 | |5;imaging-gaussian-blur;116;118;116;116;117;116;117;116;117;117 08:36:27 INFO - PROCESS | 17902 | |6;imaging-darkroom;146;146;145;146;147;147;146;146;145;146 08:36:27 INFO - PROCESS | 17902 | |7;imaging-desaturate;95;96;95;96;96;95;95;96;96;95 08:36:27 INFO - PROCESS | 17902 | |8;json-parse-financial;50;50;59;51;52;51;52;50;49;49 08:36:27 INFO - PROCESS | 17902 | |9;json-stringify-tinderbox;62;62;63;62;64;64;63;63;63;63 08:36:27 INFO - PROCESS | 17902 | |10;stanford-crypto-aes;82;82;74;75;75;80;75;84;75;80 08:36:27 INFO - PROCESS | 17902 | |11;stanford-crypto-ccm;135;120;121;120;134;132;118;119;201;128 08:36:27 INFO - PROCESS | 17902 | |12;stanford-crypto-pbkdf2;159;155;156;156;162;157;159;154;159;156 08:36:27 INFO - PROCESS | 17902 | |13;stanford-crypto-sha256-iterative;62;63;65;62;63;66;65;68;62;63 08:36:27 INFO - PROCESS | 17902 | __end_tp_report 08:36:27 INFO - PROCESS | 17902 | __start_cc_report 08:36:27 INFO - PROCESS | 17902 | _x_x_mozilla_cycle_collect,1707 08:36:27 INFO - PROCESS | 17902 | __end_cc_report 08:36:27 INFO - PROCESS | 17902 | __startTimestamp1469115387835__endTimestamp 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | ------- Summary: start ------- 08:36:27 INFO - PROCESS | 17902 | Number of tests: 14 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#0] ai-astar Cycles:10 Average:110.50 Median:112.00 stddev:2.59 (2.3%) stddev-sans-first:1.30 08:36:27 INFO - PROCESS | 17902 | Values: 104.0 112.0 112.0 110.0 113.0 112.0 111.0 112.0 110.0 109.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:132.50 stddev:22.10 (16.7%) stddev-sans-first:22.53 08:36:27 INFO - PROCESS | 17902 | Values: 151.0 119.0 123.0 181.0 119.0 118.0 142.0 116.0 152.0 115.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 08:36:27 INFO - PROCESS | 17902 | Values: 160.0 161.0 159.0 160.0 161.0 160.0 161.0 161.0 160.0 162.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.00 stddev:14.77 (19.7%) stddev-sans-first:15.39 08:36:27 INFO - PROCESS | 17902 | Values: 74.0 74.0 76.0 114.0 74.0 76.0 75.0 75.0 105.0 75.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:83.50 stddev:21.96 (26.3%) stddev-sans-first:22.79 08:36:27 INFO - PROCESS | 17902 | Values: 84.0 84.0 133.0 83.0 82.0 84.0 129.0 83.0 84.0 124.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 08:36:27 INFO - PROCESS | 17902 | Values: 116.0 118.0 116.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 08:36:27 INFO - PROCESS | 17902 | Values: 146.0 146.0 145.0 146.0 147.0 147.0 146.0 146.0 145.0 146.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:36:27 INFO - PROCESS | 17902 | Values: 95.0 96.0 95.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.00 stddev:2.91 (5.7%) stddev-sans-first:3.05 08:36:27 INFO - PROCESS | 17902 | Values: 50.0 50.0 59.0 51.0 52.0 51.0 52.0 50.0 49.0 49.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#9] json-stringify-tinderbox Cycles:10 Average:62.90 Median:63.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 08:36:27 INFO - PROCESS | 17902 | Values: 62.0 62.0 63.0 62.0 64.0 64.0 63.0 63.0 63.0 63.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:80.00 stddev:3.77 (4.7%) stddev-sans-first:3.73 08:36:27 INFO - PROCESS | 17902 | Values: 82.0 82.0 74.0 75.0 75.0 80.0 75.0 84.0 75.0 80.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#11] stanford-crypto-ccm Cycles:10 Average:132.80 Median:130.00 stddev:24.85 (19.1%) stddev-sans-first:26.34 08:36:27 INFO - PROCESS | 17902 | Values: 135.0 120.0 121.0 120.0 134.0 132.0 118.0 119.0 201.0 128.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.30 Median:158.00 stddev:2.41 (1.5%) stddev-sans-first:2.47 08:36:27 INFO - PROCESS | 17902 | Values: 159.0 155.0 156.0 156.0 162.0 157.0 159.0 154.0 159.0 156.0 08:36:27 INFO - PROCESS | 17902 | 08:36:27 INFO - PROCESS | 17902 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:2.02 (3.2%) stddev-sans-first:2.03 08:36:27 INFO - PROCESS | 17902 | Values: 62.0 63.0 65.0 62.0 63.0 66.0 65.0 68.0 62.0 63.0 08:36:27 INFO - PROCESS | 17902 | -------- Summary: end -------- 08:36:27 INFO - PROCESS | 17902 | 08:36:28 INFO - TEST-INFO | 17902: exit 0 08:36:28 INFO - TEST-OK | kraken | took 60955ms 08:36:28 INFO - SUITE-END | took 1175s 08:36:28 INFO - Completed test suite (00:19:35) 08:36:28 INFO - javascript benchmark 08:36:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3030.666626671067, 3030.909072727818, 3062.433449811785, 3066.1874603230135, 3074.523448865425, 5304.257403963448, 5323.653962492435, 5327.334083239595, 5407.351117865852, 5458.7543560539825, 5375.704336261514, 5400.945990540094, 5413.810516631918, 5470.507654311111, 5507.917381239285, 4824.107540105092, 4906.509349065101, 4917.311576379309, 4920.655554111214, 5081.136206844845, 4988.625853061017, 5031.798728050873, 5046.041252162096, 5064.316317297173, 5065.189569668853, 4824.300476430898, 4839.685420447659, 4842.055799119174, 4856.320115183859, 4960.851174464772, 4856.781444834991, 4864.294677271776, 4886.169351210267, 4968.453470118284, 4984.152694042022, 4866.0, 4895.2167653175675, 4905.607551395879, 4927.605791536705, 4948.802047918079, 5307.814226502054, 5322.2548843161985, 5398.838034858961, 5418.593605479566, 5453.618246722731, 5770.336411312669, 5850.707464626752, 5858.12128180772, 5862.20860183874, 5866.882662346729, 5817.418258174149, 5836.854078648025, 5880.794172203995, 5880.8235752927485, 5890.175375447441, 5242.79028838846, 5295.046891559479, 5342.091844386464, 5500.669959802384, 5501.312335958005, 5894.852628684232, 5896.498797602166, 5929.466348028698, 5938.910916336258, 5954.76180952757, 5626.803061892896, 5633.436656334416, 5679.801206957819, 5681.147827825859, 5681.176229446707, 5597.608167428241, 5692.743826527816, 5728.140778883201, 5785.623934444247, 5786.218860453869, 5741.425857414225, 5744.052231381859, 5744.626599271034, 5783.334916484574, 5795.0, 5671.291088613923, 5718.228039214736, 5729.856753581111, 5731.570132240098, 5749.597528172988, 4311.9784401077795, 4349.652027837766, 4353.651707863363, 4394.406755088086, 4402.075564131568, 3925.0579860833195, 3942.5466071402157, 3987.043109653662, 3993.4608827808456, 4029.4560234367987, 5451.700156491352, 5455.236266922555, 5460.453954604508, 5461.617686761968, 5462.2079798429, 5670.5180059695385, 5735.971320143456, 5820.214271073351, 5828.329742079631, 5870.471657550841, 5405.891882162335, 5446.972765136228, 5454.372747134051, 5486.1222204447895, 5498.615096943176, 5018.974905125451, 5024.949750502448, 5025.572826309804, 5031.6729412588065, 5074.238864170404, 5820.039693450618, 5959.463648271676, 5969.104634304889, 6012.699365031818, 6036.456718895232, 5821.388754180919, 5855.970720146372, 5870.735816888336, 5910.852228694317, 5913.556483263858, 6009.2188015557695, 6027.849303767265, 6028.668423236764, 6030.005049167014, 6040.063790112364, 5538.806141785019, 5659.462351076641, 5713.05734553794, 5756.481916627524, 5759.510441612342, 5798.59409841307, 5822.155787410717, 5839.0949402842625, 5874.500667443315, 5876.853078672896, 5675.347335056356, 5708.286464191976, 5708.4862362387585, 5725.0267454531995, 5731.19763233156, 5563.193336966036, 5635.239242702182, 5718.142278658234, 5725.6850873202375, 5728.770849165987, 5606.299212598426, 5664.603477756682, 5665.546756259407, 5667.518260947865, 5721.0, 5748.223989761329, 5751.1373294006235, 5763.5965482417505, 5815.505682017076, 5816.767329306949, 5383.380911195262, 5503.477169668875, 5640.85897852557, 5649.406812284651, 5650.378458369494], "value": 5338.375887193781, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.2432223237603, 615.2125279642058, 635.6853357587995, 636.9745210191589, 643.62991280014, 30921.072367828972, 45068.32397514036, 45588.54411455884, 45608.54391456085, 45667.54332456679, 46017.0, 46370.536294637044, 46488.53511464885, 46534.30198547024, 46542.30186547204, 8988.235999940012, 9108.407953483002, 9110.180083792457, 9139.223166030863, 9141.908580914172, 8959.5968181432, 9032.819343613124, 9060.139286767746, 9063.410878292923, 9078.682246121387, 9079.636814527412, 9136.771580710501, 9142.954285228565, 9143.954280228589, 9146.862797058016, 9065.18413342799, 9099.408538444979, 9111.635534578609, 9112.407693499901, 9118.680846170419, 8913.153250441197, 8970.596323165408, 8971.506567138806, 8975.192232699088, 8978.86531702025, 43023.35464968028, 43196.0, 43347.78326108381, 43354.34968475475, 43397.0, 3371.7471189660628, 3434.0899661589697, 4038.1318016626565, 4185.686073544496, 4255.255330317213, 32398.83800580982, 33055.50416743751, 33346.3330733384, 33561.160970975674, 34523.48214776755, 4432.911341773179, 4549.158405694924, 4551.527567471273, 4596.218642830727, 4703.294505824119, 365370.0, 366032.0, 367030.1648491767, 368035.0, 369743.0, 60109.69945150334, 60352.698236508535, 60455.69772151111, 60486.39513604896, 60589.697051515344, 63422.68288658527, 63625.36374636287, 63714.0, 63743.68128159329, 63745.68127159335, 15016.699666006618, 15035.323410446337, 15055.924720376328, 15220.923895380452, 15238.238088095552, 11447.713807154722, 11614.0, 11621.53513859453, 11632.243904146204, 11634.53461861533, 14999.925000375146, 17007.574810629834, 17074.74387884158, 17078.0, 17127.314907403565, 6287.748490060347, 6343.429091381798, 6390.904136437957, 6510.804675859732, 6574.276829548746, 747.6488023317165, 1049.7323182588543, 1072.9302595331278, 1078.1321036565482, 1109.2789686703545, 33795.0, 34441.0, 34596.30807383838, 34686.13284667904, 34717.47923781145, 1086.7283179205199, 1139.128566646516, 1142.7600203957263, 1159.287038471351, 1168.865580458241, 17501.912490437466, 17737.911310443364, 17839.375621853174, 17863.821361786217, 17933.910330448263, 518244.0, 520986.0, 521554.0, 524331.0, 527065.0, 61127.08309375363, 61338.38661613327, 61362.38637613656, 61409.07886381708, 61411.38588614146, 1236.258245052961, 1363.5091367107834, 1371.766799644063, 1373.1623709537175, 1405.1569058564983, 31344.216394589406, 35375.82312088526, 36155.0, 36320.81839590785, 36378.45432318465, 53775.19337209867, 54431.0, 54513.18230226471, 54635.453645463036, 54646.726766365915, 525393.0, 529821.0, 530298.0, 535185.0, 535253.0, 39282.0, 44520.33219501645, 45267.547324526335, 45945.0, 49236.0, 49550.0, 50031.24953125633, 50520.49479505305, 50862.49137508726, 50976.74511627418, 42186.78906605447, 42223.15553688847, 42559.57440425556, 42799.004069716066, 44354.55645443504, 42725.57274427216, 42962.57037429586, 43440.78279608582, 44191.779041104586, 44578.77710611426, 42496.5750342505, 43456.34815477707, 44209.778951105036, 44511.332330014426, 44595.0, 35686.821565892, 38863.2227355457, 39183.97810672969, 39193.608063919, 39597.60402396054, 29151.416971660023, 29386.412271754874, 29629.25926851846, 29670.851645741634, 29776.553351700175], "value": 20896.456562064737, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.3112211287006, 373.2497679663875, 374.52248383311286, 379.5729803970533, 392.23318412503556, 19229.423117306487, 20289.49276268094, 20989.47526311843, 21011.99999999998, 21139.894300528515, 11536.423178841045, 11861.52553897843, 11866.466009029593, 11882.287062776219, 14335.569932902004, 5317.415084340729, 5556.0, 5560.443955604427, 5561.749721262553, 5562.638428502155, 4045.110075783323, 5441.83674489764, 7401.592912389817, 7854.567998760094, 7926.643301051437, 7663.540187588735, 7863.056433228021, 7897.644605992716, 8041.798955026113, 8043.55760433176, 7593.658285377116, 7773.533587984739, 7788.221177882223, 7789.026371703537, 7804.414668899855, 7558.924410755877, 7630.427717921122, 7631.046119235096, 7632.274933881328, 7634.6182690865235, 25741.227763167135, 26517.3370665733, 26581.0, 26941.46117077667, 26983.865080674666, 2311.318161142467, 2463.5811911975006, 2890.132960111958, 3371.866579672163, 3494.4932984717075, 16156.788240881864, 16444.373452599626, 16563.337466501325, 16807.0, 17084.743728844078, 2040.6020825938947, 2127.7978592033733, 2131.1688441507827, 2160.8163306119, 2192.210804110519, 103401.99999999924, 104126.47936760343, 104979.47510262477, 105627.47186264096, 106270.46864765704, 17781.7332740009, 18686.07592405583, 18832.0, 18846.811531884505, 18868.716969245474, 33226.50160247598, 33253.66746332554, 33434.0, 33466.66533334684, 33489.497657535154, 8871.955640221757, 9268.814623707489, 9685.515724213901, 9722.416655000723, 9934.652287170058, 7861.0, 7984.6806127754235, 8140.470869393471, 8190.467619604707, 8233.629486673113, 8584.184502472255, 8888.0, 9080.636774528944, 9241.861372079424, 9341.90658093424, 2897.406031763526, 3389.4237979543536, 3483.676203042878, 3545.166885781839, 3636.9272614547563, 921.9585118669676, 1022.1567207054061, 1038.55342202852, 1090.0843291635065, 1099.5436893689189, 19803.60392792165, 20257.797422025887, 20345.89827050885, 20404.897975510026, 20651.793482065288, 974.4883935933692, 978.1490103609763, 998.9850152247722, 1008.9545970431349, 1014.0012088093222, 10313.690589282334, 11150.721231969103, 11172.776544469065, 11208.55165793359, 11234.438278086063, 468617.0, 479774.0, 482537.0, 483353.0, 485266.0, 87812.99999999872, 88979.0, 90409.0, 90859.09140908638, 91156.0, 980.7989362180735, 1018.6638409324905, 1041.578160844859, 1052.3422860712055, 1059.0627294843964, 17207.655846882742, 17645.245953791622, 17984.640307194044, 18001.639967200845, 18084.09579521045, 43825.123497530505, 43842.56157438385, 43981.56018439903, 44912.550874492146, 45238.77380613076, 58493.0, 58650.0, 59510.10734838894, 60572.697136514034, 60920.39079609325, 6568.981807819796, 7528.322450979219, 8170.918290817016, 8175.305099066408, 8346.081930987466, 10420.50544544801, 12595.93702031484, 12799.168054076456, 12825.422855971317, 13286.136401133896, 11220.55117795279, 11307.0, 11543.0, 11591.6522504325, 11707.531698732297, 11194.216404851582, 11255.212135150789, 11269.380184089954, 11641.06871450299, 11769.411529423665, 10749.677509674722, 11237.60668376603, 11243.212975091665, 11894.167408281337, 11944.701382465508, 7156.570605763671, 7470.775876723707, 7489.21363256872, 7828.217178282126, 8442.451240669337, 10420.270581059252, 11259.662210133709, 11326.32042077478, 11394.202405831844, 11836.881631183578], "value": 10394.108318504039, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.8501722301937, 1487.6355292953224, 1520.7414739494284, 1652.8264532224116, 1802.161994672478, 8363.498190108598, 8826.735197944065, 8888.244499217575, 9066.818663626724, 9158.358914875962, 8974.192322690957, 9034.960979487361, 9071.228945539637, 9075.319351048682, 9091.77270568236, 2058.238451772846, 2251.9774802252014, 2355.034435881289, 2372.8101751859895, 2376.8217383696206, 2396.8561886286793, 2399.8200134989947, 2400.59189937711, 2405.7714517120844, 2407.2898494944034, 2304.665823455598, 2320.1879342230254, 2337.5324935013064, 2338.462153704649, 2343.5898717724417, 2003.4991252186953, 2250.324902529251, 2305.204704376996, 2315.235972129202, 2315.409570559513, 2356.9410764730846, 2365.0, 2369.4194922243987, 2370.0638247892157, 2371.454565449939, 8709.129087091304, 8710.390272680916, 8726.952765668097, 8782.473051616924, 8804.515751633657, 5205.765740541685, 5338.439463856278, 5339.5728341732565, 5341.626086173969, 5366.892662146735, 3208.855601497938, 3320.0, 3381.1885147564653, 3383.4079036168573, 3387.5257463955068, 1053.9473026348728, 1088.3306766338724, 1111.77208672222, 1120.6974116988367, 1127.5433449452985, 12228.327441990687, 12233.571824986082, 12329.136960412774, 12367.381630918508, 12368.1960672556, 4538.11506756186, 4720.858374248778, 4739.478657347689, 4750.192467280571, 4762.52374762521, 10436.373817570819, 10505.00202480763, 10555.94722026385, 10569.260151789455, 10603.522841472151, 5160.767765450564, 5162.948370516322, 5166.302549155891, 5173.611979101583, 5177.741112944338, 4728.196206644879, 4728.219843725747, 4857.417109946829, 4900.4854490278, 4984.950150498448, 4116.064357774965, 4138.337865941435, 4184.451752851464, 4211.789410529461, 4237.152569486054, 1278.4055414232241, 1295.5077070713069, 1301.0502333296747, 1301.8112373705949, 1308.685915380321, 1019.4953498018529, 1034.2294990232174, 1044.8850626431085, 1047.1622701838498, 1060.236629626683, 6054.273487181566, 6141.877162456726, 6202.4417802398, 6351.777687780906, 6362.331955144643, 461.1169610196503, 462.0966011447679, 465.97204167750044, 467.5698357511098, 477.29122253453596, 4218.873433796991, 4277.657787377002, 4332.176886391639, 4418.33724941255, 4467.776611169428, 10422.21833362501, 10846.511906964206, 10853.0, 10863.239573229797, 10879.347239165676, 10183.236257280585, 10518.842217366746, 10532.210084243712, 10578.0, 10725.463726813628, 7807.531548107132, 8010.559419231999, 8019.478733882279, 8025.839483210303, 8042.195780421911, 6259.311475737574, 6364.87270254583, 6418.935810641834, 6449.580777249652, 6601.075849381186, 10178.592856285963, 10218.335808172445, 10226.0, 10319.793604128026, 10339.534720937425, 9008.819823603622, 9533.427994320364, 9718.659846905324, 9771.12059914611, 9989.60041598328, 7240.637968101679, 7387.482876198615, 7487.775366739006, 7572.318491335806, 7595.620218989139, 8156.143604921635, 8249.216324449168, 8301.045379781403, 8304.169583041888, 8375.288100511525, 7094.787156385317, 7245.275472452712, 7454.254574542502, 7456.552606843607, 7476.289752473506, 7240.348368646847, 7268.0914885639295, 7288.963555182402, 7477.102747670315, 7604.087509498895, 7014.473914456538, 7015.36861682451, 7110.217876033736, 7126.0, 7144.464165187528, 4995.725235112103, 5094.6688465249645, 5102.311187989574, 5109.616778741683, 5328.6802791832615, 5256.290400795843, 5272.920906186334, 5289.391719952255, 5314.256004159498, 5514.062609356339], "value": 4571.693278520463, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.3744929069343, 314.3194982862104, 319.8496706547925, 322.11739832857967, 322.21379833206976, 5105.744712764356, 5205.193195054763, 5286.31277933869, 5317.9202311965355, 5347.41178470368, 5178.637495375326, 5193.480651934805, 5235.450277720832, 5237.0, 5249.685018898859, 4069.267531844267, 4073.5722749111437, 4073.877783666495, 4092.6930480213955, 4101.938470922939, 3944.1322908959983, 4106.445629839978, 4108.732932359387, 4110.568390319018, 4115.54728979812, 4067.776272305037, 4070.9389359159636, 4074.6129117733767, 4076.001379661987, 4078.9796051019853, 4062.6952978526583, 4064.1058967027207, 4081.795910204478, 4086.9182616347803, 4099.1801639672185, 3499.475078738184, 3562.9109272268142, 3593.2094939113613, 3602.1354874830113, 3612.6026137124895, 5054.9494505054845, 5078.64449488536, 5080.949190508121, 5084.389873215201, 5115.872103197413, 4641.864742218583, 4646.884747660571, 4655.3947986761505, 4674.602658774008, 4682.648801339913, 5338.279332290169, 5356.517913387775, 5403.351597808249, 5471.699056551888, 5489.094299440588, 3911.2764138634443, 3923.333033384303, 3924.058226025762, 3933.0953880607335, 3935.0949281665376, 5650.6892120933335, 5656.151577263402, 5670.461306175906, 5719.027765279912, 5758.884822303572, 3939.0, 3967.781772002568, 3977.900552486211, 4081.20416518776, 4118.217538667644, 4100.097978444797, 4148.979255103705, 4149.502059752849, 4160.396742472324, 4183.477065366829, 4713.929291060636, 4734.928976065362, 4737.597304229179, 4762.499937506581, 4776.116418462596, 5295.7087360195555, 5520.089185284383, 5536.833894983157, 5552.472515111058, 5573.860653483614, 1377.793331000338, 1417.7235439089484, 1425.7932599772976, 1432.534426311453, 1452.3609611770787, 939.7885475767897, 973.8101070291225, 977.6117912564176, 991.0436428846198, 995.9701208963743, 5177.611679124081, 5189.481051894856, 5199.948000519947, 5230.607704422184, 5239.73801309933, 5474.534664553478, 5529.834104976857, 5542.032578434598, 5578.693171875586, 5661.405552417019, 5007.223880298559, 5172.120739474299, 5216.504432078947, 5218.947810521846, 5265.657732247391, 2930.5164647833294, 2967.759341697005, 2974.1375001249826, 2992.192108130836, 2999.7750168737434, 5834.387389324144, 5837.760043175993, 5838.970805146032, 5841.561882858802, 5859.121131830174, 5646.689432081275, 5698.0, 5702.287214098238, 5729.312482502127, 5758.193852860602, 5770.509506691894, 5778.797742079007, 5788.305403351541, 5810.227880496404, 5817.447342502455, 5329.573634109184, 5584.664920104807, 5642.266505354278, 5647.971760141173, 5681.715914204356, 5486.478784515464, 5565.30433695788, 5571.4428557143965, 5584.944150558443, 5587.0, 5735.225744524602, 5796.536277097738, 5802.709864506842, 5803.361630220588, 5805.535557155502, 3787.1289603391924, 3952.1700442907313, 4017.4174744661277, 4021.5174179098694, 4026.2953983053435, 5379.220013098001, 5559.6664200148125, 5567.747256867173, 5595.832125036255, 5628.690422026828, 5392.973035134799, 5498.175273708815, 5502.339719233718, 5502.81689436773, 5508.283923090133, 5491.121420572689, 5506.944930550643, 5512.145617429305, 5521.475459831309, 5552.861178470409, 5278.630495865253, 5371.731413429391, 5431.185322201543, 5486.79290556077, 5511.200875873056, 5472.616916815785, 5509.669419834822, 5529.33647962231, 5530.723463826712, 5534.861628459321, 5426.240326354235, 5463.180522921593, 5493.148561972901, 5495.395506494203, 5496.148097044965], "value": 4189.279739319904, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [797.0753925446484, 808.6522795198065, 822.5147163173734, 838.8825564420978, 848.448508469495, 13593.66015849603, 14203.928980355084, 14556.781648275271, 14559.490417835377, 15879.364825407, 14042.297885105729, 14326.068805527633, 14354.354054067566, 14386.856131438682, 14420.567382978501, 668.7325069972026, 801.7193982106274, 810.0117856215408, 810.9918900811005, 811.0348685064754, 797.9680812767482, 830.4021104804531, 830.489249111795, 831.6673330667751, 833.5582141465034, 857.4298091768998, 862.7282406042108, 863.4301361101674, 864.4294765454802, 864.6714248585529, 694.3091623834267, 852.0201767966826, 855.2217482091298, 855.4995327733284, 856.4518708026868, 602.8944934636421, 616.294342977293, 618.542278713753, 622.7695752571577, 623.4233334165879, 14330.211838348887, 14354.354054067591, 14370.13779173253, 14385.568432947028, 14387.496437624633, 13079.149855259378, 13367.598972030757, 13807.309634518233, 13831.377588008463, 13865.792013119812, 11906.821397679041, 12091.395430228453, 12232.449539770643, 12548.309842958633, 12793.616191514177, 4095.795210239506, 4136.400221967798, 4184.4351012613215, 4215.241256573816, 4234.640055595247, 68865.65567172182, 70754.64622676854, 70949.0, 80367.5981620094, 80688.59655701743, 10793.136549076055, 11012.39431831241, 11051.502682379312, 11252.66242012741, 11363.943180284046, 18502.722459163124, 18590.628187436174, 18600.906995464935, 18644.161012754186, 18651.067446627614, 8185.508869467851, 8235.629396677165, 8239.546824924566, 8334.6666133354, 8758.255548278468, 4424.75663838492, 4472.08322293929, 4497.595216430537, 4559.498455169929, 4619.3071039343695, 3283.540304357392, 3384.7461440392067, 3387.6442973487924, 3415.573053368329, 3416.590009198912, 604.9122877182872, 605.6638565596137, 606.5753972219464, 607.4108115128319, 622.4242575617536, 748.6256871564218, 749.5015814483417, 787.3347021766598, 795.2087672765635, 818.5661599352353, 8655.52394618302, 9474.95262523683, 9742.95128524353, 9781.608735650636, 9932.255080868963, 720.204174387305, 724.0478770416885, 736.1938677148514, 745.1468069060895, 747.7756672998079, 4475.098083314579, 4691.554302341272, 4710.426622427995, 4741.810327586927, 4772.49888761682, 109423.0, 110154.44922775496, 114901.0, 118675.0, 119961.0, 61980.66135370481, 81844.18155818485, 99715.50142249388, 101376.49311753541, 104159.47920260501, 75717.24282757212, 77802.61098694583, 78654.99999999885, 84676.57661711652, 89425.0, 8841.381103322963, 8993.730188094367, 9243.537823108953, 9382.296327775308, 9452.43285402878, 51938.0, 52807.0, 53328.73335633297, 53855.73072134614, 54846.0, 41491.58508414877, 69136.65431672809, 73943.26056739363, 74889.25110748822, 76412.23587764274, 11975.0, 12471.127021108436, 12498.437570309175, 12504.124711270124, 12863.485460581473, 13931.930340348234, 13971.301434928417, 14686.853131468975, 14691.191984440959, 14752.262386880828, 12767.61697149087, 12835.35823208817, 12930.460780516041, 13547.661308467366, 13599.320033998458, 13125.409356579168, 13177.868221318047, 13589.184648921097, 14032.438702451787, 14174.787378189129, 13231.735365292448, 13678.179309241477, 13702.794458083335, 14012.439502420197, 14133.505327313494, 10082.949585252027, 10166.390016599027, 10838.132949363875, 10862.13102951746, 11151.72120696989, 10314.226433017404, 10554.68335949892, 11316.15128865322, 11510.712232194262, 11662.94168529152], "value": 7416.524327888046, "unit": "score"}], "name": "dromaeo_css", "value": 7403.819938063769, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 112.0, 110.0, 113.0, 112.0, 111.0, 112.0, 110.0, 109.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 119.0, 123.0, 181.0, 119.0, 118.0, 142.0, 116.0, 152.0, 115.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 160.0, 161.0, 160.0, 161.0, 161.0, 160.0, 162.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 114.0, 74.0, 76.0, 75.0, 75.0, 105.0, 75.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 133.0, 83.0, 82.0, 84.0, 129.0, 83.0, 84.0, 124.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 147.0, 147.0, 146.0, 146.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 59.0, 51.0, 52.0, 51.0, 52.0, 50.0, 49.0, 49.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0], "value": 62.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 74.0, 75.0, 75.0, 80.0, 75.0, 84.0, 75.0, 80.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 120.0, 121.0, 120.0, 134.0, 132.0, 118.0, 119.0, 201.0, 128.0], "value": 132.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 155.0, 156.0, 156.0, 162.0, 157.0, 159.0, 154.0, 159.0, 156.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 65.0, 62.0, 63.0, 66.0, 65.0, 68.0, 62.0, 63.0], "value": 63.9, "unit": "score"}], "name": "kraken", "value": 1487.9, "alertThreshold": 2.0}]} 08:36:29 INFO - Return code: 0 08:36:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:36:29 INFO - # TBPL SUCCESS # 08:36:29 INFO - Running post-action listener: _resource_record_post_action 08:36:29 INFO - [mozharness: 2016-07-21 15:36:29.157570Z] Finished run-tests step (success) 08:36:29 INFO - Running post-run listener: _resource_record_post_run 08:36:29 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 563879936; Read time: 0; Write time: 497348 08:36:29 INFO - TinderboxPrint: CPU usage
13.0% 08:36:29 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:36:29 INFO - TinderboxPrint: I/O write bytes / time
563,879,936 / 497,348 08:36:29 INFO - TinderboxPrint: CPU idle
8,283.2 (87.0%) 08:36:29 INFO - TinderboxPrint: CPU user
1,193.9 (12.5%) 08:36:29 INFO - TinderboxPrint: Swap in / out
0 / 0 08:36:29 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 267825152; Read time: 0; Write time: 250332 08:36:29 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 296054784; Read time: 0; Write time: 247016 08:36:29 INFO - Running post-run listener: _upload_blobber_files 08:36:29 INFO - Blob upload gear active. 08:36:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:36:29 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 08:36:29 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'] 08:36:29 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 08:36:30 INFO - (blobuploader) - INFO - Open directory for files ... 08:36:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:36:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:36:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:36:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:36:43 INFO - (blobuploader) - INFO - Done attempting. 08:36:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:36:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:36:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:36:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:36:44 INFO - (blobuploader) - INFO - Done attempting. 08:36:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:36:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:36:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:36:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:36:45 INFO - (blobuploader) - INFO - Done attempting. 08:36:45 INFO - (blobuploader) - INFO - Iteration through files over. 08:36:45 INFO - Return code: 0 08:36:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:36:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:36:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae051bbf2b6d7ba793035fa9a3464fcae8ef190c1a7d8f84bfb4487b2e10691c906fabbf42077e4ac30db011ac5b1d551892d6c2b9dfaa7439b02fc77b892c89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc93274c2c7175084258f195bcc42bc81737c9b38a9c90f2409edd03be1714d59ab7569c4d64ab977aa5f2684f265f39393c8ee39e2361a887732d0b9eff101d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25fc035de7e4825f91fc474171b2a591d977f408dbd63023ec66aeca3e73db2d393a0fc534171bf70240959361fbb108a7a3868460f8dc24535676500af51538"} 08:36:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:36:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:36:45 INFO - Contents: 08:36:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae051bbf2b6d7ba793035fa9a3464fcae8ef190c1a7d8f84bfb4487b2e10691c906fabbf42077e4ac30db011ac5b1d551892d6c2b9dfaa7439b02fc77b892c89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc93274c2c7175084258f195bcc42bc81737c9b38a9c90f2409edd03be1714d59ab7569c4d64ab977aa5f2684f265f39393c8ee39e2361a887732d0b9eff101d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25fc035de7e4825f91fc474171b2a591d977f408dbd63023ec66aeca3e73db2d393a0fc534171bf70240959361fbb108a7a3868460f8dc24535676500af51538"} 08:36:45 INFO - Running post-run listener: copy_logs_to_upload_dir 08:36:45 INFO - Copying logs to upload dir... 08:36:45 INFO - mkdir: /builds/slave/test/build/upload/logs 08:36:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.334524 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-21 08:36:45.385835) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 08:36:45.390142) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351 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/ae051bbf2b6d7ba793035fa9a3464fcae8ef190c1a7d8f84bfb4487b2e10691c906fabbf42077e4ac30db011ac5b1d551892d6c2b9dfaa7439b02fc77b892c89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc93274c2c7175084258f195bcc42bc81737c9b38a9c90f2409edd03be1714d59ab7569c4d64ab977aa5f2684f265f39393c8ee39e2361a887732d0b9eff101d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25fc035de7e4825f91fc474171b2a591d977f408dbd63023ec66aeca3e73db2d393a0fc534171bf70240959361fbb108a7a3868460f8dc24535676500af51538"} build_url:https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011355 build_url: 'https://queue.taskcluster.net/v1/task/D9p2TwMLRE68OP2iMqXDrA/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/ae051bbf2b6d7ba793035fa9a3464fcae8ef190c1a7d8f84bfb4487b2e10691c906fabbf42077e4ac30db011ac5b1d551892d6c2b9dfaa7439b02fc77b892c89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc93274c2c7175084258f195bcc42bc81737c9b38a9c90f2409edd03be1714d59ab7569c4d64ab977aa5f2684f265f39393c8ee39e2361a887732d0b9eff101d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25fc035de7e4825f91fc474171b2a591d977f408dbd63023ec66aeca3e73db2d393a0fc534171bf70240959361fbb108a7a3868460f8dc24535676500af51538"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 08:36:45.423071) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:36:45.423776) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1469114078.739481-2124394351 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004551 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:36:45.506512) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 08:36:45.506837) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 08:36:45.507176) ========= ========= Total master_lag: 0.21 =========