builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-060 starttime: 1469033446.46 results: success (0) buildid: 20160720083420 builduid: 80a8a103b6994bddb37ef72e4f8b8826 revision: 0cca5d80caa9f04d491c28b388f2f396fd45fe5c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:46.456820) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:46.457334) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:46.457622) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:46.472699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:46.473124) ========= 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=337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-20 09:50:46-- 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% 66.5M=0s 2016-07-20 09:50:46 (66.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111037 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:46.598114) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:46.598431) ========= 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=337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022447 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:46.647826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:46.648193) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0cca5d80caa9f04d491c28b388f2f396fd45fe5c --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 0cca5d80caa9f04d491c28b388f2f396fd45fe5c --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=337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-20 09:50:46,710 truncating revision to first 12 chars 2016-07-20 09:50:46,710 Setting DEBUG logging. 2016-07-20 09:50:46,711 attempt 1/10 2016-07-20 09:50:46,711 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0cca5d80caa9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 09:50:47,017 unpacking tar archive at: fx-team-0cca5d80caa9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.624061 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:47.285887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:47.286267) ========= script_repo_revision: 0cca5d80caa9f04d491c28b388f2f396fd45fe5c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:47.286653) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:47.286938) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:47.297714) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-07-20 09:50:47.298122) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:50:47 INFO - MultiFileLogger online at 20160720 09:50:47 in /builds/slave/test 09:50:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 09:50:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:50:47 INFO - {'append_to_log': False, 09:50:47 INFO - 'base_work_dir': '/builds/slave/test', 09:50:47 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:50:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:50:47 INFO - 'branch': 'Fx-Team-Non-PGO', 09:50:47 INFO - 'buildbot_json_path': 'buildprops.json', 09:50:47 INFO - 'config_files': ('talos/linux_config.py',), 09:50:47 INFO - 'default_actions': ('clobber', 09:50:47 INFO - 'read-buildbot-config', 09:50:47 INFO - 'download-and-extract', 09:50:47 INFO - 'populate-webroot', 09:50:47 INFO - 'create-virtualenv', 09:50:47 INFO - 'install', 09:50:47 INFO - 'run-tests'), 09:50:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:50:47 INFO - 'download_minidump_stackwalk': True, 09:50:47 INFO - 'download_symbols': 'ondemand', 09:50:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:50:47 INFO - 'tooltool.py': '/tools/tooltool.py', 09:50:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:50:47 INFO - '/tools/misc-python/virtualenv.py')}, 09:50:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:50:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:50:47 INFO - 'installer_path': 'installer.exe', 09:50:47 INFO - 'log_level': 'info', 09:50:47 INFO - 'log_name': 'talos', 09:50:47 INFO - 'log_to_console': True, 09:50:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:50:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:50:47 INFO - 'opt_config_files': (), 09:50:47 INFO - 'pip_index': False, 09:50:47 INFO - 'sps_profile': False, 09:50:47 INFO - 'sps_profile_interval': 0, 09:50:47 INFO - 'suite': 'dromaeojs', 09:50:47 INFO - 'system_bits': '32', 09:50:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:50:47 INFO - 'title': 'talos-linux64-ix-060', 09:50:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:50:47 INFO - 'use_talos_json': True, 09:50:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:50:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:50:47 INFO - 'work_dir': 'build'} 09:50:47 INFO - [mozharness: 2016-07-20 16:50:47.449694Z] Running clobber step. 09:50:47 INFO - Running pre-action listener: _resource_record_pre_action 09:50:47 INFO - Running main action method: clobber 09:50:47 INFO - rmtree: /builds/slave/test/build 09:50:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:50:47 INFO - Running post-action listener: _resource_record_post_action 09:50:47 INFO - [mozharness: 2016-07-20 16:50:47.923936Z] Finished clobber step (success) 09:50:47 INFO - [mozharness: 2016-07-20 16:50:47.924038Z] Running read-buildbot-config step. 09:50:47 INFO - Running pre-action listener: _resource_record_pre_action 09:50:47 INFO - Running main action method: read_buildbot_config 09:50:47 INFO - Using buildbot properties: 09:50:47 INFO - { 09:50:47 INFO - "project": "", 09:50:47 INFO - "product": "firefox", 09:50:47 INFO - "script_repo_revision": "production", 09:50:47 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:50:47 INFO - "repository": "", 09:50:47 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:50:47 INFO - "buildid": "20160720083420", 09:50:47 INFO - "pgo_build": "False", 09:50:47 INFO - "basedir": "/builds/slave/test", 09:50:47 INFO - "buildnumber": 1812, 09:50:47 INFO - "slavename": "talos-linux64-ix-060", 09:50:47 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:50:47 INFO - "platform": "ubuntu64_hw", 09:50:47 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:50:47 INFO - "branch": "fx-team", 09:50:47 INFO - "repo_path": "integration/fx-team", 09:50:47 INFO - "revision": "0cca5d80caa9f04d491c28b388f2f396fd45fe5c", 09:50:47 INFO - "stage_platform": "linux64", 09:50:47 INFO - "builduid": "80a8a103b6994bddb37ef72e4f8b8826", 09:50:47 INFO - "slavebuilddir": "test" 09:50:47 INFO - } 09:50:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:50:47 INFO - Running post-action listener: _resource_record_post_action 09:50:47 INFO - [mozharness: 2016-07-20 16:50:47.926489Z] Finished read-buildbot-config step (success) 09:50:47 INFO - [mozharness: 2016-07-20 16:50:47.926577Z] Running download-and-extract step. 09:50:47 INFO - Running pre-action listener: _resource_record_pre_action 09:50:47 INFO - Running main action method: download_and_extract 09:50:47 INFO - mkdir: /builds/slave/test/build/tests 09:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:47 INFO - https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:50:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:50:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:50:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:48 INFO - Downloaded 1361 bytes. 09:50:48 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:50:48 INFO - Using the following test package requirements: 09:50:48 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:50:48 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:50:48 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:50:48 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:48 INFO - u'jsshell-linux-x86_64.zip'], 09:50:48 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:50:48 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:50:48 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:50:48 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:50:48 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:50:48 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:50:48 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:50:48 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:48 INFO - https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:50:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:50:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:50:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:49 INFO - Downloaded 22771994 bytes. 09:50:49 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'] 09:50:49 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 09:50:50 INFO - Return code: 0 09:50:50 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 09:50:50 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')]} 09:50:50 INFO - https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:50:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:50:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:50:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:51 INFO - Downloaded 22771994 bytes. 09:50:51 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'] 09:50:51 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 09:50:53 INFO - Return code: 0 09:50: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')]} 09:50:53 INFO - https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:50:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:50:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:50:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:54 INFO - Downloaded 14403317 bytes. 09:50:54 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'] 09:50:54 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 09:50:54 INFO - Return code: 0 09:50:54 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')]} 09:50:54 INFO - https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:50:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:50:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:50:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-XT3CbhRC6pLGqaA7MKvg/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 09:50:55 INFO - Downloaded 57115690 bytes. 09:50:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:50:55 INFO - mkdir: /builds/slave/test/properties 09:50:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:50:55 INFO - Writing to file /builds/slave/test/properties/build_url 09:50:55 INFO - Contents: 09:50:55 INFO - build_url:https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:50:56 INFO - Running post-action listener: _resource_record_post_action 09:50:56 INFO - Running post-action listener: set_extra_try_arguments 09:50:56 INFO - [mozharness: 2016-07-20 16:50:56.277204Z] Finished download-and-extract step (success) 09:50:56 INFO - [mozharness: 2016-07-20 16:50:56.277471Z] Running populate-webroot step. 09:50:56 INFO - Running pre-action listener: _resource_record_pre_action 09:50:56 INFO - Running main action method: populate_webroot 09:50:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:50:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:50:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:50:56 INFO - '--tppagecycles', 09:50:56 INFO - '7'], 09:50:56 INFO - 'tests': ['tsvgm']}}, 09:50:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:50:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:50:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:50:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:50:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:56 INFO - 'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:50:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:50:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:56 INFO - 'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['damp', 'tps']}, 09:50:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:56 INFO - 'tests': ['damp', 'tps']}, 09:50:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['dromaeo_dom']}, 09:50:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:50:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['basic_compositor_video']}, 09:50:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:50:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['a11yr', 09:50:56 INFO - 'ts_paint', 09:50:56 INFO - 'tpaint', 09:50:56 INFO - 'sessionrestore', 09:50:56 INFO - 'sessionrestore_no_auto_restore', 09:50:56 INFO - 'tabpaint']}, 09:50:56 INFO - 'other-e10s': {'tests': ['a11yr', 09:50:56 INFO - 'ts_paint', 09:50:56 INFO - 'tpaint', 09:50:56 INFO - 'sessionrestore', 09:50:56 INFO - 'sessionrestore_no_auto_restore', 09:50:56 INFO - 'tabpaint']}, 09:50:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:50:56 INFO - 'ts_paint', 09:50:56 INFO - 'tpaint', 09:50:56 INFO - 'sessionrestore', 09:50:56 INFO - 'sessionrestore_no_auto_restore', 09:50:56 INFO - 'tabpaint']}, 09:50:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:50:56 INFO - 'ts_paint', 09:50:56 INFO - 'tpaint', 09:50:56 INFO - 'sessionrestore', 09:50:56 INFO - 'sessionrestore_no_auto_restore', 09:50:56 INFO - 'tabpaint']}, 09:50:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['a11yr', 09:50:56 INFO - 'ts_paint', 09:50:56 INFO - 'tpaint', 09:50:56 INFO - 'sessionrestore', 09:50:56 INFO - 'sessionrestore_no_auto_restore', 09:50:56 INFO - 'tabpaint']}, 09:50:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['a11yr', 09:50:56 INFO - 'ts_paint', 09:50:56 INFO - 'tpaint', 09:50:56 INFO - 'sessionrestore', 09:50:56 INFO - 'sessionrestore_no_auto_restore', 09:50:56 INFO - 'tabpaint']}, 09:50:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['tsvgx', 09:50:56 INFO - 'tsvgr_opacity', 09:50:56 INFO - 'tart', 09:50:56 INFO - 'tscrollx', 09:50:56 INFO - 'cart']}, 09:50:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:50:56 INFO - 'tsvgr_opacity', 09:50:56 INFO - 'tart', 09:50:56 INFO - 'tscrollx', 09:50:56 INFO - 'cart']}, 09:50:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:56 INFO - 'talos_options': ['--disable-e10s'], 09:50:56 INFO - 'tests': ['tp5o']}, 09:50:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:56 INFO - 'tests': ['tp5o']}, 09:50:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:56 INFO - 'talos_options': ['--disable-e10s', 09:50:56 INFO - '--xperf_path', 09:50:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:50:56 INFO - 'tests': ['tp5n']}, 09:50:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:56 INFO - 'talos_options': ['--xperf_path', 09:50:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:50:56 INFO - 'tests': ['tp5n']}}, 09:50:56 INFO - 'talos.zip': {'path': '', 09:50:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:50:56 INFO - Running post-action listener: _resource_record_post_action 09:50:56 INFO - [mozharness: 2016-07-20 16:50:56.298209Z] Finished populate-webroot step (success) 09:50:56 INFO - [mozharness: 2016-07-20 16:50:56.298298Z] Running create-virtualenv step. 09:50:56 INFO - Running pre-action listener: _resource_record_pre_action 09:50:56 INFO - Running main action method: create_virtualenv 09:50:56 INFO - Creating virtualenv /builds/slave/test/build/venv 09:50:56 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 09:50:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:50:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:50:56 INFO - Using real prefix '/usr' 09:50:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:50:56 INFO - Installing distribute.............................................................................................................................................................................................done. 09:50:58 INFO - Installing pip.................done. 09:50:58 INFO - Return code: 0 09:50:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:50:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:50:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:58 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 0x2425030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, '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': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', '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 09:50:58 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 09:50:58 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 09:50:58 INFO - Using env: {'DISPLAY': ':0', 09:50:58 INFO - 'HOME': '/home/cltbld', 09:50:58 INFO - 'LANG': 'en_US.UTF-8', 09:50:58 INFO - 'LANGUAGE': 'en_US:en', 09:50:58 INFO - 'LOGNAME': 'cltbld', 09:50:58 INFO - 'MAIL': '/var/mail/cltbld', 09:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:50:58 INFO - 'MOZ_NO_REMOTE': '1', 09:50:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:50:58 INFO - 'NO_EM_RESTART': '1', 09:50:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:50:58 INFO - 'PWD': '/builds/slave/test', 09:50:58 INFO - 'SHELL': '/bin/bash', 09:50:58 INFO - 'SHLVL': '1', 09:50:58 INFO - 'TERM': 'linux', 09:50:58 INFO - 'TMOUT': '86400', 09:50:58 INFO - 'USER': 'cltbld', 09:50:58 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:50:58 INFO - '_': '/tools/buildbot/bin/python'} 09:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:50:59 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 09:50:59 INFO - Cleaning up... 09:50:59 INFO - Return code: 0 09:50:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:59 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 0x2425030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, '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': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', '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 09:50:59 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 09:50:59 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 09:50:59 INFO - Using env: {'DISPLAY': ':0', 09:50:59 INFO - 'HOME': '/home/cltbld', 09:50:59 INFO - 'LANG': 'en_US.UTF-8', 09:50:59 INFO - 'LANGUAGE': 'en_US:en', 09:50:59 INFO - 'LOGNAME': 'cltbld', 09:50:59 INFO - 'MAIL': '/var/mail/cltbld', 09:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:50:59 INFO - 'MOZ_NO_REMOTE': '1', 09:50:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:50:59 INFO - 'NO_EM_RESTART': '1', 09:50:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:50:59 INFO - 'PWD': '/builds/slave/test', 09:50:59 INFO - 'SHELL': '/bin/bash', 09:50:59 INFO - 'SHLVL': '1', 09:50:59 INFO - 'TERM': 'linux', 09:50:59 INFO - 'TMOUT': '86400', 09:50:59 INFO - 'USER': 'cltbld', 09:50:59 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:50:59 INFO - '_': '/tools/buildbot/bin/python'} 09:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:50:59 INFO - Downloading/unpacking psutil>=3.1.1 09:50:59 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 09:50:59 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 09:50:59 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 09:50:59 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 09:50:59 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 09:50:59 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 09:51:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:51:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:51:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:51:01 INFO - Installing collected packages: psutil 09:51:01 INFO - Running setup.py install for psutil 09:51:01 INFO - building 'psutil._psutil_linux' extension 09:51:01 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 09:51:01 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 09:51:01 INFO - building 'psutil._psutil_posix' extension 09:51:01 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 09:51:01 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 09:51:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:51:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:51:01 INFO - Successfully installed psutil 09:51:01 INFO - Cleaning up... 09:51:01 INFO - Return code: 0 09:51:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:51: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')]} 09:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51: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')]} 09:51:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:01 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 0x2425030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, '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': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', '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 09:51:01 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 09:51:01 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 09:51:01 INFO - Using env: {'DISPLAY': ':0', 09:51:01 INFO - 'HOME': '/home/cltbld', 09:51:01 INFO - 'LANG': 'en_US.UTF-8', 09:51:01 INFO - 'LANGUAGE': 'en_US:en', 09:51:01 INFO - 'LOGNAME': 'cltbld', 09:51:01 INFO - 'MAIL': '/var/mail/cltbld', 09:51:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:01 INFO - 'MOZ_NO_REMOTE': '1', 09:51:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:01 INFO - 'NO_EM_RESTART': '1', 09:51:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:01 INFO - 'PWD': '/builds/slave/test', 09:51:01 INFO - 'SHELL': '/bin/bash', 09:51:01 INFO - 'SHLVL': '1', 09:51:01 INFO - 'TERM': 'linux', 09:51:01 INFO - 'TMOUT': '86400', 09:51:01 INFO - 'USER': 'cltbld', 09:51:01 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:51:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:01 INFO - '_': '/tools/buildbot/bin/python'} 09:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:51:04 INFO - Installing collected packages: mozsystemmonitor 09:51:04 INFO - Running setup.py install for mozsystemmonitor 09:51:04 INFO - Successfully installed mozsystemmonitor 09:51:04 INFO - Cleaning up... 09:51:04 INFO - Return code: 0 09:51:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2425030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, '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': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', '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 09:51:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:51:04 INFO - Using env: {'DISPLAY': ':0', 09:51:04 INFO - 'HOME': '/home/cltbld', 09:51:04 INFO - 'LANG': 'en_US.UTF-8', 09:51:04 INFO - 'LANGUAGE': 'en_US:en', 09:51:04 INFO - 'LOGNAME': 'cltbld', 09:51:04 INFO - 'MAIL': '/var/mail/cltbld', 09:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:04 INFO - 'MOZ_NO_REMOTE': '1', 09:51:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:04 INFO - 'NO_EM_RESTART': '1', 09:51:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:04 INFO - 'PWD': '/builds/slave/test', 09:51:04 INFO - 'SHELL': '/bin/bash', 09:51:04 INFO - 'SHLVL': '1', 09:51:04 INFO - 'TERM': 'linux', 09:51:04 INFO - 'TMOUT': '86400', 09:51:04 INFO - 'USER': 'cltbld', 09:51:04 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:04 INFO - '_': '/tools/buildbot/bin/python'} 09:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:04 INFO - Downloading/unpacking jsonschema==2.5.1 09:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:51:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:51:06 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 09:51:06 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 09:51:06 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 09:51:06 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 09:51:06 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 09:51:06 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 09:51:06 INFO - Downloading functools32-3.2.3-2.tar.gz 09:51:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:51:06 INFO - warning: no files found matching '*.txt' 09:51:06 INFO - no previously-included directories found matching 'build' 09:51:06 INFO - no previously-included directories found matching 'dist' 09:51:06 INFO - no previously-included directories found matching '.git*' 09:51:06 INFO - Installing collected packages: jsonschema, functools32 09:51:06 INFO - Running setup.py install for functools32 09:51:06 INFO - warning: no files found matching '*.txt' 09:51:06 INFO - no previously-included directories found matching 'build' 09:51:06 INFO - no previously-included directories found matching 'dist' 09:51:06 INFO - no previously-included directories found matching '.git*' 09:51:06 INFO - Successfully installed jsonschema functools32 09:51:06 INFO - Cleaning up... 09:51:06 INFO - Return code: 0 09:51:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2425030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, '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': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', '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 09:51:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:51:06 INFO - Using env: {'DISPLAY': ':0', 09:51:06 INFO - 'HOME': '/home/cltbld', 09:51:06 INFO - 'LANG': 'en_US.UTF-8', 09:51:06 INFO - 'LANGUAGE': 'en_US:en', 09:51:06 INFO - 'LOGNAME': 'cltbld', 09:51:06 INFO - 'MAIL': '/var/mail/cltbld', 09:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:06 INFO - 'MOZ_NO_REMOTE': '1', 09:51:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:06 INFO - 'NO_EM_RESTART': '1', 09:51:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:06 INFO - 'PWD': '/builds/slave/test', 09:51:06 INFO - 'SHELL': '/bin/bash', 09:51:06 INFO - 'SHLVL': '1', 09:51:06 INFO - 'TERM': 'linux', 09:51:06 INFO - 'TMOUT': '86400', 09:51:06 INFO - 'USER': 'cltbld', 09:51:06 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:06 INFO - '_': '/tools/buildbot/bin/python'} 09:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:07 INFO - Downloading/unpacking blobuploader==1.2.4 09:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:08 INFO - Downloading blobuploader-1.2.4.tar.gz 09:51:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:51:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:51:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:09 INFO - Downloading docopt-0.6.1.tar.gz 09:51:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:51:09 INFO - Installing collected packages: blobuploader, requests, docopt 09:51:09 INFO - Running setup.py install for blobuploader 09:51:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:51:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:51:09 INFO - Running setup.py install for requests 09:51:10 INFO - Running setup.py install for docopt 09:51:10 INFO - Successfully installed blobuploader requests docopt 09:51:10 INFO - Cleaning up... 09:51:10 INFO - Return code: 0 09:51:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51: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')]} 09:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51: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')]} 09:51:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:10 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 0x2425030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, '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': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', '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 09:51:10 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 09:51:10 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 09:51:10 INFO - Using env: {'DISPLAY': ':0', 09:51:10 INFO - 'HOME': '/home/cltbld', 09:51:10 INFO - 'LANG': 'en_US.UTF-8', 09:51:10 INFO - 'LANGUAGE': 'en_US:en', 09:51:10 INFO - 'LOGNAME': 'cltbld', 09:51:10 INFO - 'MAIL': '/var/mail/cltbld', 09:51:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:10 INFO - 'MOZ_NO_REMOTE': '1', 09:51:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:10 INFO - 'NO_EM_RESTART': '1', 09:51:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:10 INFO - 'PWD': '/builds/slave/test', 09:51:10 INFO - 'SHELL': '/bin/bash', 09:51:10 INFO - 'SHLVL': '1', 09:51:10 INFO - 'TERM': 'linux', 09:51:10 INFO - 'TMOUT': '86400', 09:51:10 INFO - 'USER': 'cltbld', 09:51:10 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:51:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:10 INFO - '_': '/tools/buildbot/bin/python'} 09:51:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:51:10 INFO - Running setup.py (path:/tmp/pip-TNIHRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:51:10 INFO - Running setup.py (path:/tmp/pip-Oozb1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:51:10 INFO - Running setup.py (path:/tmp/pip-hbUZ83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:51:10 INFO - Running setup.py (path:/tmp/pip-Cty5Sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:51:10 INFO - Running setup.py (path:/tmp/pip-tqGW_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:51:10 INFO - Running setup.py (path:/tmp/pip-7SiCRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:51:10 INFO - Running setup.py (path:/tmp/pip-LcUq0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:51:11 INFO - Running setup.py (path:/tmp/pip-kyRNhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:51:11 INFO - Running setup.py (path:/tmp/pip-MPKk_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:51:11 INFO - Running setup.py (path:/tmp/pip-6BfKC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:51:11 INFO - Running setup.py (path:/tmp/pip-nw7SlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:51:11 INFO - Running setup.py (path:/tmp/pip-7_wDtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:51:11 INFO - Running setup.py (path:/tmp/pip-9E1SBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:51:11 INFO - Running setup.py (path:/tmp/pip-CBH5TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:11 INFO - Running setup.py (path:/tmp/pip-BQfu4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:51:11 INFO - Running setup.py (path:/tmp/pip-mGEeZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:51:11 INFO - Running setup.py (path:/tmp/pip-eLoFcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:51:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:51:11 INFO - Running setup.py install for manifestparser 09:51:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:51:11 INFO - Running setup.py install for mozcrash 09:51:11 INFO - Running setup.py install for mozdebug 09:51:12 INFO - Running setup.py install for mozdevice 09:51:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Running setup.py install for mozfile 09:51:12 INFO - Running setup.py install for mozhttpd 09:51:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Running setup.py install for mozinfo 09:51:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Running setup.py install for mozInstall 09:51:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Running setup.py install for mozleak 09:51:12 INFO - Running setup.py install for mozlog 09:51:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Running setup.py install for moznetwork 09:51:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:51:12 INFO - Running setup.py install for mozprocess 09:51:12 INFO - Running setup.py install for mozprofile 09:51:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:51:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:51:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:51:13 INFO - Running setup.py install for mozrunner 09:51:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:51:13 INFO - Running setup.py install for mozscreenshot 09:51:13 INFO - Running setup.py install for moztest 09:51:13 INFO - Running setup.py install for mozversion 09:51:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:51:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:51:13 INFO - Cleaning up... 09:51:13 INFO - Return code: 0 09:51:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:13 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 0x2425030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, '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': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', '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 09:51:13 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 09:51:13 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 09:51:13 INFO - Using env: {'DISPLAY': ':0', 09:51:13 INFO - 'HOME': '/home/cltbld', 09:51:13 INFO - 'LANG': 'en_US.UTF-8', 09:51:13 INFO - 'LANGUAGE': 'en_US:en', 09:51:13 INFO - 'LOGNAME': 'cltbld', 09:51:13 INFO - 'MAIL': '/var/mail/cltbld', 09:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:13 INFO - 'MOZ_NO_REMOTE': '1', 09:51:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:13 INFO - 'NO_EM_RESTART': '1', 09:51:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:13 INFO - 'PWD': '/builds/slave/test', 09:51:13 INFO - 'SHELL': '/bin/bash', 09:51:13 INFO - 'SHLVL': '1', 09:51:13 INFO - 'TERM': 'linux', 09:51:13 INFO - 'TMOUT': '86400', 09:51:13 INFO - 'USER': 'cltbld', 09:51:13 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:13 INFO - '_': '/tools/buildbot/bin/python'} 09:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:51:13 INFO - Running setup.py (path:/tmp/pip-x4jm7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:51:13 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)) 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:51:13 INFO - Running setup.py (path:/tmp/pip-tCzr5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:51:13 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)) 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:51:13 INFO - Running setup.py (path:/tmp/pip-taAx13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:51:14 INFO - Running setup.py (path:/tmp/pip-X6r9pY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:51:14 INFO - Running setup.py (path:/tmp/pip-JHGyPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:51:14 INFO - Running setup.py (path:/tmp/pip-xTiPgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:51:14 INFO - Running setup.py (path:/tmp/pip-d7TCPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:51:14 INFO - Running setup.py (path:/tmp/pip-bvGhlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:51:14 INFO - Running setup.py (path:/tmp/pip-Y4Gn1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:51:14 INFO - Running setup.py (path:/tmp/pip-6YsVYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:51:14 INFO - Running setup.py (path:/tmp/pip-oMNsyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:51:14 INFO - Running setup.py (path:/tmp/pip-9YBehx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:51:14 INFO - Running setup.py (path:/tmp/pip-_uYvag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:51:14 INFO - Running setup.py (path:/tmp/pip-Oh247f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:14 INFO - Running setup.py (path:/tmp/pip-siv5PC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:51:14 INFO - Running setup.py (path:/tmp/pip-R8D1Aw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:51:14 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)) 09:51:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:51:14 INFO - Running setup.py (path:/tmp/pip-KMFiyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:51:15 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)) 09:51:15 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)) 09:51:15 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)) 09:51:15 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)) 09:51:15 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)) 09:51:15 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)) 09:51:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:51:15 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 09:51:15 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 09:51:15 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 09:51:15 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 09:51:15 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 09:51:15 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 09:51:16 INFO - Downloading blessings-1.6.tar.gz 09:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:51:17 INFO - Installing collected packages: blessings 09:51:17 INFO - Running setup.py install for blessings 09:51:17 INFO - Successfully installed blessings 09:51:17 INFO - Cleaning up... 09:51:17 INFO - Return code: 0 09:51:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:51:17 INFO - Reading from file tmpfile_stdout 09:51:17 INFO - Current package versions: 09:51:17 INFO - argparse == 1.2.1 09:51:17 INFO - blessings == 1.6 09:51:17 INFO - blobuploader == 1.2.4 09:51:17 INFO - docopt == 0.6.1 09:51:17 INFO - functools32 == 3.2.3-2 09:51:17 INFO - jsonschema == 2.5.1 09:51:17 INFO - manifestparser == 1.1 09:51:17 INFO - mozInstall == 1.12 09:51:17 INFO - mozcrash == 0.17 09:51:17 INFO - mozdebug == 0.1 09:51:17 INFO - mozdevice == 0.48 09:51:17 INFO - mozfile == 1.2 09:51:17 INFO - mozhttpd == 0.7 09:51:17 INFO - mozinfo == 0.9 09:51:17 INFO - mozleak == 0.1 09:51:17 INFO - mozlog == 3.3 09:51:17 INFO - moznetwork == 0.27 09:51:17 INFO - mozprocess == 0.23 09:51:17 INFO - mozprofile == 0.28 09:51:17 INFO - mozrunner == 6.12 09:51:17 INFO - mozscreenshot == 0.1 09:51:17 INFO - mozsystemmonitor == 0.3 09:51:17 INFO - moztest == 0.7 09:51:17 INFO - mozversion == 1.4 09:51:17 INFO - psutil == 3.1.1 09:51:17 INFO - requests == 1.2.3 09:51:17 INFO - wsgiref == 0.1.2 09:51:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51:17 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')]} 09:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:17 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')]} 09:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:17 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 0x2425030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, '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': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', '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 09:51:17 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 09:51:17 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 09:51:17 INFO - Using env: {'DISPLAY': ':0', 09:51:17 INFO - 'HOME': '/home/cltbld', 09:51:17 INFO - 'LANG': 'en_US.UTF-8', 09:51:17 INFO - 'LANGUAGE': 'en_US:en', 09:51:17 INFO - 'LOGNAME': 'cltbld', 09:51:17 INFO - 'MAIL': '/var/mail/cltbld', 09:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:17 INFO - 'MOZ_NO_REMOTE': '1', 09:51:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:17 INFO - 'NO_EM_RESTART': '1', 09:51:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:17 INFO - 'PWD': '/builds/slave/test', 09:51:17 INFO - 'SHELL': '/bin/bash', 09:51:17 INFO - 'SHLVL': '1', 09:51:17 INFO - 'TERM': 'linux', 09:51:17 INFO - 'TMOUT': '86400', 09:51:17 INFO - 'USER': 'cltbld', 09:51:17 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:17 INFO - '_': '/tools/buildbot/bin/python'} 09:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 INFO - Cleaning up... 09:51:17 INFO - Return code: 0 09:51:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:51:17 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')]} 09:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:17 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')]} 09:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:17 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 0x2425030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26e6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, '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': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', '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 09:51:17 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 09:51:17 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 09:51:17 INFO - Using env: {'DISPLAY': ':0', 09:51:17 INFO - 'HOME': '/home/cltbld', 09:51:17 INFO - 'LANG': 'en_US.UTF-8', 09:51:17 INFO - 'LANGUAGE': 'en_US:en', 09:51:17 INFO - 'LOGNAME': 'cltbld', 09:51:17 INFO - 'MAIL': '/var/mail/cltbld', 09:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:17 INFO - 'MOZ_NO_REMOTE': '1', 09:51:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:17 INFO - 'NO_EM_RESTART': '1', 09:51:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:17 INFO - 'PWD': '/builds/slave/test', 09:51:17 INFO - 'SHELL': '/bin/bash', 09:51:17 INFO - 'SHLVL': '1', 09:51:17 INFO - 'TERM': 'linux', 09:51:17 INFO - 'TMOUT': '86400', 09:51:17 INFO - 'USER': 'cltbld', 09:51:17 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:17 INFO - '_': '/tools/buildbot/bin/python'} 09:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:51:17 INFO - Cleaning up... 09:51:17 INFO - Return code: 0 09:51:17 INFO - Running post-action listener: _resource_record_post_action 09:51:17 INFO - Running post-action listener: _start_resource_monitoring 09:51:17 INFO - Starting resource monitoring. 09:51:17 INFO - [mozharness: 2016-07-20 16:51:17.871148Z] Finished create-virtualenv step (success) 09:51:17 INFO - [mozharness: 2016-07-20 16:51:17.871589Z] Running install step. 09:51:17 INFO - Running pre-action listener: _resource_record_pre_action 09:51:17 INFO - Running main action method: install 09:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:51:18 INFO - Reading from file tmpfile_stdout 09:51:18 INFO - Detecting whether we're running mozinstall >=1.0... 09:51:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:51:18 INFO - Reading from file tmpfile_stdout 09:51:18 INFO - Output received: 09:51:18 INFO - Usage: mozinstall [options] installer 09:51:18 INFO - Options: 09:51:18 INFO - -h, --help show this help message and exit 09:51:18 INFO - -d DEST, --destination=DEST 09:51:18 INFO - Directory to install application into. [default: 09:51:18 INFO - "/builds/slave/test"] 09:51:18 INFO - --app=APP Application being installed. [default: firefox] 09:51:18 INFO - mkdir: /builds/slave/test/build/application 09:51:18 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'] 09:51:18 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 09:51:34 INFO - Reading from file tmpfile_stdout 09:51:34 INFO - Output received: 09:51:34 INFO - /builds/slave/test/build/application/firefox/firefox 09:51:34 INFO - Running post-action listener: _resource_record_post_action 09:51:34 INFO - [mozharness: 2016-07-20 16:51:34.575868Z] Finished install step (success) 09:51:34 INFO - [mozharness: 2016-07-20 16:51:34.576144Z] Running run-tests step. 09:51:34 INFO - Running pre-action listener: _resource_record_pre_action 09:51:34 INFO - Running main action method: run_tests 09:51:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:51:34 INFO - Python 2.7.3 09:51:34 INFO - Return code: 0 09:51:34 INFO - grabbing minidump binary from tooltool 09:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:34 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 0x26e43b0>, '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 0x26e52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26e5740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:51:34 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 09:51:34 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 09:51:34 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 09:51:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:51:34 INFO - Return code: 0 09:51:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:51:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:51:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:51:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:51:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:51:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 09:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 09:51:34 INFO - Using env: {'DISPLAY': ':0', 09:51:34 INFO - 'HOME': '/home/cltbld', 09:51:34 INFO - 'LANG': 'en_US.UTF-8', 09:51:34 INFO - 'LANGUAGE': 'en_US:en', 09:51:34 INFO - 'LOGNAME': 'cltbld', 09:51:34 INFO - 'MAIL': '/var/mail/cltbld', 09:51:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:51:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:34 INFO - 'MOZ_NO_REMOTE': '1', 09:51:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:51:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:34 INFO - 'NO_EM_RESTART': '1', 09:51:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:34 INFO - 'PWD': '/builds/slave/test', 09:51:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:51:34 INFO - 'SHELL': '/bin/bash', 09:51:34 INFO - 'SHLVL': '1', 09:51:34 INFO - 'TERM': 'linux', 09:51:34 INFO - 'TMOUT': '86400', 09:51:34 INFO - 'USER': 'cltbld', 09:51:34 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900', 09:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:34 INFO - '_': '/tools/buildbot/bin/python'} 09:51:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 09:51:34 INFO - mozversion application_buildid: 20160720083420 09:51:34 INFO - mozversion application_changeset: 0cca5d80caa9f04d491c28b388f2f396fd45fe5c 09:51:34 INFO - mozversion application_display_name: Nightly 09:51:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:51:34 INFO - mozversion application_name: Firefox 09:51:34 INFO - mozversion application_remotingname: firefox 09:51:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:51:34 INFO - mozversion application_vendor: Mozilla 09:51:34 INFO - mozversion application_version: 50.0a1 09:51:34 INFO - mozversion platform_buildid: 20160720083420 09:51:34 INFO - mozversion platform_changeset: 0cca5d80caa9f04d491c28b388f2f396fd45fe5c 09:51:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:51:34 INFO - mozversion platform_version: 50.0a1 09:51:34 INFO - using testdate: 1469033494 09:51:34 INFO - actual date: 1469033494 09:51:34 INFO - starting webserver on 'localhost:60205' 09:51:34 INFO - SUITE-START | Running 2 tests 09:51:34 INFO - TEST-START | dromaeo_css 09:51:34 INFO - operating with platform_type : linux_ 09:51:34 INFO - Initialising browser for dromaeo_css test... 09:51:34 INFO - TEST-INFO | started process 18757 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZBY_a9/profile http://localhost:60205/getInfo.html) 09:51:38 INFO - PROCESS | 18757 | __metrics Screen width/height:1600/1200 09:51:38 INFO - PROCESS | 18757 | colorDepth:24 09:51:38 INFO - PROCESS | 18757 | Browser inner width/height: 1024/697 09:51:38 INFO - PROCESS | 18757 | __metrics 09:51:39 INFO - PROCESS | 18757 | 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" 09:51:39 INFO - PROCESS | 18757 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:51:40 INFO - TEST-INFO | 18757: exit 0 09:51:40 INFO - Browser initialized. 09:51:40 INFO - Running cycle 1/1 for dromaeo_css test... 09:51:40 INFO - TEST-INFO | started process 18836 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZBY_a9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:51:52 INFO - PROCESS | 18836 | RSS: Main: 158552064 09:51:52 INFO - PROCESS | 18836 | 09:52:44 INFO - PROCESS | 18836 | 1469033564642 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] 09:54:56 INFO - PROCESS | 18836 | Cycle 1(1): loaded http://localhost:60205/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60205/tests/dromaeo/cssquery-ext.html) 09:54:57 INFO - PROCESS | 18836 | RSS: Main: 247431168 09:54:57 INFO - PROCESS | 18836 | 09:57:59 INFO - PROCESS | 18836 | Cycle 1(1): loaded http://localhost:60205/tests/dromaeo/cssquery-ext.html (next: http://localhost:60205/tests/dromaeo/cssquery-jquery.html) 09:58:00 INFO - PROCESS | 18836 | RSS: Main: 240553984 09:58:00 INFO - PROCESS | 18836 | 10:01:02 INFO - PROCESS | 18836 | Cycle 1(1): loaded http://localhost:60205/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60205/tests/dromaeo/cssquery-mootools.html) 10:01:03 INFO - PROCESS | 18836 | RSS: Main: 189784064 10:01:03 INFO - PROCESS | 18836 | 10:04:05 INFO - PROCESS | 18836 | Cycle 1(1): loaded http://localhost:60205/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60205/tests/dromaeo/cssquery-prototype.html) 10:04:06 INFO - PROCESS | 18836 | RSS: Main: 190128128 10:04:06 INFO - PROCESS | 18836 | 10:07:09 INFO - PROCESS | 18836 | Cycle 1(1): loaded http://localhost:60205/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60205/tests/dromaeo/cssquery-yui.html) 10:07:09 INFO - PROCESS | 18836 | RSS: Main: 189923328 10:07:09 INFO - PROCESS | 18836 | 10:10:12 INFO - PROCESS | 18836 | Cycle 1(1): loaded http://localhost:60205/tests/dromaeo/cssquery-yui.html (next: http://localhost:60205/tests/dromaeo/cssquery-dojo.html) 10:10:13 INFO - PROCESS | 18836 | RSS: Main: 283529216 10:10:13 INFO - PROCESS | 18836 | 10:10:13 INFO - PROCESS | 18836 | __start_tp_report 10:10:13 INFO - PROCESS | 18836 | _x_x_mozilla_page_load 10:10:13 INFO - PROCESS | 18836 | _x_x_mozilla_page_load_details 10:10:13 INFO - PROCESS | 18836 | |i|pagename|runs| 10:10:13 INFO - PROCESS | 18836 | |0;dojo.html;2535.252100630314;2585.43120513487;2590.9222723318285;2632.9736702632968;2634.3940893594463;4563.429571303587;4629.120467111255;4703.764811759411;4756.405449318835;4763.4522029966565;4677.415323084614;4718.811247550094;4722.551357621029;4740.07568524138;4796.088743138801;4319.265724826779;4349.391085248068;4392.626626736731;4443.933340999872;4482.641388688897;4390.868273951771;4417.447819022622;4433.113377324532;4446.510883802779;4465.70972886763;4192.601702838256;4259.91480170398;4273.658107351404;4314.568543145691;4410.404595379631;4305.052888364586;4312.374705667692;4316.6978311518205;4364.388985542027;4410.16206920684;4308.5045219799495;4328.177646247234;4332;4339.587739164775;4377.190219809346;4528.25283828168;4645.303204519315;4728.692634978715;4775.498572649856;4789.066132104241;5013.523715247045;5097.745112744348;5146.022255771391;5150.922736158961;5168.689878607295;5148.974255128738;5158.74206289684;5178;5192.3249977503065;5202.661826981234;4671.11248862715;4671.95328046722;4703.435587729426;4814.879628009257;4817.036592681443;5168.61235407346;5193.948060519422;5226.555742761831;5235.031519168966;5239.266502689627;4958.603311735053;4998.850034498971;5005.074061298671;5009.499050095034;5033.496650335011;4913.70517768935;5036.169032109734;5053.267276244924;5073.365829271341;5123.359580052494;5114.156164232934;5127.461616530285;5130.204818253177;5134.768935397916;5141.511556402136;5093.541581257705;5103.387593488805;5121.4622464641425;5132.9743351283005;5165.870853228625;4229.492460904649;4286.571342865751;4300.161468513672;4367.58507941745;4449.599536041707;3770.472133901256;3771.6982641389172;3825.8660946866726;3853.0367408147963;3868.342381795045;4303.698741088157;4349.804258808361;4361.0841723237845;4361.127774445124;4404.515503294635;4904.411470623548;4975.378077740283;5016.44819069902;5019.7239151847;5065.594752419797;4272.957270427318;4284.1645879053285;4302.89242768927;4308.46144231971;4367.847125350598;4539.477960034572;4546.909061818811;4553.430821147356;4558.680892337505;4590.655700822452;4609.884752881205;4693.741844198602;4744.952550474451;4744.95255047452;4758.929240920766;5022.3219865317715;5085.720285384192;5103.260027296095;5156.097682905402;5156.303898973741;4811.446683631427;4812.25410061441;4822.276658501253;4823.083614113307;4871.439784424837;5020.598352131894;5046.596272298134;5052.7726252318;5059.620528460454;5060.240963855451;4984.028114517634;4985.476524964826;5023.020511000466;5030.0694371541385;5116.692998420107;4933.284673722362;4934.235193545006;4935.284383764262;4938.333325001079;4984.825531106539;4906.435759887659;4920.753962301942;4925.458199598113;4932.7533623319405;4977.527134922177;4738.881527961691;4757;4771.451283102488;4776.068666609977;4808.495108013608;4798.688085274446;4806.279058141306;4809.807607695793;4817.614590832655;4857.587105096107;4650.488446270975;4694.530546945278;4704.3649107370065;4707.069092942848;4747.477777444409 10:10:13 INFO - PROCESS | 18836 | |1;ext.html;559.7061542690088;564.9067903795874;569.5187566506303;571.6570057965224;578.4302462074857;27305.18084457467;27549;39971.20057598846;40657.18685626286;40758.388624170664;41504.792476037655;41731;41815.79092104535;41831.163376732526;42019.57980420195;7839.882401763978;7979.720709775159;7990.920090799104;7993.96003019987;8007.95996020022;7858;7923.088844782837;7926;7936.047674279094;7936.603169841514;7920.36637069036;7994.48035877669;7998;7998.240167184108;8003.95998020012;7901.604919754018;7944.404169687296;7944.960275198645;7945.642446089911;7954;7861.921380786176;7924;7930.405219608495;7932.801679957989;7946.324562412202;39401;39812.402813957524;39821.80089099565;39828.402573961415;39929;2955.275957390453;2981.5825784390204;3078.738307243887;3288.9506657400157;3687.649673281034;29410;29488;30425;31046.689533104607;31358.843205784055;3146.2449012237125;3180.8568614412407;3239.805611663308;3250.837458127084;3257.609086909586;264388;264673;265057;266910.66544667346;267377;53639.19541206885;53902.46097539053;54003.459965399845;54026.189607155924;54084.18873716897;32565.348693026008;34027.829860850536;34046.65953340485;36201.45697814535;36411.087113030895;15350.769738453931;15516.534503964898;15523.146226957626;15527.534173974798;15531.5340539784;11673.708157295965;11782.116341274439;11782.410879455992;11791.349211110452;11793.587224447103;18441;18520.536986575444;18520.722189167172;18529.53676158107;18844.717329240073;6665.300143484864;6757.189137303555;6861.897071543931;6930.569368162691;7174.713011479483;789.135896193667;850.5279569838675;858.7767180533107;952.7094236258008;975.1223898491414;27593.44813103727;28566.71433285682;28847.855760721483;29094.85452572766;29095.418091638472;796.8406318736276;958.7459323279337;979.2264111351952;984.2519685039422;1008.4604736465956;14213.360398782079;14922.403103875724;14926.626834329229;14989.70020599582;15249.085054896741;451914;455079;455621;456724;457651;49100.50899490959;49716.254256186185;49804;49812.50187498151;49914.50085499171;1078.4338222433284;1145.7937571237258;1197.7245233596323;1206.5053328135423;1216.8661447240797;27674.723252767213;28820;28991.42017159603;29389.17382901269;29587;42749.35875961925;43030.569694302656;43200.99999999874;43355.34966975561;43516.34725479057;330153;331583;331619;332099;333726;22366.77633223647;24450.388740280923;24780.38049048788;25371.746282536937;25725.871370643028;35928.64071359253;36503.63496365002;46194;46781;47498.52501475079;36412.81793591015;36921.44617830681;37228.62771372252;37447.25105497929;37639.247215054995;36378.45432318465;36502.39860444786;36973.63026369702;37213.44179837251;38099.80950095232;35527.11182220465;35904;36108.81945590255;36109.27781444409;36736.26527469382;31321;32920.67079329176;33381.49927751134;33523.1619209512;33933.830330848185;25177.370565736004;25851.353716157246;25961.091361803003;26025.869870650524;26074.348141296618 10:10:13 INFO - PROCESS | 18836 | |2;jquery.html;347.7044512164659;367.34245700196647;368.2395852564454;369.2006805266597;386.3586446498812;12890.291033993126;16333.5916602085;16780.244888979978;18412.171452284645;18516.166772495235;17639.91180044098;17667.116644167803;17760.46718598441;17858.285668573273;18038.729419058724;4808.663393562443;5108.719020453874;5122.564582010534;5126.666766660155;5138.922916156261;3764.3036038333;5094.872628184306;5181.248718935752;5188.247704082905;5704.543636509069;5961.523078153759;6031.879362412749;6036.456718895299;6047.909281360783;6068.362821903703;5838.795642152547;5971.104334349839;6030.96984515079;6033.668148251844;6036.12476190952;3465.376232278189;5313;5314.415414304423;5317.601179911522;5318.574514038867;16931.830681693147;20512.487187820276;20746;21818.78181218199;21948.89025554878;1848.5840685845712;1963.430605124512;1981.837289076604;2162.76209616942;2343.109618345035;13286.734265314737;13289.601311960656;13354.532591359353;13360.532381366605;14148.646283842883;1586.373382513912;1631.119195634356;1656.2546853915665;1660.3690597572966;1672.7072762266675;68819.31180688179;70280;72208.63895680488;73308.26691733122;73714.26285737127;9219.308551858638;9275.30435217348;9322.440653560672;9467.857982130274;9610.471424071744;12927;12970.546030888874;12990.935045324713;12999.025073119554;13015.934920325337;7301.963490182515;7409.555426674417;7439.4420418468835;7513.135989361184;7530.472866899265;7226.421886249087;7261.2012678605315;7331.486795924234;7410.814729631802;7491.925080749232;5912.408759124053;6013.759449622054;6062.545309101747;6071.2714474263375;6150.261968563801;1626.1381467822073;1651.4914284004735;1677.3039188736539;1720.8365205305477;1752.1939907642627;854.7008547008497;895.4269267668698;932.4032619123766;984.0897170117613;1003.5233264199593;17724.20241089154;18039.368622098427;18387.936970471263;18393.724094138597;18680.906595467208;844.5101840932244;880.1858281089966;881.823635272948;889.915458031486;907.600655711498;9984.900150998543;10225.642102526524;10436.634717784993;10481.633142839964;10557.102646275152;403948;405760;406347;419389;420129;73125.6343718278;74129.62935185398;75260.6918552483;75902.24097759063;77153.61423192848;927.8469052606245;937.5640327247862;966.3428868369435;966.9806603867884;967.2117224462041;15692.450764223197;15752.133632649857;15900.204989750235;16391.34434622602;16820.158992050103;25392.746072539037;35814.46278305775;36207.818960905024;37676;37944.620553794106;48118.5188148114;48390.27414588714;48418.273725893436;48506;48616.2707559394;5628.902364038973;6870.725170993104;7691.30778229962;7866.331361834308;8298.336133109216;11531.942340288246;11574;11892.881071189177;12280.385980701109;12370.81443778362;10651.094656953936;10910.399568142653;10996.875264755241;11091.72270693239;11209.43952802373;10584.947075264574;10603.257771955889;10993.560257589606;11002.944985275022;11140.721481962692;10293.897061029593;10538.367697938149;10782.568697252022;10951.34291942486;10999.61501347449;6661.367170118831;7718.845623087619;7900.3284720799375;7930.532851422275;8052.83894322122;9608.75978100553;10061.496925153566;10371.688849334532;10587.20595955291;11030.669079927615 10:10:13 INFO - PROCESS | 18836 | |3;mootools.html;1254.4668515880749;1385.1066062389757;1394.1008049807872;1497.1915165810462;1734.0809371033356;6579.736810527579;7594.392448604112;7892.092409372924;7896.960515197423;8090.91909080909;7931.444798864082;7987.680492780281;7989.280964713174;7995.840083198333;7996.200379962001;1893.1953919584191;2083.4374718825907;2195.2426412887567;2218.0240694094628;2223.8109760670363;2205.867647941129;2234.0951914474663;2262.5814224368546;2269.103704036904;2271.9659205111852;2135.252661568454;2159.2442645074257;2172.2722887832515;2174.608570457325;2179.8256139508803;1849.3157531713218;1907.895065771382;2139.7860213978556;2141.261264863612;2150.0969592771075;1943.6015616798522;1964.7642282925997;2110.53568214994;2186.267600353883;2191.6274233380364;7810.453268271223;7852.371810255166;7864.68541258349;7869.213078692085;7878;4628.8148474061;4767.952320476786;4773.76131193439;4786.760661966922;4787.688800227974;2872.152714949074;2911.621489206411;2944.131481213026;2945.7496112830436;2947.7936544441895;921.3504479342115;926.073926073926;930.186087173723;955.7132860141999;972.2756546372998;10496.842547361795;10649.84025239622;10661.680149595448;10671;10687;4167.187398457272;4265;4326.848560300375;4360.520342762294;4386.2762644163995;8496.320294376435;8550.358723095793;8551.74344769658;8558.743237702878;8567.100454452317;4669.4396672399525;4690.108879312919;4694.36626055485;4694.741789201558;4699.177643912301;3719.8698045568276;3807.3337165996395;4323.524412314643;4376.956230437655;4381.079973205598;3453.3093381323333;3470.774399664014;3551.5027896094566;3566.3045706087064;3579.552555930509;1021.3463383434607;1069.0645685025602;1079.2660990526517;1087.0705546757567;1097.4896673047072;796.6574373019657;834.1200033964104;835.5738573327525;837.5226121110908;848.9066202717696;5147.6654017488745;5194.246834209019;5232.502912223333;5303.4696530346655;5330.653507521999;852.4416507187802;885.6014793343021;902.1429641840147;924.2605915267759;957.1624828275259;3522.9295414091575;3534.3991521441417;3625.782453052825;3707.703383729295;3746.83139258734;9755.853662195073;9764.072413120743;9813.264005199639;9897.059779321096;9900;9775.902240977499;9813.509324533745;9883.703488895344;9895.851562226573;9896.802063958825;7298.489105762652;7512.774616761397;7520.1351844537485;7528.698852045966;7539.585322807188;5015.1474249378425;5360.812371566976;5377.193420986883;5416.593755468277;5620.1288800236025;8548;8863.157999989991;9011.2790976723;9044.010317730088;9070.81858362816;9054.366194366332;9061.365704400629;9088.363814532919;9175.908240917506;9188.081191881025;6901.723931042702;7063.82340441493;7066.328698773608;7072.2220555737795;7077.504574679723;7247.383972362198;7404.96297518509;7418.443616728875;7457.515261507984;7473.701051957861;6979.581225126509;6993.965030174817;6997.545159564612;7064.258252883374;7214.098237720285;6976.120736732404;7079.681414336264;7148.64256787169;7166.713331466683;7185.928140718735;6069.2110025696375;6176.474999625082;6177.135201071764;6178.938210617836;6188.876222475616;4344.56554344563;4397.978010109929;4426.247537918626;4455.019895622958;4460.286354183315;4456.17560751262;4515.9548404515535;4593.678442508993;4612.146752850734;4645.326427667902 10:10:13 INFO - PROCESS | 18836 | |4;prototype.html;294.7473238735375;296.3051643895066;302.5461807289066;306.9340091880245;307.03284653341956;4731.810727570897;4758.191107511722;4761.214399624062;4781.497942716012;4781.737004464753;4750.619950403968;4752.928706069403;4753.762311884409;4758.05000899442;4758.428988521382;3614.6746792788645;3694.6305369463107;3736.6823819975198;3771.283456143324;3774.5659249186283;3797.544294684628;3799.4300854871713;3803.2013277211818;3806.638369354907;3813.218290250506;3777.2823163598964;3780.432935059749;3782.5460944686674;3787.4886890269736;3792.6586607205345;3607.6031636520092;3639.290338384016;3770.377887648535;3784.3944968804994;3794.2221844521805;3356;3368.5452463917304;3373.797572145679;3380.340833537461;3382.2051817822794;4654.953450465486;4668.486466488684;4676.836310729108;4681.88295292617;4682.742449165295;4153.626173644357;4298.677599180042;4303.589989605831;4306.44016277885;4339.546252005582;4968.7018778873025;4981.526754958273;4983.476734942815;4987.501249875019;4990.201567749143;3659.70722342212;3663.358912190356;3664.9083772905624;3673.94489082664;3681.1533347329996;5036.445990940957;5181.171012637998;5203.973980130113;5210.791568337262;5212.810619539802;3734.3278209922473;3762.454444105644;3775.679067279257;3782.149016471272;3789.5642001170218;3820.5988371220624;3822.7515211511163;3824.1204522959874;3825.713071519647;3827.119762454651;4295.398644189816;4295.484541854998;4302.225599392077;4305.590968857953;4305.9569404306185;4771.618270538349;4952.579030782424;4985.152524070844;4987.62592805541;4998.800047998112;1308.4112149532557;1316.0984725463088;1320.39261939507;1322.1934619881865;1326.6285440076795;828.3538839704919;852.6163226548078;861.8922634670666;862.7972426479772;863.2230992107154;4296.839853239608;4427.535108813592;4431.158079964796;4434.268345722984;4437.844675436344;5134.818991631871;5150.175971844487;5160.458151893997;5165.715885626327;5168.638195326366;4394.340848872695;4413.13943780966;4413.3159360299205;4428.180786554498;4433.202023635777;2566.114690431798;2714.24001279642;2737.7262273772462;2743.7530622244094;2751.2434080627986;4868.758466590996;4871.5128487151005;4871.637255930871;4871.658983871166;4881.487443818348;5006.4492905780335;5289.418164001957;5295.947040529622;5299.2845965794895;5305.761240744176;4875.585575226075;4881.585065269421;4896.5837903777865;4900.117978763857;4922.92615610759;5226.529612334908;5228;5233.319668443078;5238.71187084714;5239.214117882196;4874.756262186947;5042.84871453857;5077.974610126926;5091.719955402488;5093.261477085728;4869.780859861386;4875.90248195027;4877.585405240452;4881.51184881509;4895.46149923501;3648.4892115103376;3663.4796559428605;3678.705703543764;3682.9815850920572;3698.130939229333;4889.877753056202;5178.119719647593;5185.299984502044;5187.481251874782;5198.714070726146;5013.273075403973;5022.493252024305;5025.773840177128;5030.723310217973;5031.345925029723;5047.949520504748;5054.494550544916;5059.87350316245;5060.342155519907;5063.1898896176435;4829.420469543677;4978.083437876516;4981.576565991932;4986.404350607916;4995.325631039908;4750.216214324736;4790.808367665254;4953.380827396575;4959.3881988353205;4976.2038073908;4877.731724755031;4898.559129678346;4900.460949295646;4900.828471003498;4902.3872015998 10:10:13 INFO - PROCESS | 18836 | |5;yui.html;651.4657980456027;652.0707991112666;673.946084313255;714.4034730999615;757.1784613694142;12883.549075782337;13682.452701891913;13730.244836533986;13763.44946202152;13798.931005344984;13267.93366033171;13392.732145357088;13436.328183590831;13441.932790336059;13444.3950022249;611.1443978430211;773.5436092705303;777.246071310829;794.6185830801219;797.3541431440523;764.5756605084176;774.6746366526072;775.3215936055951;775.5695588948131;776.0687175389527;840.2983508770184;842.2546046748641;843.236870632079;843.6625349860044;843.7089204224533;649.9415052645285;804.9356051515865;831.4221615976899;840.4915026409026;841.2933136165589;614.7602435050334;616.4328817487922;616.7687117331001;618.005011930791;618.2210414877242;12734;12812.167209131376;12895.09734318598;12948.287844168566;13133.737325253442;12325.322107284095;12521.93739031299;12770.169938953939;12913.139913070245;13239.66900827487;10850.728731781768;11051.336919784759;11054.392008439532;11108.444577771079;11223.438828058564;4113.835446582133;4202.390653355277;4215.367694845767;4240.809163587646;4312.676549258818;61916;63159.05261421082;63195.36804631941;64002;64175.67912160456;9619.663311784054;9923.80152396948;10044.708351360907;10333.689989300334;10395.584176633001;18319.90840045818;18585.349512767258;18617.720734189;18620.81379186218;18624.72062919057;6661.16735408074;6698.330166983263;6781.830454238683;7036.964815175994;7150.964245178845;3709.0727318170457;3785.09157802131;3847.0574709196426;3860.942085868714;3895.2988462076532;2895.2327633177224;2988.2678743707497;2993.02726613847;3049.679783622732;3085.8148511088957;525.0601423451978;530.2391068816261;531.9547838433776;534.036064902854;549.2804426201689;512.293035610862;514.7194778845463;518.9195674670432;528.183955788308;536.656539814519;7171.644620019565;7379.520331178456;7448.553086814808;7605.961970190114;8046.195380462024;418.7299192021129;458.3193956973891;493.740786087307;501.0605115408609;549.4368272520618;3474.7741396809633;3480.3213373391945;3538.310029544214;3595.640435956436;3766.4162054881535;52301.2154817678;52783.736081319345;102121.3833239179;102485.48757256316;102769.48615256876;84215;84331.15668843233;85105.57447212725;85141;86477;73768.63115684495;78924.6053769739;79328;81230.59384703157;81466.59266703628;7099.36105750485;7198.676059577437;7357.337839594462;7585.658645361083;7588.165301816685;44719.776401117786;44829.77585112053;45811.54188458073;46649.30026049544;46680.76659616816;36821;37363.813180933925;56265;65135.022974656364;66743.33256667371;8540.27407670355;8759.430637008574;9021.774455638662;9121.863172052557;9178.403403778733;9500.334976551576;9647.662331818634;9702;9737.123658870736;9939.155171810477;8461.365397595328;8809.075047120217;8819.250363719155;8882.955585222033;9488.383255088396;8832.5583720815;8872.689455869013;9245.768855778659;9380.390274632127;9487.810243794947;9012.143846334588;9213.677521286723;9278.489683067226;9315.161635452841;9441.952790236004;8138.959305203436;8184.427090103635;8373.413861029669;8382.203690649378;8421.87367189505;8044.87932681022;8250.174982501941;8275.586220689062;8416.579171041545;8762.956185219033 10:10:13 INFO - PROCESS | 18836 | __end_tp_report 10:10:13 INFO - PROCESS | 18836 | __start_cc_report 10:10:13 INFO - PROCESS | 18836 | _x_x_mozilla_cycle_collect,989 10:10:13 INFO - PROCESS | 18836 | __end_cc_report 10:10:13 INFO - PROCESS | 18836 | __startTimestamp1469034613069__endTimestamp 10:10:13 INFO - PROCESS | 18836 | 10:10:13 INFO - PROCESS | 18836 | ------- Summary: start ------- 10:10:13 INFO - PROCESS | 18836 | Number of tests: 6 10:10:13 INFO - PROCESS | 18836 | 10:10:13 INFO - PROCESS | 18836 | [#0] dojo.html Cycles:165 Average:4675.24 Median:4771.45 stddev:498.25 (10.4%) stddev-sans-first:470.65 10:10:13 INFO - PROCESS | 18836 | Values: 2535.3 2585.4 2590.9 2633.0 2634.4 4563.4 4629.1 4703.8 4756.4 4763.5 4677.4 4718.8 4722.6 4740.1 4796.1 4319.3 4349.4 4392.6 4443.9 4482.6 4390.9 4417.4 4433.1 4446.5 4465.7 4192.6 4259.9 4273.7 4314.6 4410.4 4305.1 4312.4 4316.7 4364.4 4410.2 4308.5 4328.2 4332.0 4339.6 4377.2 4528.3 4645.3 4728.7 4775.5 4789.1 5013.5 5097.7 5146.0 5150.9 5168.7 5149.0 5158.7 5178.0 5192.3 5202.7 4671.1 4672.0 4703.4 4814.9 4817.0 5168.6 5193.9 5226.6 5235.0 5239.3 4958.6 4998.9 5005.1 5009.5 5033.5 4913.7 5036.2 5053.3 5073.4 5123.4 5114.2 5127.5 5130.2 5134.8 5141.5 5093.5 5103.4 5121.5 5133.0 5165.9 4229.5 4286.6 4300.2 4367.6 4449.6 3770.5 3771.7 3825.9 3853.0 3868.3 4303.7 4349.8 4361.1 4361.1 4404.5 4904.4 4975.4 5016.4 5019.7 5065.6 4273.0 4284.2 4302.9 4308.5 4367.8 4539.5 4546.9 4553.4 4558.7 4590.7 4609.9 4693.7 4745.0 4745.0 4758.9 5022.3 5085.7 5103.3 5156.1 5156.3 4811.4 4812.3 4822.3 4823.1 4871.4 5020.6 5046.6 5052.8 5059.6 5060.2 4984.0 4985.5 5023.0 5030.1 5116.7 4933.3 4934.2 4935.3 4938.3 4984.8 4906.4 4920.8 4925.5 4932.8 4977.5 4738.9 4757.0 4771.5 4776.1 4808.5 4798.7 4806.3 4809.8 4817.6 4857.6 4650.5 4694.5 4704.4 4707.1 4747.5 10:10:13 INFO - PROCESS | 18836 | 10:10:13 INFO - PROCESS | 18836 | [#1] ext.html Cycles:180 Average:49790.59 Median:36619.95 stddev:95142.04 (259.8%) stddev-sans-first:95337.13 10:10:13 INFO - PROCESS | 18836 | Values: 559.7 564.9 569.5 571.7 578.4 27305.2 27549.0 39971.2 40657.2 40758.4 41504.8 41731.0 41815.8 41831.2 42019.6 7839.9 7979.7 7990.9 7994.0 8008.0 7858.0 7923.1 7926.0 7936.0 7936.6 7920.4 7994.5 7998.0 7998.2 8004.0 7901.6 7944.4 7945.0 7945.6 7954.0 7861.9 7924.0 7930.4 7932.8 7946.3 39401.0 39812.4 39821.8 39828.4 39929.0 2955.3 2981.6 3078.7 3289.0 3687.6 29410.0 29488.0 30425.0 31046.7 31358.8 3146.2 3180.9 3239.8 3250.8 3257.6 264388.0 264673.0 265057.0 266910.7 267377.0 53639.2 53902.5 54003.5 54026.2 54084.2 32565.3 34027.8 34046.7 36201.5 36411.1 15350.8 15516.5 15523.1 15527.5 15531.5 11673.7 11782.1 11782.4 11791.3 11793.6 18441.0 18520.5 18520.7 18529.5 18844.7 6665.3 6757.2 6861.9 6930.6 7174.7 789.1 850.5 858.8 952.7 975.1 27593.4 28566.7 28847.9 29094.9 29095.4 796.8 958.7 979.2 984.3 1008.5 14213.4 14922.4 14926.6 14989.7 15249.1 451914.0 455079.0 455621.0 456724.0 457651.0 49100.5 49716.3 49804.0 49812.5 49914.5 1078.4 1145.8 1197.7 1206.5 1216.9 27674.7 28820.0 28991.4 29389.2 29587.0 42749.4 43030.6 43201.0 43355.3 43516.3 330153.0 331583.0 331619.0 332099.0 333726.0 22366.8 24450.4 24780.4 25371.7 25725.9 35928.6 36503.6 46194.0 46781.0 47498.5 36412.8 36921.4 37228.6 37447.3 37639.2 36378.5 36502.4 36973.6 37213.4 38099.8 35527.1 35904.0 36108.8 36109.3 36736.3 31321.0 32920.7 33381.5 33523.2 33933.8 25177.4 25851.4 25961.1 26025.9 26074.3 10:10:13 INFO - PROCESS | 18836 | 10:10:13 INFO - PROCESS | 18836 | [#2] jquery.html Cycles:180 Average:25064.26 Median:202167.18 stddev:67677.25 (33.5%) stddev-sans-first:67841.66 10:10:13 INFO - PROCESS | 18836 | Values: 347.7 367.3 368.2 369.2 386.4 12890.3 16333.6 16780.2 18412.2 18516.2 17639.9 17667.1 17760.5 17858.3 18038.7 4808.7 5108.7 5122.6 5126.7 5138.9 3764.3 5094.9 5181.2 5188.2 5704.5 5961.5 6031.9 6036.5 6047.9 6068.4 5838.8 5971.1 6031.0 6033.7 6036.1 3465.4 5313.0 5314.4 5317.6 5318.6 16931.8 20512.5 20746.0 21818.8 21948.9 1848.6 1963.4 1981.8 2162.8 2343.1 13286.7 13289.6 13354.5 13360.5 14148.6 1586.4 1631.1 1656.3 1660.4 1672.7 68819.3 70280.0 72208.6 73308.3 73714.3 9219.3 9275.3 9322.4 9467.9 9610.5 12927.0 12970.5 12990.9 12999.0 13015.9 7302.0 7409.6 7439.4 7513.1 7530.5 7226.4 7261.2 7331.5 7410.8 7491.9 5912.4 6013.8 6062.5 6071.3 6150.3 1626.1 1651.5 1677.3 1720.8 1752.2 854.7 895.4 932.4 984.1 1003.5 17724.2 18039.4 18387.9 18393.7 18680.9 844.5 880.2 881.8 889.9 907.6 9984.9 10225.6 10436.6 10481.6 10557.1 403948.0 405760.0 406347.0 419389.0 420129.0 73125.6 74129.6 75260.7 75902.2 77153.6 927.8 937.6 966.3 967.0 967.2 15692.5 15752.1 15900.2 16391.3 16820.2 25392.7 35814.5 36207.8 37676.0 37944.6 48118.5 48390.3 48418.3 48506.0 48616.3 5628.9 6870.7 7691.3 7866.3 8298.3 11531.9 11574.0 11892.9 12280.4 12370.8 10651.1 10910.4 10996.9 11091.7 11209.4 10584.9 10603.3 10993.6 11002.9 11140.7 10293.9 10538.4 10782.6 10951.3 10999.6 6661.4 7718.8 7900.3 7930.5 8052.8 9608.8 10061.5 10371.7 10587.2 11030.7 10:10:13 INFO - PROCESS | 18836 | 10:10:13 INFO - PROCESS | 18836 | [#3] mootools.html Cycles:180 Average:5164.77 Median:5345.73 stddev:2910.34 (54.4%) stddev-sans-first:2903.66 10:10:13 INFO - PROCESS | 18836 | Values: 1254.5 1385.1 1394.1 1497.2 1734.1 6579.7 7594.4 7892.1 7897.0 8090.9 7931.4 7987.7 7989.3 7995.8 7996.2 1893.2 2083.4 2195.2 2218.0 2223.8 2205.9 2234.1 2262.6 2269.1 2272.0 2135.3 2159.2 2172.3 2174.6 2179.8 1849.3 1907.9 2139.8 2141.3 2150.1 1943.6 1964.8 2110.5 2186.3 2191.6 7810.5 7852.4 7864.7 7869.2 7878.0 4628.8 4768.0 4773.8 4786.8 4787.7 2872.2 2911.6 2944.1 2945.7 2947.8 921.4 926.1 930.2 955.7 972.3 10496.8 10649.8 10661.7 10671.0 10687.0 4167.2 4265.0 4326.8 4360.5 4386.3 8496.3 8550.4 8551.7 8558.7 8567.1 4669.4 4690.1 4694.4 4694.7 4699.2 3719.9 3807.3 4323.5 4377.0 4381.1 3453.3 3470.8 3551.5 3566.3 3579.6 1021.3 1069.1 1079.3 1087.1 1097.5 796.7 834.1 835.6 837.5 848.9 5147.7 5194.2 5232.5 5303.5 5330.7 852.4 885.6 902.1 924.3 957.2 3522.9 3534.4 3625.8 3707.7 3746.8 9755.9 9764.1 9813.3 9897.1 9900.0 9775.9 9813.5 9883.7 9895.9 9896.8 7298.5 7512.8 7520.1 7528.7 7539.6 5015.1 5360.8 5377.2 5416.6 5620.1 8548.0 8863.2 9011.3 9044.0 9070.8 9054.4 9061.4 9088.4 9175.9 9188.1 6901.7 7063.8 7066.3 7072.2 7077.5 7247.4 7405.0 7418.4 7457.5 7473.7 6979.6 6994.0 6997.5 7064.3 7214.1 6976.1 7079.7 7148.6 7166.7 7185.9 6069.2 6176.5 6177.1 6178.9 6188.9 4344.6 4398.0 4426.2 4455.0 4460.3 4456.2 4516.0 4593.7 4612.1 4645.3 10:10:13 INFO - PROCESS | 18836 | 10:10:13 INFO - PROCESS | 18836 | [#4] prototype.html Cycles:180 Average:4179.27 Median:4758.12 stddev:1198.02 (25.2%) stddev-sans-first:1165.37 10:10:13 INFO - PROCESS | 18836 | Values: 294.7 296.3 302.5 306.9 307.0 4731.8 4758.2 4761.2 4781.5 4781.7 4750.6 4752.9 4753.8 4758.1 4758.4 3614.7 3694.6 3736.7 3771.3 3774.6 3797.5 3799.4 3803.2 3806.6 3813.2 3777.3 3780.4 3782.5 3787.5 3792.7 3607.6 3639.3 3770.4 3784.4 3794.2 3356.0 3368.5 3373.8 3380.3 3382.2 4655.0 4668.5 4676.8 4681.9 4682.7 4153.6 4298.7 4303.6 4306.4 4339.5 4968.7 4981.5 4983.5 4987.5 4990.2 3659.7 3663.4 3664.9 3673.9 3681.2 5036.4 5181.2 5204.0 5210.8 5212.8 3734.3 3762.5 3775.7 3782.1 3789.6 3820.6 3822.8 3824.1 3825.7 3827.1 4295.4 4295.5 4302.2 4305.6 4306.0 4771.6 4952.6 4985.2 4987.6 4998.8 1308.4 1316.1 1320.4 1322.2 1326.6 828.4 852.6 861.9 862.8 863.2 4296.8 4427.5 4431.2 4434.3 4437.8 5134.8 5150.2 5160.5 5165.7 5168.6 4394.3 4413.1 4413.3 4428.2 4433.2 2566.1 2714.2 2737.7 2743.8 2751.2 4868.8 4871.5 4871.6 4871.7 4881.5 5006.4 5289.4 5295.9 5299.3 5305.8 4875.6 4881.6 4896.6 4900.1 4922.9 5226.5 5228.0 5233.3 5238.7 5239.2 4874.8 5042.8 5078.0 5091.7 5093.3 4869.8 4875.9 4877.6 4881.5 4895.5 3648.5 3663.5 3678.7 3683.0 3698.1 4889.9 5178.1 5185.3 5187.5 5198.7 5013.3 5022.5 5025.8 5030.7 5031.3 5047.9 5054.5 5059.9 5060.3 5063.2 4829.4 4978.1 4981.6 4986.4 4995.3 4750.2 4790.8 4953.4 4959.4 4976.2 4877.7 4898.6 4900.5 4900.8 4902.4 10:10:13 INFO - PROCESS | 18836 | 10:10:13 INFO - PROCESS | 18836 | [#5] yui.html Cycles:180 Average:16812.58 Median:64088.84 stddev:24655.58 (38.5%) stddev-sans-first:24694.88 10:10:13 INFO - PROCESS | 18836 | Values: 651.5 652.1 673.9 714.4 757.2 12883.5 13682.5 13730.2 13763.4 13798.9 13267.9 13392.7 13436.3 13441.9 13444.4 611.1 773.5 777.2 794.6 797.4 764.6 774.7 775.3 775.6 776.1 840.3 842.3 843.2 843.7 843.7 649.9 804.9 831.4 840.5 841.3 614.8 616.4 616.8 618.0 618.2 12734.0 12812.2 12895.1 12948.3 13133.7 12325.3 12521.9 12770.2 12913.1 13239.7 10850.7 11051.3 11054.4 11108.4 11223.4 4113.8 4202.4 4215.4 4240.8 4312.7 61916.0 63159.1 63195.4 64002.0 64175.7 9619.7 9923.8 10044.7 10333.7 10395.6 18319.9 18585.3 18617.7 18620.8 18624.7 6661.2 6698.3 6781.8 7037.0 7151.0 3709.1 3785.1 3847.1 3860.9 3895.3 2895.2 2988.3 2993.0 3049.7 3085.8 525.1 530.2 532.0 534.0 549.3 512.3 514.7 518.9 528.2 536.7 7171.6 7379.5 7448.6 7606.0 8046.2 418.7 458.3 493.7 501.1 549.4 3474.8 3480.3 3538.3 3595.6 3766.4 52301.2 52783.7 102121.4 102485.5 102769.5 84215.0 84331.2 85105.6 85141.0 86477.0 73768.6 78924.6 79328.0 81230.6 81466.6 7099.4 7198.7 7357.3 7585.7 7588.2 44719.8 44829.8 45811.5 46649.3 46680.8 36821.0 37363.8 56265.0 65135.0 66743.3 8540.3 8759.4 9021.8 9121.9 9178.4 9500.3 9647.7 9702.0 9737.1 9939.2 8461.4 8809.1 8819.3 8883.0 9488.4 8832.6 8872.7 9245.8 9380.4 9487.8 9012.1 9213.7 9278.5 9315.2 9442.0 8139.0 8184.4 8373.4 8382.2 8421.9 8044.9 8250.2 8275.6 8416.6 8763.0 10:10:13 INFO - PROCESS | 18836 | -------- Summary: end -------- 10:10:13 INFO - PROCESS | 18836 | 10:10:13 INFO - TEST-INFO | 18836: exit 0 10:10:13 INFO - TEST-OK | dromaeo_css | took 1119032ms 10:10:13 INFO - TEST-START | kraken 10:10:13 INFO - operating with platform_type : linux_ 10:10:13 INFO - Initialising browser for kraken test... 10:10:13 INFO - TEST-INFO | started process 19022 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWYkHl7/profile http://localhost:60205/getInfo.html) 10:10:18 INFO - PROCESS | 19022 | __metrics Screen width/height:1600/1200 10:10:18 INFO - PROCESS | 19022 | colorDepth:24 10:10:18 INFO - PROCESS | 19022 | Browser inner width/height: 1024/697 10:10:18 INFO - PROCESS | 19022 | __metrics 10:10:18 INFO - PROCESS | 19022 | 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" 10:10:18 INFO - PROCESS | 19022 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:10:19 INFO - TEST-INFO | 19022: exit 0 10:10:19 INFO - Browser initialized. 10:10:19 INFO - Running cycle 1/1 for kraken test... 10:10:19 INFO - TEST-INFO | started process 19102 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWYkHl7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:10:31 INFO - PROCESS | 19102 | RSS: Main: 158236672 10:10:31 INFO - PROCESS | 19102 | 10:10:34 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60205/tests/kraken/driver.html?testName=audio-beat-detection) 10:10:35 INFO - PROCESS | 19102 | RSS: Main: 214466560 10:10:35 INFO - PROCESS | 19102 | 10:10:38 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60205/tests/kraken/driver.html?testName=audio-dft) 10:10:38 INFO - PROCESS | 19102 | RSS: Main: 192606208 10:10:38 INFO - PROCESS | 19102 | 10:10:42 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60205/tests/kraken/driver.html?testName=audio-fft) 10:10:42 INFO - PROCESS | 19102 | RSS: Main: 272740352 10:10:42 INFO - PROCESS | 19102 | 10:10:44 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60205/tests/kraken/driver.html?testName=audio-oscillator) 10:10:45 INFO - PROCESS | 19102 | RSS: Main: 181571584 10:10:45 INFO - PROCESS | 19102 | 10:10:47 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60205/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:10:48 INFO - PROCESS | 19102 | RSS: Main: 187031552 10:10:48 INFO - PROCESS | 19102 | 10:10:51 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60205/tests/kraken/driver.html?testName=imaging-darkroom) 10:10:51 INFO - PROCESS | 19102 | RSS: Main: 222814208 10:10:51 INFO - PROCESS | 19102 | 10:10:55 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60205/tests/kraken/driver.html?testName=imaging-desaturate) 10:10:56 INFO - PROCESS | 19102 | RSS: Main: 217124864 10:10:56 INFO - PROCESS | 19102 | 10:10:58 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60205/tests/kraken/driver.html?testName=json-parse-financial) 10:10:59 INFO - PROCESS | 19102 | RSS: Main: 219574272 10:10:59 INFO - PROCESS | 19102 | 10:11:01 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60205/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:11:01 INFO - PROCESS | 19102 | RSS: Main: 185303040 10:11:01 INFO - PROCESS | 19102 | 10:11:03 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60205/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:11:04 INFO - PROCESS | 19102 | RSS: Main: 191651840 10:11:04 INFO - PROCESS | 19102 | 10:11:06 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60205/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:11:07 INFO - PROCESS | 19102 | RSS: Main: 195522560 10:11:07 INFO - PROCESS | 19102 | 10:11:09 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60205/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:11:10 INFO - PROCESS | 19102 | RSS: Main: 190554112 10:11:10 INFO - PROCESS | 19102 | 10:11:13 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60205/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:11:14 INFO - PROCESS | 19102 | RSS: Main: 194273280 10:11:14 INFO - PROCESS | 19102 | 10:11:15 INFO - PROCESS | 19102 | Cycle 1(1): loaded http://localhost:60205/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60205/tests/kraken/driver.html?testName=ai-astar) 10:11:16 INFO - PROCESS | 19102 | RSS: Main: 245010432 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | __start_tp_report 10:11:16 INFO - PROCESS | 19102 | _x_x_mozilla_page_load 10:11:16 INFO - PROCESS | 19102 | _x_x_mozilla_page_load_details 10:11:16 INFO - PROCESS | 19102 | |i|pagename|runs| 10:11:16 INFO - PROCESS | 19102 | |0;ai-astar;105;116;114;113;115;114;113;116;113;113 10:11:16 INFO - PROCESS | 19102 | |1;audio-beat-detection;148;121;122;179;125;123;153;123;163;122 10:11:16 INFO - PROCESS | 19102 | |2;audio-dft;161;162;160;159;160;161;161;160;161;161 10:11:16 INFO - PROCESS | 19102 | |3;audio-fft;75;76;78;106;76;76;76;75;112;75 10:11:16 INFO - PROCESS | 19102 | |4;audio-oscillator;85;84;118;85;85;86;118;84;85;121 10:11:16 INFO - PROCESS | 19102 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;117;117 10:11:16 INFO - PROCESS | 19102 | |6;imaging-darkroom;147;146;147;146;146;146;147;146;146;145 10:11:16 INFO - PROCESS | 19102 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 10:11:16 INFO - PROCESS | 19102 | |8;json-parse-financial;65;63;63;63;64;65;64;63;63;62 10:11:16 INFO - PROCESS | 19102 | |9;json-stringify-tinderbox;65;67;65;66;66;65;65;66;67;66 10:11:16 INFO - PROCESS | 19102 | |10;stanford-crypto-aes;79;82;84;83;84;80;80;85;78;86 10:11:16 INFO - PROCESS | 19102 | |11;stanford-crypto-ccm;130;132;131;127;128;137;133;146;168;130 10:11:16 INFO - PROCESS | 19102 | |12;stanford-crypto-pbkdf2;166;172;172;164;163;165;163;167;168;163 10:11:16 INFO - PROCESS | 19102 | |13;stanford-crypto-sha256-iterative;67;67;68;68;65;69;65;66;65;64 10:11:16 INFO - PROCESS | 19102 | __end_tp_report 10:11:16 INFO - PROCESS | 19102 | __start_cc_report 10:11:16 INFO - PROCESS | 19102 | _x_x_mozilla_cycle_collect,1937 10:11:16 INFO - PROCESS | 19102 | __end_cc_report 10:11:16 INFO - PROCESS | 19102 | __startTimestamp1469034676083__endTimestamp 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | ------- Summary: start ------- 10:11:16 INFO - PROCESS | 19102 | Number of tests: 14 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:3.12 (2.7%) stddev-sans-first:1.27 10:11:16 INFO - PROCESS | 19102 | Values: 105.0 116.0 114.0 113.0 115.0 114.0 113.0 116.0 113.0 113.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:136.50 stddev:21.22 (15.5%) stddev-sans-first:22.19 10:11:16 INFO - PROCESS | 19102 | Values: 148.0 121.0 122.0 179.0 125.0 123.0 153.0 123.0 163.0 122.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 10:11:16 INFO - PROCESS | 19102 | Values: 161.0 162.0 160.0 159.0 160.0 161.0 161.0 160.0 161.0 161.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#3] audio-fft Cycles:10 Average:82.50 Median:76.00 stddev:14.07 (18.5%) stddev-sans-first:14.65 10:11:16 INFO - PROCESS | 19102 | Values: 75.0 76.0 78.0 106.0 76.0 76.0 76.0 75.0 112.0 75.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:85.00 stddev:16.52 (19.4%) stddev-sans-first:17.12 10:11:16 INFO - PROCESS | 19102 | Values: 85.0 84.0 118.0 85.0 85.0 86.0 118.0 84.0 85.0 121.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:11:16 INFO - PROCESS | 19102 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 10:11:16 INFO - PROCESS | 19102 | Values: 147.0 146.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 145.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:11:16 INFO - PROCESS | 19102 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:63.50 stddev:0.97 (1.5%) stddev-sans-first:0.87 10:11:16 INFO - PROCESS | 19102 | Values: 65.0 63.0 63.0 63.0 64.0 65.0 64.0 63.0 63.0 62.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 10:11:16 INFO - PROCESS | 19102 | Values: 65.0 67.0 65.0 66.0 66.0 65.0 65.0 66.0 67.0 66.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:83.50 stddev:2.73 (3.3%) stddev-sans-first:2.65 10:11:16 INFO - PROCESS | 19102 | Values: 79.0 82.0 84.0 83.0 84.0 80.0 80.0 85.0 78.0 86.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:132.50 stddev:12.43 (9.4%) stddev-sans-first:12.99 10:11:16 INFO - PROCESS | 19102 | Values: 130.0 132.0 131.0 127.0 128.0 137.0 133.0 146.0 168.0 130.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:166.50 stddev:3.47 (2.1%) stddev-sans-first:3.67 10:11:16 INFO - PROCESS | 19102 | Values: 166.0 172.0 172.0 164.0 163.0 165.0 163.0 167.0 168.0 163.0 10:11:16 INFO - PROCESS | 19102 | 10:11:16 INFO - PROCESS | 19102 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.65 (2.5%) stddev-sans-first:1.73 10:11:16 INFO - PROCESS | 19102 | Values: 67.0 67.0 68.0 68.0 65.0 69.0 65.0 66.0 65.0 64.0 10:11:16 INFO - PROCESS | 19102 | -------- Summary: end -------- 10:11:16 INFO - PROCESS | 19102 | 10:11:17 INFO - TEST-INFO | 19102: exit 0 10:11:17 INFO - TEST-OK | kraken | took 63319ms 10:11:17 INFO - SUITE-END | took 1182s 10:11:17 INFO - Completed test suite (00:19:42) 10:11:17 INFO - javascript benchmark 10:11:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2535.252100630314, 2585.43120513487, 2590.9222723318285, 2632.9736702632968, 2634.3940893594463, 4563.429571303587, 4629.120467111255, 4703.764811759411, 4756.405449318835, 4763.4522029966565, 4677.415323084614, 4718.811247550094, 4722.551357621029, 4740.07568524138, 4796.088743138801, 4319.265724826779, 4349.391085248068, 4392.626626736731, 4443.933340999872, 4482.641388688897, 4390.868273951771, 4417.447819022622, 4433.113377324532, 4446.510883802779, 4465.70972886763, 4192.601702838256, 4259.91480170398, 4273.658107351404, 4314.568543145691, 4410.404595379631, 4305.052888364586, 4312.374705667692, 4316.6978311518205, 4364.388985542027, 4410.16206920684, 4308.5045219799495, 4328.177646247234, 4332.0, 4339.587739164775, 4377.190219809346, 4528.25283828168, 4645.303204519315, 4728.692634978715, 4775.498572649856, 4789.066132104241, 5013.523715247045, 5097.745112744348, 5146.022255771391, 5150.922736158961, 5168.689878607295, 5148.974255128738, 5158.74206289684, 5178.0, 5192.3249977503065, 5202.661826981234, 4671.11248862715, 4671.95328046722, 4703.435587729426, 4814.879628009257, 4817.036592681443, 5168.61235407346, 5193.948060519422, 5226.555742761831, 5235.031519168966, 5239.266502689627, 4958.603311735053, 4998.850034498971, 5005.074061298671, 5009.499050095034, 5033.496650335011, 4913.70517768935, 5036.169032109734, 5053.267276244924, 5073.365829271341, 5123.359580052494, 5114.156164232934, 5127.461616530285, 5130.204818253177, 5134.768935397916, 5141.511556402136, 5093.541581257705, 5103.387593488805, 5121.4622464641425, 5132.9743351283005, 5165.870853228625, 4229.492460904649, 4286.571342865751, 4300.161468513672, 4367.58507941745, 4449.599536041707, 3770.472133901256, 3771.6982641389172, 3825.8660946866726, 3853.0367408147963, 3868.342381795045, 4303.698741088157, 4349.804258808361, 4361.0841723237845, 4361.127774445124, 4404.515503294635, 4904.411470623548, 4975.378077740283, 5016.44819069902, 5019.7239151847, 5065.594752419797, 4272.957270427318, 4284.1645879053285, 4302.89242768927, 4308.46144231971, 4367.847125350598, 4539.477960034572, 4546.909061818811, 4553.430821147356, 4558.680892337505, 4590.655700822452, 4609.884752881205, 4693.741844198602, 4744.952550474451, 4744.95255047452, 4758.929240920766, 5022.3219865317715, 5085.720285384192, 5103.260027296095, 5156.097682905402, 5156.303898973741, 4811.446683631427, 4812.25410061441, 4822.276658501253, 4823.083614113307, 4871.439784424837, 5020.598352131894, 5046.596272298134, 5052.7726252318, 5059.620528460454, 5060.240963855451, 4984.028114517634, 4985.476524964826, 5023.020511000466, 5030.0694371541385, 5116.692998420107, 4933.284673722362, 4934.235193545006, 4935.284383764262, 4938.333325001079, 4984.825531106539, 4906.435759887659, 4920.753962301942, 4925.458199598113, 4932.7533623319405, 4977.527134922177, 4738.881527961691, 4757.0, 4771.451283102488, 4776.068666609977, 4808.495108013608, 4798.688085274446, 4806.279058141306, 4809.807607695793, 4817.614590832655, 4857.587105096107, 4650.488446270975, 4694.530546945278, 4704.3649107370065, 4707.069092942848, 4747.477777444409], "value": 4642.762376968701, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.7061542690088, 564.9067903795874, 569.5187566506303, 571.6570057965224, 578.4302462074857, 27305.18084457467, 27549.0, 39971.20057598846, 40657.18685626286, 40758.388624170664, 41504.792476037655, 41731.0, 41815.79092104535, 41831.163376732526, 42019.57980420195, 7839.882401763978, 7979.720709775159, 7990.920090799104, 7993.96003019987, 8007.95996020022, 7858.0, 7923.088844782837, 7926.0, 7936.047674279094, 7936.603169841514, 7920.36637069036, 7994.48035877669, 7998.0, 7998.240167184108, 8003.95998020012, 7901.604919754018, 7944.404169687296, 7944.960275198645, 7945.642446089911, 7954.0, 7861.921380786176, 7924.0, 7930.405219608495, 7932.801679957989, 7946.324562412202, 39401.0, 39812.402813957524, 39821.80089099565, 39828.402573961415, 39929.0, 2955.275957390453, 2981.5825784390204, 3078.738307243887, 3288.9506657400157, 3687.649673281034, 29410.0, 29488.0, 30425.0, 31046.689533104607, 31358.843205784055, 3146.2449012237125, 3180.8568614412407, 3239.805611663308, 3250.837458127084, 3257.609086909586, 264388.0, 264673.0, 265057.0, 266910.66544667346, 267377.0, 53639.19541206885, 53902.46097539053, 54003.459965399845, 54026.189607155924, 54084.18873716897, 32565.348693026008, 34027.829860850536, 34046.65953340485, 36201.45697814535, 36411.087113030895, 15350.769738453931, 15516.534503964898, 15523.146226957626, 15527.534173974798, 15531.5340539784, 11673.708157295965, 11782.116341274439, 11782.410879455992, 11791.349211110452, 11793.587224447103, 18441.0, 18520.536986575444, 18520.722189167172, 18529.53676158107, 18844.717329240073, 6665.300143484864, 6757.189137303555, 6861.897071543931, 6930.569368162691, 7174.713011479483, 789.135896193667, 850.5279569838675, 858.7767180533107, 952.7094236258008, 975.1223898491414, 27593.44813103727, 28566.71433285682, 28847.855760721483, 29094.85452572766, 29095.418091638472, 796.8406318736276, 958.7459323279337, 979.2264111351952, 984.2519685039422, 1008.4604736465956, 14213.360398782079, 14922.403103875724, 14926.626834329229, 14989.70020599582, 15249.085054896741, 451914.0, 455079.0, 455621.0, 456724.0, 457651.0, 49100.50899490959, 49716.254256186185, 49804.0, 49812.50187498151, 49914.50085499171, 1078.4338222433284, 1145.7937571237258, 1197.7245233596323, 1206.5053328135423, 1216.8661447240797, 27674.723252767213, 28820.0, 28991.42017159603, 29389.17382901269, 29587.0, 42749.35875961925, 43030.569694302656, 43200.99999999874, 43355.34966975561, 43516.34725479057, 330153.0, 331583.0, 331619.0, 332099.0, 333726.0, 22366.77633223647, 24450.388740280923, 24780.38049048788, 25371.746282536937, 25725.871370643028, 35928.64071359253, 36503.63496365002, 46194.0, 46781.0, 47498.52501475079, 36412.81793591015, 36921.44617830681, 37228.62771372252, 37447.25105497929, 37639.247215054995, 36378.45432318465, 36502.39860444786, 36973.63026369702, 37213.44179837251, 38099.80950095232, 35527.11182220465, 35904.0, 36108.81945590255, 36109.27781444409, 36736.26527469382, 31321.0, 32920.67079329176, 33381.49927751134, 33523.1619209512, 33933.830330848185, 25177.370565736004, 25851.353716157246, 25961.091361803003, 26025.869870650524, 26074.348141296618], "value": 17577.049040463487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.7044512164659, 367.34245700196647, 368.2395852564454, 369.2006805266597, 386.3586446498812, 12890.291033993126, 16333.5916602085, 16780.244888979978, 18412.171452284645, 18516.166772495235, 17639.91180044098, 17667.116644167803, 17760.46718598441, 17858.285668573273, 18038.729419058724, 4808.663393562443, 5108.719020453874, 5122.564582010534, 5126.666766660155, 5138.922916156261, 3764.3036038333, 5094.872628184306, 5181.248718935752, 5188.247704082905, 5704.543636509069, 5961.523078153759, 6031.879362412749, 6036.456718895299, 6047.909281360783, 6068.362821903703, 5838.795642152547, 5971.104334349839, 6030.96984515079, 6033.668148251844, 6036.12476190952, 3465.376232278189, 5313.0, 5314.415414304423, 5317.601179911522, 5318.574514038867, 16931.830681693147, 20512.487187820276, 20746.0, 21818.78181218199, 21948.89025554878, 1848.5840685845712, 1963.430605124512, 1981.837289076604, 2162.76209616942, 2343.109618345035, 13286.734265314737, 13289.601311960656, 13354.532591359353, 13360.532381366605, 14148.646283842883, 1586.373382513912, 1631.119195634356, 1656.2546853915665, 1660.3690597572966, 1672.7072762266675, 68819.31180688179, 70280.0, 72208.63895680488, 73308.26691733122, 73714.26285737127, 9219.308551858638, 9275.30435217348, 9322.440653560672, 9467.857982130274, 9610.471424071744, 12927.0, 12970.546030888874, 12990.935045324713, 12999.025073119554, 13015.934920325337, 7301.963490182515, 7409.555426674417, 7439.4420418468835, 7513.135989361184, 7530.472866899265, 7226.421886249087, 7261.2012678605315, 7331.486795924234, 7410.814729631802, 7491.925080749232, 5912.408759124053, 6013.759449622054, 6062.545309101747, 6071.2714474263375, 6150.261968563801, 1626.1381467822073, 1651.4914284004735, 1677.3039188736539, 1720.8365205305477, 1752.1939907642627, 854.7008547008497, 895.4269267668698, 932.4032619123766, 984.0897170117613, 1003.5233264199593, 17724.20241089154, 18039.368622098427, 18387.936970471263, 18393.724094138597, 18680.906595467208, 844.5101840932244, 880.1858281089966, 881.823635272948, 889.915458031486, 907.600655711498, 9984.900150998543, 10225.642102526524, 10436.634717784993, 10481.633142839964, 10557.102646275152, 403948.0, 405760.0, 406347.0, 419389.0, 420129.0, 73125.6343718278, 74129.62935185398, 75260.6918552483, 75902.24097759063, 77153.61423192848, 927.8469052606245, 937.5640327247862, 966.3428868369435, 966.9806603867884, 967.2117224462041, 15692.450764223197, 15752.133632649857, 15900.204989750235, 16391.34434622602, 16820.158992050103, 25392.746072539037, 35814.46278305775, 36207.818960905024, 37676.0, 37944.620553794106, 48118.5188148114, 48390.27414588714, 48418.273725893436, 48506.0, 48616.2707559394, 5628.902364038973, 6870.725170993104, 7691.30778229962, 7866.331361834308, 8298.336133109216, 11531.942340288246, 11574.0, 11892.881071189177, 12280.385980701109, 12370.81443778362, 10651.094656953936, 10910.399568142653, 10996.875264755241, 11091.72270693239, 11209.43952802373, 10584.947075264574, 10603.257771955889, 10993.560257589606, 11002.944985275022, 11140.721481962692, 10293.897061029593, 10538.367697938149, 10782.568697252022, 10951.34291942486, 10999.61501347449, 6661.367170118831, 7718.845623087619, 7900.3284720799375, 7930.532851422275, 8052.83894322122, 9608.75978100553, 10061.496925153566, 10371.688849334532, 10587.20595955291, 11030.669079927615], "value": 8456.940093760235, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1254.4668515880749, 1385.1066062389757, 1394.1008049807872, 1497.1915165810462, 1734.0809371033356, 6579.736810527579, 7594.392448604112, 7892.092409372924, 7896.960515197423, 8090.91909080909, 7931.444798864082, 7987.680492780281, 7989.280964713174, 7995.840083198333, 7996.200379962001, 1893.1953919584191, 2083.4374718825907, 2195.2426412887567, 2218.0240694094628, 2223.8109760670363, 2205.867647941129, 2234.0951914474663, 2262.5814224368546, 2269.103704036904, 2271.9659205111852, 2135.252661568454, 2159.2442645074257, 2172.2722887832515, 2174.608570457325, 2179.8256139508803, 1849.3157531713218, 1907.895065771382, 2139.7860213978556, 2141.261264863612, 2150.0969592771075, 1943.6015616798522, 1964.7642282925997, 2110.53568214994, 2186.267600353883, 2191.6274233380364, 7810.453268271223, 7852.371810255166, 7864.68541258349, 7869.213078692085, 7878.0, 4628.8148474061, 4767.952320476786, 4773.76131193439, 4786.760661966922, 4787.688800227974, 2872.152714949074, 2911.621489206411, 2944.131481213026, 2945.7496112830436, 2947.7936544441895, 921.3504479342115, 926.073926073926, 930.186087173723, 955.7132860141999, 972.2756546372998, 10496.842547361795, 10649.84025239622, 10661.680149595448, 10671.0, 10687.0, 4167.187398457272, 4265.0, 4326.848560300375, 4360.520342762294, 4386.2762644163995, 8496.320294376435, 8550.358723095793, 8551.74344769658, 8558.743237702878, 8567.100454452317, 4669.4396672399525, 4690.108879312919, 4694.36626055485, 4694.741789201558, 4699.177643912301, 3719.8698045568276, 3807.3337165996395, 4323.524412314643, 4376.956230437655, 4381.079973205598, 3453.3093381323333, 3470.774399664014, 3551.5027896094566, 3566.3045706087064, 3579.552555930509, 1021.3463383434607, 1069.0645685025602, 1079.2660990526517, 1087.0705546757567, 1097.4896673047072, 796.6574373019657, 834.1200033964104, 835.5738573327525, 837.5226121110908, 848.9066202717696, 5147.6654017488745, 5194.246834209019, 5232.502912223333, 5303.4696530346655, 5330.653507521999, 852.4416507187802, 885.6014793343021, 902.1429641840147, 924.2605915267759, 957.1624828275259, 3522.9295414091575, 3534.3991521441417, 3625.782453052825, 3707.703383729295, 3746.83139258734, 9755.853662195073, 9764.072413120743, 9813.264005199639, 9897.059779321096, 9900.0, 9775.902240977499, 9813.509324533745, 9883.703488895344, 9895.851562226573, 9896.802063958825, 7298.489105762652, 7512.774616761397, 7520.1351844537485, 7528.698852045966, 7539.585322807188, 5015.1474249378425, 5360.812371566976, 5377.193420986883, 5416.593755468277, 5620.1288800236025, 8548.0, 8863.157999989991, 9011.2790976723, 9044.010317730088, 9070.81858362816, 9054.366194366332, 9061.365704400629, 9088.363814532919, 9175.908240917506, 9188.081191881025, 6901.723931042702, 7063.82340441493, 7066.328698773608, 7072.2220555737795, 7077.504574679723, 7247.383972362198, 7404.96297518509, 7418.443616728875, 7457.515261507984, 7473.701051957861, 6979.581225126509, 6993.965030174817, 6997.545159564612, 7064.258252883374, 7214.098237720285, 6976.120736732404, 7079.681414336264, 7148.64256787169, 7166.713331466683, 7185.928140718735, 6069.2110025696375, 6176.474999625082, 6177.135201071764, 6178.938210617836, 6188.876222475616, 4344.56554344563, 4397.978010109929, 4426.247537918626, 4455.019895622958, 4460.286354183315, 4456.17560751262, 4515.9548404515535, 4593.678442508993, 4612.146752850734, 4645.326427667902], "value": 4141.70024100311, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.7473238735375, 296.3051643895066, 302.5461807289066, 306.9340091880245, 307.03284653341956, 4731.810727570897, 4758.191107511722, 4761.214399624062, 4781.497942716012, 4781.737004464753, 4750.619950403968, 4752.928706069403, 4753.762311884409, 4758.05000899442, 4758.428988521382, 3614.6746792788645, 3694.6305369463107, 3736.6823819975198, 3771.283456143324, 3774.5659249186283, 3797.544294684628, 3799.4300854871713, 3803.2013277211818, 3806.638369354907, 3813.218290250506, 3777.2823163598964, 3780.432935059749, 3782.5460944686674, 3787.4886890269736, 3792.6586607205345, 3607.6031636520092, 3639.290338384016, 3770.377887648535, 3784.3944968804994, 3794.2221844521805, 3356.0, 3368.5452463917304, 3373.797572145679, 3380.340833537461, 3382.2051817822794, 4654.953450465486, 4668.486466488684, 4676.836310729108, 4681.88295292617, 4682.742449165295, 4153.626173644357, 4298.677599180042, 4303.589989605831, 4306.44016277885, 4339.546252005582, 4968.7018778873025, 4981.526754958273, 4983.476734942815, 4987.501249875019, 4990.201567749143, 3659.70722342212, 3663.358912190356, 3664.9083772905624, 3673.94489082664, 3681.1533347329996, 5036.445990940957, 5181.171012637998, 5203.973980130113, 5210.791568337262, 5212.810619539802, 3734.3278209922473, 3762.454444105644, 3775.679067279257, 3782.149016471272, 3789.5642001170218, 3820.5988371220624, 3822.7515211511163, 3824.1204522959874, 3825.713071519647, 3827.119762454651, 4295.398644189816, 4295.484541854998, 4302.225599392077, 4305.590968857953, 4305.9569404306185, 4771.618270538349, 4952.579030782424, 4985.152524070844, 4987.62592805541, 4998.800047998112, 1308.4112149532557, 1316.0984725463088, 1320.39261939507, 1322.1934619881865, 1326.6285440076795, 828.3538839704919, 852.6163226548078, 861.8922634670666, 862.7972426479772, 863.2230992107154, 4296.839853239608, 4427.535108813592, 4431.158079964796, 4434.268345722984, 4437.844675436344, 5134.818991631871, 5150.175971844487, 5160.458151893997, 5165.715885626327, 5168.638195326366, 4394.340848872695, 4413.13943780966, 4413.3159360299205, 4428.180786554498, 4433.202023635777, 2566.114690431798, 2714.24001279642, 2737.7262273772462, 2743.7530622244094, 2751.2434080627986, 4868.758466590996, 4871.5128487151005, 4871.637255930871, 4871.658983871166, 4881.487443818348, 5006.4492905780335, 5289.418164001957, 5295.947040529622, 5299.2845965794895, 5305.761240744176, 4875.585575226075, 4881.585065269421, 4896.5837903777865, 4900.117978763857, 4922.92615610759, 5226.529612334908, 5228.0, 5233.319668443078, 5238.71187084714, 5239.214117882196, 4874.756262186947, 5042.84871453857, 5077.974610126926, 5091.719955402488, 5093.261477085728, 4869.780859861386, 4875.90248195027, 4877.585405240452, 4881.51184881509, 4895.46149923501, 3648.4892115103376, 3663.4796559428605, 3678.705703543764, 3682.9815850920572, 3698.130939229333, 4889.877753056202, 5178.119719647593, 5185.299984502044, 5187.481251874782, 5198.714070726146, 5013.273075403973, 5022.493252024305, 5025.773840177128, 5030.723310217973, 5031.345925029723, 5047.949520504748, 5054.494550544916, 5059.87350316245, 5060.342155519907, 5063.1898896176435, 4829.420469543677, 4978.083437876516, 4981.576565991932, 4986.404350607916, 4995.325631039908, 4750.216214324736, 4790.808367665254, 4953.380827396575, 4959.3881988353205, 4976.2038073908, 4877.731724755031, 4898.559129678346, 4900.460949295646, 4900.828471003498, 4902.3872015998], "value": 3799.3986999896333, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.4657980456027, 652.0707991112666, 673.946084313255, 714.4034730999615, 757.1784613694142, 12883.549075782337, 13682.452701891913, 13730.244836533986, 13763.44946202152, 13798.931005344984, 13267.93366033171, 13392.732145357088, 13436.328183590831, 13441.932790336059, 13444.3950022249, 611.1443978430211, 773.5436092705303, 777.246071310829, 794.6185830801219, 797.3541431440523, 764.5756605084176, 774.6746366526072, 775.3215936055951, 775.5695588948131, 776.0687175389527, 840.2983508770184, 842.2546046748641, 843.236870632079, 843.6625349860044, 843.7089204224533, 649.9415052645285, 804.9356051515865, 831.4221615976899, 840.4915026409026, 841.2933136165589, 614.7602435050334, 616.4328817487922, 616.7687117331001, 618.005011930791, 618.2210414877242, 12734.0, 12812.167209131376, 12895.09734318598, 12948.287844168566, 13133.737325253442, 12325.322107284095, 12521.93739031299, 12770.169938953939, 12913.139913070245, 13239.66900827487, 10850.728731781768, 11051.336919784759, 11054.392008439532, 11108.444577771079, 11223.438828058564, 4113.835446582133, 4202.390653355277, 4215.367694845767, 4240.809163587646, 4312.676549258818, 61916.0, 63159.05261421082, 63195.36804631941, 64002.0, 64175.67912160456, 9619.663311784054, 9923.80152396948, 10044.708351360907, 10333.689989300334, 10395.584176633001, 18319.90840045818, 18585.349512767258, 18617.720734189, 18620.81379186218, 18624.72062919057, 6661.16735408074, 6698.330166983263, 6781.830454238683, 7036.964815175994, 7150.964245178845, 3709.0727318170457, 3785.09157802131, 3847.0574709196426, 3860.942085868714, 3895.2988462076532, 2895.2327633177224, 2988.2678743707497, 2993.02726613847, 3049.679783622732, 3085.8148511088957, 525.0601423451978, 530.2391068816261, 531.9547838433776, 534.036064902854, 549.2804426201689, 512.293035610862, 514.7194778845463, 518.9195674670432, 528.183955788308, 536.656539814519, 7171.644620019565, 7379.520331178456, 7448.553086814808, 7605.961970190114, 8046.195380462024, 418.7299192021129, 458.3193956973891, 493.740786087307, 501.0605115408609, 549.4368272520618, 3474.7741396809633, 3480.3213373391945, 3538.310029544214, 3595.640435956436, 3766.4162054881535, 52301.2154817678, 52783.736081319345, 102121.3833239179, 102485.48757256316, 102769.48615256876, 84215.0, 84331.15668843233, 85105.57447212725, 85141.0, 86477.0, 73768.63115684495, 78924.6053769739, 79328.0, 81230.59384703157, 81466.59266703628, 7099.36105750485, 7198.676059577437, 7357.337839594462, 7585.658645361083, 7588.165301816685, 44719.776401117786, 44829.77585112053, 45811.54188458073, 46649.30026049544, 46680.76659616816, 36821.0, 37363.813180933925, 56265.0, 65135.022974656364, 66743.33256667371, 8540.27407670355, 8759.430637008574, 9021.774455638662, 9121.863172052557, 9178.403403778733, 9500.334976551576, 9647.662331818634, 9702.0, 9737.123658870736, 9939.155171810477, 8461.365397595328, 8809.075047120217, 8819.250363719155, 8882.955585222033, 9488.383255088396, 8832.5583720815, 8872.689455869013, 9245.768855778659, 9380.390274632127, 9487.810243794947, 9012.143846334588, 9213.677521286723, 9278.489683067226, 9315.161635452841, 9441.952790236004, 8138.959305203436, 8184.427090103635, 8373.413861029669, 8382.203690649378, 8421.87367189505, 8044.87932681022, 8250.174982501941, 8275.586220689062, 8416.579171041545, 8762.956185219033], "value": 6206.632275474385, "unit": "score"}], "name": "dromaeo_css", "value": 6379.538296574726, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 114.0, 113.0, 115.0, 114.0, 113.0, 116.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 121.0, 122.0, 179.0, 125.0, 123.0, 153.0, 123.0, 163.0, 122.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 159.0, 160.0, 161.0, 161.0, 160.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 78.0, 106.0, 76.0, 76.0, 76.0, 75.0, 112.0, 75.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 118.0, 85.0, 85.0, 86.0, 118.0, 84.0, 85.0, 121.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 63.0, 63.0, 64.0, 65.0, 64.0, 63.0, 63.0, 62.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 65.0, 66.0, 66.0, 65.0, 65.0, 66.0, 67.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 84.0, 83.0, 84.0, 80.0, 80.0, 85.0, 78.0, 86.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 131.0, 127.0, 128.0, 137.0, 133.0, 146.0, 168.0, 130.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 172.0, 172.0, 164.0, 163.0, 165.0, 163.0, 167.0, 168.0, 163.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 68.0, 65.0, 69.0, 65.0, 66.0, 65.0, 64.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1527.8000000000002, "alertThreshold": 2.0}]} 10:11:17 INFO - Return code: 0 10:11:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:11:17 INFO - # TBPL SUCCESS # 10:11:17 INFO - Running post-action listener: _resource_record_post_action 10:11:17 INFO - [mozharness: 2016-07-20 17:11:17.412568Z] Finished run-tests step (success) 10:11:17 INFO - Running post-run listener: _resource_record_post_run 10:11:17 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 541421568; Read time: 20; Write time: 438824 10:11:17 INFO - TinderboxPrint: CPU usage
13.0% 10:11:17 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 10:11:17 INFO - TinderboxPrint: I/O write bytes / time
541,421,568 / 438,824 10:11:17 INFO - TinderboxPrint: CPU idle
8,328.7 (87.0%) 10:11:17 INFO - TinderboxPrint: CPU user
1,196.6 (12.5%) 10:11:17 INFO - TinderboxPrint: Swap in / out
0 / 0 10:11:17 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 250523648; Read time: 0; Write time: 242692 10:11:17 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290545664; Read time: 8; Write time: 196100 10:11:18 INFO - Running post-run listener: _upload_blobber_files 10:11:18 INFO - Blob upload gear active. 10:11:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:11:18 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 10:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:11:18 INFO - (blobuploader) - INFO - Open directory for files ... 10:11:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:11:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:11:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:19 INFO - (blobuploader) - INFO - Done attempting. 10:11:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:11:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:11:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:20 INFO - (blobuploader) - INFO - Done attempting. 10:11:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:11:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:11:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:46 INFO - (blobuploader) - INFO - Done attempting. 10:11:46 INFO - (blobuploader) - INFO - Iteration through files over. 10:11:46 INFO - Return code: 0 10:11:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:11:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:11:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c9dfda5a443cbf4a0d46005fbcc2acdca85631f7568a21a97a6dd25d40da685765b0a7b21ce766d6bff7b4f067d660615854edcc0795734eb606253d9b8310a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f1b9fcfe669767190c3b80bd867b55f847e200ea062d7d104a80e97e7d7aa415f5229cf1d8b05f91b8e9879fd1fa953cbb54052b18963f6f5116ee266e0bc416", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0ab946e11b9fe584e405092040756e1f7dfa8807ccd1fb9d6ff3ea2f6a96a2e39082f511823b2d44b661b9237cc85dc7d8c7823ffc29dc175c71697d9f9572ed"} 10:11:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:11:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:11:46 INFO - Contents: 10:11:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c9dfda5a443cbf4a0d46005fbcc2acdca85631f7568a21a97a6dd25d40da685765b0a7b21ce766d6bff7b4f067d660615854edcc0795734eb606253d9b8310a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f1b9fcfe669767190c3b80bd867b55f847e200ea062d7d104a80e97e7d7aa415f5229cf1d8b05f91b8e9879fd1fa953cbb54052b18963f6f5116ee266e0bc416", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0ab946e11b9fe584e405092040756e1f7dfa8807ccd1fb9d6ff3ea2f6a96a2e39082f511823b2d44b661b9237cc85dc7d8c7823ffc29dc175c71697d9f9572ed"} 10:11:46 INFO - Running post-run listener: copy_logs_to_upload_dir 10:11:46 INFO - Copying logs to upload dir... 10:11:46 INFO - mkdir: /builds/slave/test/build/upload/logs 10:11:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.467415 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-07-20 10:11:46.779961) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 10:11:46.783389) ========= 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=337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c9dfda5a443cbf4a0d46005fbcc2acdca85631f7568a21a97a6dd25d40da685765b0a7b21ce766d6bff7b4f067d660615854edcc0795734eb606253d9b8310a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f1b9fcfe669767190c3b80bd867b55f847e200ea062d7d104a80e97e7d7aa415f5229cf1d8b05f91b8e9879fd1fa953cbb54052b18963f6f5116ee266e0bc416", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0ab946e11b9fe584e405092040756e1f7dfa8807ccd1fb9d6ff3ea2f6a96a2e39082f511823b2d44b661b9237cc85dc7d8c7823ffc29dc175c71697d9f9572ed"} build_url:https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011330 build_url: 'https://queue.taskcluster.net/v1/task/b-XT3CbhRC6pLGqaA7MKvg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c9dfda5a443cbf4a0d46005fbcc2acdca85631f7568a21a97a6dd25d40da685765b0a7b21ce766d6bff7b4f067d660615854edcc0795734eb606253d9b8310a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f1b9fcfe669767190c3b80bd867b55f847e200ea062d7d104a80e97e7d7aa415f5229cf1d8b05f91b8e9879fd1fa953cbb54052b18963f6f5116ee266e0bc416", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0ab946e11b9fe584e405092040756e1f7dfa8807ccd1fb9d6ff3ea2f6a96a2e39082f511823b2d44b661b9237cc85dc7d8c7823ffc29dc175c71697d9f9572ed"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 10:11:46.809074) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 10:11:46.809442) ========= 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=337fe3f4b1febe37b85fccc40000019e-1469033445.536583-1894784900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 10:11:46.896620) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 10:11:46.897360) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 10:11:46.898156) ========= ========= Total master_lag: 0.17 =========