builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1474370493.23 results: success (0) buildid: 20160920021501 builduid: 65976f0b1ea14cbc8e51c39ee640a855 revision: 4a3775a4a1ab7ec6adef8ca6518bad4ab3f4e4bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:33.233595) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:33.234106) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:33.234404) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:33.267129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:33.267560) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1474370492.718580-1159804280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 04:21:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 81.4M=0s 2016-09-20 04:21:33 (81.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105456 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:33.387602) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:33.387974) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1474370492.718580-1159804280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021247 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:33.437604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 04:21:33.437994) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 4a3775a4a1ab7ec6adef8ca6518bad4ab3f4e4bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 4a3775a4a1ab7ec6adef8ca6518bad4ab3f4e4bc --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1474370492.718580-1159804280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 04:21:33,849 truncating revision to first 12 chars 2016-09-20 04:21:33,849 Setting DEBUG logging. 2016-09-20 04:21:33,849 attempt 1/10 2016-09-20 04:21:33,849 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/4a3775a4a1ab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 04:21:34,355 unpacking tar archive at: mozilla-inbound-4a3775a4a1ab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.381926 ========= master_lag: 0.42 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 04:21:35.241907) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:35.242236) ========= script_repo_revision: 4a3775a4a1ab7ec6adef8ca6518bad4ab3f4e4bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:35.242659) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:35.242923) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 04:21:35.254599) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-20 04:21:35.255004) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1474370492.718580-1159804280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:21:35 INFO - MultiFileLogger online at 20160920 04:21:35 in /builds/slave/test 04:21:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 04:21:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:21:35 INFO - {'append_to_log': False, 04:21:35 INFO - 'base_work_dir': '/builds/slave/test', 04:21:35 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 04:21:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:21:35 INFO - 'branch': 'Mozilla-Inbound', 04:21:35 INFO - 'buildbot_json_path': 'buildprops.json', 04:21:35 INFO - 'config_files': ('talos/linux_config.py',), 04:21:35 INFO - 'default_actions': ('clobber', 04:21:35 INFO - 'read-buildbot-config', 04:21:35 INFO - 'download-and-extract', 04:21:35 INFO - 'populate-webroot', 04:21:35 INFO - 'create-virtualenv', 04:21:35 INFO - 'install', 04:21:35 INFO - 'run-tests'), 04:21:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:21:35 INFO - 'download_minidump_stackwalk': True, 04:21:35 INFO - 'download_symbols': 'ondemand', 04:21:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:21:35 INFO - 'tooltool.py': '/tools/tooltool.py', 04:21:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:21:35 INFO - '/tools/misc-python/virtualenv.py')}, 04:21:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:21:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:21:35 INFO - 'installer_path': 'installer.exe', 04:21:35 INFO - 'log_level': 'info', 04:21:35 INFO - 'log_name': 'talos', 04:21:35 INFO - 'log_to_console': True, 04:21:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:21:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:21:35 INFO - 'opt_config_files': (), 04:21:35 INFO - 'pip_index': False, 04:21:35 INFO - 'sps_profile': False, 04:21:35 INFO - 'sps_profile_interval': 0, 04:21:35 INFO - 'suite': 'dromaeojs', 04:21:35 INFO - 'system_bits': '32', 04:21:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:21:35 INFO - 'title': 'talos-linux64-ix-032', 04:21:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:21:35 INFO - 'use_talos_json': True, 04:21:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:21:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:21:35 INFO - 'work_dir': 'build'} 04:21:35 INFO - [mozharness: 2016-09-20 11:21:35.755241Z] Running clobber step. 04:21:35 INFO - Running pre-action listener: _resource_record_pre_action 04:21:35 INFO - Running main action method: clobber 04:21:35 INFO - rmtree: /builds/slave/test/build 04:21:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:21:36 INFO - Running post-action listener: _resource_record_post_action 04:21:36 INFO - [mozharness: 2016-09-20 11:21:36.223602Z] Finished clobber step (success) 04:21:36 INFO - [mozharness: 2016-09-20 11:21:36.223705Z] Running read-buildbot-config step. 04:21:36 INFO - Running pre-action listener: _resource_record_pre_action 04:21:36 INFO - Running main action method: read_buildbot_config 04:21:36 INFO - Using buildbot properties: 04:21:36 INFO - { 04:21:36 INFO - "project": "", 04:21:36 INFO - "product": "firefox", 04:21:36 INFO - "script_repo_revision": "production", 04:21:36 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 04:21:36 INFO - "repository": "", 04:21:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 04:21:36 INFO - "buildid": "20160920021501", 04:21:36 INFO - "pgo_build": "True", 04:21:36 INFO - "basedir": "/builds/slave/test", 04:21:36 INFO - "buildnumber": 2449, 04:21:36 INFO - "slavename": "talos-linux64-ix-032", 04:21:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:21:36 INFO - "platform": "ubuntu64_hw", 04:21:36 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 04:21:36 INFO - "branch": "mozilla-inbound", 04:21:36 INFO - "repo_path": "integration/mozilla-inbound", 04:21:36 INFO - "revision": "4a3775a4a1ab7ec6adef8ca6518bad4ab3f4e4bc", 04:21:36 INFO - "stage_platform": "linux64-pgo", 04:21:36 INFO - "builduid": "65976f0b1ea14cbc8e51c39ee640a855", 04:21:36 INFO - "slavebuilddir": "test" 04:21:36 INFO - } 04:21:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 04:21:36 INFO - Running post-action listener: _resource_record_post_action 04:21:36 INFO - [mozharness: 2016-09-20 11:21:36.226186Z] Finished read-buildbot-config step (success) 04:21:36 INFO - [mozharness: 2016-09-20 11:21:36.226278Z] Running download-and-extract step. 04:21:36 INFO - Running pre-action listener: _resource_record_pre_action 04:21:36 INFO - Running main action method: download_and_extract 04:21:36 INFO - mkdir: /builds/slave/test/build/tests 04:21:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:36 INFO - trying https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:21:36 INFO - Downloading https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:21:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:21:36 INFO - Downloaded 1361 bytes. 04:21:36 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:21:36 INFO - Using the following test package requirements: 04:21:36 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:21:36 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:21:36 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:21:36 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:36 INFO - u'jsshell-linux-x86_64.zip'], 04:21:36 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:21:36 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:21:36 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:21:36 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:21:36 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:21:36 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:21:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:21:36 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:21:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:36 INFO - trying https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:21:36 INFO - Downloading https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:21:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:21:38 INFO - Downloaded 21824042 bytes. 04:21:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:21:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:21:39 INFO - Return code: 0 04:21:39 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:21:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:39 INFO - trying https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:21:39 INFO - Downloading https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:21:40 INFO - Downloaded 21824042 bytes. 04:21:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:21:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:21:41 INFO - Return code: 0 04:21:41 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:41 INFO - trying https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 04:21:41 INFO - Downloading https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 04:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:21:42 INFO - Downloaded 14413723 bytes. 04:21:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:21:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:21:42 INFO - Return code: 0 04:21:42 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:42 INFO - trying https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:21:42 INFO - Downloading https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:21:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:21:45 INFO - Downloaded 59375090 bytes. 04:21:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:21:45 INFO - mkdir: /builds/slave/test/properties 04:21:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:21:45 INFO - Writing to file /builds/slave/test/properties/build_url 04:21:45 INFO - Contents: 04:21:45 INFO - build_url:https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:21:45 INFO - Running post-action listener: _resource_record_post_action 04:21:45 INFO - Running post-action listener: set_extra_try_arguments 04:21:45 INFO - [mozharness: 2016-09-20 11:21:45.914282Z] Finished download-and-extract step (success) 04:21:45 INFO - [mozharness: 2016-09-20 11:21:45.914529Z] Running populate-webroot step. 04:21:45 INFO - Running pre-action listener: _resource_record_pre_action 04:21:45 INFO - Running main action method: populate_webroot 04:21:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:21:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:21:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:21:45 INFO - '--tppagecycles', 04:21:45 INFO - '7'], 04:21:45 INFO - 'tests': ['tsvgm']}}, 04:21:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:21:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:21:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:21:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:21:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:45 INFO - 'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:45 INFO - 'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['damp', 'tps']}, 04:21:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:45 INFO - 'tests': ['damp', 'tps']}, 04:21:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['dromaeo_dom']}, 04:21:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:21:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['basic_compositor_video']}, 04:21:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:21:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['a11yr', 04:21:45 INFO - 'ts_paint', 04:21:45 INFO - 'tpaint', 04:21:45 INFO - 'sessionrestore', 04:21:45 INFO - 'sessionrestore_no_auto_restore', 04:21:45 INFO - 'tabpaint']}, 04:21:45 INFO - 'other-e10s': {'tests': ['a11yr', 04:21:45 INFO - 'ts_paint', 04:21:45 INFO - 'tpaint', 04:21:45 INFO - 'sessionrestore', 04:21:45 INFO - 'sessionrestore_no_auto_restore', 04:21:45 INFO - 'tabpaint']}, 04:21:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:21:45 INFO - 'ts_paint', 04:21:45 INFO - 'tpaint', 04:21:45 INFO - 'sessionrestore', 04:21:45 INFO - 'sessionrestore_no_auto_restore', 04:21:45 INFO - 'tabpaint']}, 04:21:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:21:45 INFO - 'ts_paint', 04:21:45 INFO - 'tpaint', 04:21:45 INFO - 'sessionrestore', 04:21:45 INFO - 'sessionrestore_no_auto_restore', 04:21:45 INFO - 'tabpaint']}, 04:21:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['a11yr', 04:21:45 INFO - 'ts_paint', 04:21:45 INFO - 'tpaint', 04:21:45 INFO - 'sessionrestore', 04:21:45 INFO - 'sessionrestore_no_auto_restore', 04:21:45 INFO - 'tabpaint']}, 04:21:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['a11yr', 04:21:45 INFO - 'ts_paint', 04:21:45 INFO - 'tpaint', 04:21:45 INFO - 'sessionrestore', 04:21:45 INFO - 'sessionrestore_no_auto_restore', 04:21:45 INFO - 'tabpaint']}, 04:21:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['tsvgx', 04:21:45 INFO - 'tsvgr_opacity', 04:21:45 INFO - 'tart', 04:21:45 INFO - 'tscrollx', 04:21:45 INFO - 'cart']}, 04:21:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:21:45 INFO - 'tsvgr_opacity', 04:21:45 INFO - 'tart', 04:21:45 INFO - 'tscrollx', 04:21:45 INFO - 'cart']}, 04:21:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:45 INFO - 'talos_options': ['--disable-e10s'], 04:21:45 INFO - 'tests': ['tp5o']}, 04:21:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:45 INFO - 'tests': ['tp5o']}, 04:21:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:45 INFO - 'talos_options': ['--disable-e10s', 04:21:45 INFO - '--xperf_path', 04:21:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:45 INFO - 'tests': ['tp5n']}, 04:21:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:45 INFO - 'talos_options': ['--xperf_path', 04:21:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:45 INFO - 'tests': ['tp5n']}}, 04:21:45 INFO - 'talos.zip': {'path': '', 04:21:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:21:45 INFO - Running post-action listener: _resource_record_post_action 04:21:45 INFO - [mozharness: 2016-09-20 11:21:45.930188Z] Finished populate-webroot step (success) 04:21:45 INFO - [mozharness: 2016-09-20 11:21:45.930294Z] Running create-virtualenv step. 04:21:45 INFO - Running pre-action listener: _resource_record_pre_action 04:21:45 INFO - Running main action method: create_virtualenv 04:21:45 INFO - Creating virtualenv /builds/slave/test/build/venv 04:21:45 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 04:21:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:21:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:21:46 INFO - Using real prefix '/usr' 04:21:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:21:46 INFO - Installing distribute.............................................................................................................................................................................................done. 04:21:48 INFO - Installing pip.................done. 04:21:48 INFO - Return code: 0 04:21:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:21:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:21:48 INFO - Using env: {'DISPLAY': ':0', 04:21:48 INFO - 'HOME': '/home/cltbld', 04:21:48 INFO - 'LANG': 'en_US.UTF-8', 04:21:48 INFO - 'LANGUAGE': 'en_US:en', 04:21:48 INFO - 'LOGNAME': 'cltbld', 04:21:48 INFO - 'MAIL': '/var/mail/cltbld', 04:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:48 INFO - 'MOZ_NO_REMOTE': '1', 04:21:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:48 INFO - 'NO_EM_RESTART': '1', 04:21:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:48 INFO - 'PWD': '/builds/slave/test', 04:21:48 INFO - 'SHELL': '/bin/bash', 04:21:48 INFO - 'SHLVL': '1', 04:21:48 INFO - 'TERM': 'linux', 04:21:48 INFO - 'TMOUT': '86400', 04:21:48 INFO - 'USER': 'cltbld', 04:21:48 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:48 INFO - '_': '/tools/buildbot/bin/python'} 04:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:48 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 04:21:48 INFO - Cleaning up... 04:21:48 INFO - Return code: 0 04:21:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:21:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:21:48 INFO - Using env: {'DISPLAY': ':0', 04:21:48 INFO - 'HOME': '/home/cltbld', 04:21:48 INFO - 'LANG': 'en_US.UTF-8', 04:21:48 INFO - 'LANGUAGE': 'en_US:en', 04:21:48 INFO - 'LOGNAME': 'cltbld', 04:21:48 INFO - 'MAIL': '/var/mail/cltbld', 04:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:48 INFO - 'MOZ_NO_REMOTE': '1', 04:21:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:48 INFO - 'NO_EM_RESTART': '1', 04:21:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:48 INFO - 'PWD': '/builds/slave/test', 04:21:48 INFO - 'SHELL': '/bin/bash', 04:21:48 INFO - 'SHLVL': '1', 04:21:48 INFO - 'TERM': 'linux', 04:21:48 INFO - 'TMOUT': '86400', 04:21:48 INFO - 'USER': 'cltbld', 04:21:48 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:48 INFO - '_': '/tools/buildbot/bin/python'} 04:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:48 INFO - Downloading/unpacking psutil>=3.1.1 04:21:48 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 04:21:48 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 04:21:48 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 04:21:48 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 04:21:48 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 04:21:48 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 04:21:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:21:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:50 INFO - Installing collected packages: psutil 04:21:50 INFO - Running setup.py install for psutil 04:21:51 INFO - building 'psutil._psutil_linux' extension 04:21:51 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 04:21:51 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 04:21:51 INFO - building 'psutil._psutil_posix' extension 04:21:51 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 04:21:51 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 04:21:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:51 INFO - Successfully installed psutil 04:21:51 INFO - Cleaning up... 04:21:51 INFO - Return code: 0 04:21:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:21:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:51 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 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:21:51 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 04:21:51 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 04:21:51 INFO - Using env: {'DISPLAY': ':0', 04:21:51 INFO - 'HOME': '/home/cltbld', 04:21:51 INFO - 'LANG': 'en_US.UTF-8', 04:21:51 INFO - 'LANGUAGE': 'en_US:en', 04:21:51 INFO - 'LOGNAME': 'cltbld', 04:21:51 INFO - 'MAIL': '/var/mail/cltbld', 04:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:51 INFO - 'MOZ_NO_REMOTE': '1', 04:21:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:51 INFO - 'NO_EM_RESTART': '1', 04:21:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:51 INFO - 'PWD': '/builds/slave/test', 04:21:51 INFO - 'SHELL': '/bin/bash', 04:21:51 INFO - 'SHLVL': '1', 04:21:51 INFO - 'TERM': 'linux', 04:21:51 INFO - 'TMOUT': '86400', 04:21:51 INFO - 'USER': 'cltbld', 04:21:51 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:51 INFO - '_': '/tools/buildbot/bin/python'} 04:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:21:51 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 04:21:51 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 04:21:51 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 04:21:51 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 04:21:51 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 04:21:51 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 04:21:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:21:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:21:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:21:53 INFO - Installing collected packages: mozsystemmonitor 04:21:53 INFO - Running setup.py install for mozsystemmonitor 04:21:53 INFO - Successfully installed mozsystemmonitor 04:21:53 INFO - Cleaning up... 04:21:53 INFO - Return code: 0 04:21:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:53 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 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:21:53 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 04:21:53 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 04:21:53 INFO - Using env: {'DISPLAY': ':0', 04:21:53 INFO - 'HOME': '/home/cltbld', 04:21:53 INFO - 'LANG': 'en_US.UTF-8', 04:21:53 INFO - 'LANGUAGE': 'en_US:en', 04:21:53 INFO - 'LOGNAME': 'cltbld', 04:21:53 INFO - 'MAIL': '/var/mail/cltbld', 04:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:53 INFO - 'MOZ_NO_REMOTE': '1', 04:21:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:53 INFO - 'NO_EM_RESTART': '1', 04:21:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:53 INFO - 'PWD': '/builds/slave/test', 04:21:53 INFO - 'SHELL': '/bin/bash', 04:21:53 INFO - 'SHLVL': '1', 04:21:53 INFO - 'TERM': 'linux', 04:21:53 INFO - 'TMOUT': '86400', 04:21:53 INFO - 'USER': 'cltbld', 04:21:53 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:53 INFO - '_': '/tools/buildbot/bin/python'} 04:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:53 INFO - Downloading/unpacking jsonschema==2.5.1 04:21:53 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 04:21:53 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 04:21:53 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 04:21:53 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 04:21:53 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 04:21:53 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 04:21:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:21:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:21:55 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 04:21:55 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 04:21:55 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 04:21:55 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 04:21:55 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 04:21:55 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 04:21:56 INFO - Downloading functools32-3.2.3-2.tar.gz 04:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:21:56 INFO - warning: no files found matching '*.txt' 04:21:56 INFO - no previously-included directories found matching 'build' 04:21:56 INFO - no previously-included directories found matching 'dist' 04:21:56 INFO - no previously-included directories found matching '.git*' 04:21:56 INFO - Installing collected packages: jsonschema, functools32 04:21:56 INFO - Running setup.py install for functools32 04:21:56 INFO - warning: no files found matching '*.txt' 04:21:56 INFO - no previously-included directories found matching 'build' 04:21:56 INFO - no previously-included directories found matching 'dist' 04:21:56 INFO - no previously-included directories found matching '.git*' 04:21:56 INFO - Successfully installed jsonschema functools32 04:21:56 INFO - Cleaning up... 04:21:56 INFO - Return code: 0 04:21:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:21:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:56 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:21:56 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:21:56 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 functools32==3.2.3-2 04:21:56 INFO - Using env: {'DISPLAY': ':0', 04:21:56 INFO - 'HOME': '/home/cltbld', 04:21:56 INFO - 'LANG': 'en_US.UTF-8', 04:21:56 INFO - 'LANGUAGE': 'en_US:en', 04:21:56 INFO - 'LOGNAME': 'cltbld', 04:21:56 INFO - 'MAIL': '/var/mail/cltbld', 04:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:56 INFO - 'MOZ_NO_REMOTE': '1', 04:21:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:56 INFO - 'NO_EM_RESTART': '1', 04:21:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:56 INFO - 'PWD': '/builds/slave/test', 04:21:56 INFO - 'SHELL': '/bin/bash', 04:21:56 INFO - 'SHLVL': '1', 04:21:56 INFO - 'TERM': 'linux', 04:21:56 INFO - 'TMOUT': '86400', 04:21:56 INFO - 'USER': 'cltbld', 04:21:56 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:56 INFO - '_': '/tools/buildbot/bin/python'} 04:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:21:56 INFO - Cleaning up... 04:21:56 INFO - Return code: 0 04:21:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:21:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:56 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 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:21:56 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 04:21:56 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 04:21:56 INFO - Using env: {'DISPLAY': ':0', 04:21:56 INFO - 'HOME': '/home/cltbld', 04:21:56 INFO - 'LANG': 'en_US.UTF-8', 04:21:56 INFO - 'LANGUAGE': 'en_US:en', 04:21:56 INFO - 'LOGNAME': 'cltbld', 04:21:56 INFO - 'MAIL': '/var/mail/cltbld', 04:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:56 INFO - 'MOZ_NO_REMOTE': '1', 04:21:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:21:56 INFO - 'NO_EM_RESTART': '1', 04:21:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:56 INFO - 'PWD': '/builds/slave/test', 04:21:56 INFO - 'SHELL': '/bin/bash', 04:21:56 INFO - 'SHLVL': '1', 04:21:56 INFO - 'TERM': 'linux', 04:21:56 INFO - 'TMOUT': '86400', 04:21:56 INFO - 'USER': 'cltbld', 04:21:56 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:56 INFO - '_': '/tools/buildbot/bin/python'} 04:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:56 INFO - Downloading/unpacking blobuploader==1.2.4 04:21:56 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 04:21:56 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 04:21:56 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 04:21:56 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 04:21:56 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 04:21:56 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 04:21:58 INFO - Downloading blobuploader-1.2.4.tar.gz 04:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:21:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:21:58 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 04:21:58 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 04:21:58 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 04:21:58 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 04:21:58 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 04:21:58 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 04:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:21:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:21: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 04:21: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 04:21: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 04:21: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 04:21: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 04:21: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 04:21:59 INFO - Downloading docopt-0.6.1.tar.gz 04:21:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:21:59 INFO - Installing collected packages: blobuploader, requests, docopt 04:21:59 INFO - Running setup.py install for blobuploader 04:21:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:21:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:21:59 INFO - Running setup.py install for requests 04:21:59 INFO - Running setup.py install for docopt 04:21:59 INFO - Successfully installed blobuploader requests docopt 04:21:59 INFO - Cleaning up... 04:22:00 INFO - Return code: 0 04:22:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:00 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 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:22:00 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 04:22:00 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 04:22:00 INFO - Using env: {'DISPLAY': ':0', 04:22:00 INFO - 'HOME': '/home/cltbld', 04:22:00 INFO - 'LANG': 'en_US.UTF-8', 04:22:00 INFO - 'LANGUAGE': 'en_US:en', 04:22:00 INFO - 'LOGNAME': 'cltbld', 04:22:00 INFO - 'MAIL': '/var/mail/cltbld', 04:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:00 INFO - 'MOZ_NO_REMOTE': '1', 04:22:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:00 INFO - 'NO_EM_RESTART': '1', 04:22:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:00 INFO - 'PWD': '/builds/slave/test', 04:22:00 INFO - 'SHELL': '/bin/bash', 04:22:00 INFO - 'SHLVL': '1', 04:22:00 INFO - 'TERM': 'linux', 04:22:00 INFO - 'TMOUT': '86400', 04:22:00 INFO - 'USER': 'cltbld', 04:22:00 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:00 INFO - '_': '/tools/buildbot/bin/python'} 04:22:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:22:00 INFO - Running setup.py (path:/tmp/pip-O5XZBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:22:00 INFO - Running setup.py (path:/tmp/pip-Zz2_UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:22:00 INFO - Running setup.py (path:/tmp/pip-Rykozb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:22:00 INFO - Running setup.py (path:/tmp/pip-V9Lv95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:22:00 INFO - Running setup.py (path:/tmp/pip-GarrBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:22:00 INFO - Running setup.py (path:/tmp/pip-XUaQsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:22:00 INFO - Running setup.py (path:/tmp/pip-1nRr_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:22:00 INFO - Running setup.py (path:/tmp/pip-fXejbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:22:00 INFO - Running setup.py (path:/tmp/pip-zMSFxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:22:00 INFO - Running setup.py (path:/tmp/pip-y57e8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:22:00 INFO - Running setup.py (path:/tmp/pip-CTgMbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:22:01 INFO - Running setup.py (path:/tmp/pip-iZUHaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:22:01 INFO - Running setup.py (path:/tmp/pip-aJsLvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:22:01 INFO - Running setup.py (path:/tmp/pip-wtfeJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:22:01 INFO - Running setup.py (path:/tmp/pip-2m7kWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:22:01 INFO - Running setup.py (path:/tmp/pip-73CiUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:22:01 INFO - Running setup.py (path:/tmp/pip-FLH_h0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:22:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:22:01 INFO - Running setup.py install for manifestparser 04:22:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:22:01 INFO - Running setup.py install for mozcrash 04:22:01 INFO - Running setup.py install for mozdebug 04:22:01 INFO - Running setup.py install for mozdevice 04:22:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:22:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:22:01 INFO - Running setup.py install for mozfile 04:22:02 INFO - Running setup.py install for mozhttpd 04:22:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Running setup.py install for mozinfo 04:22:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Running setup.py install for mozInstall 04:22:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Running setup.py install for mozleak 04:22:02 INFO - Running setup.py install for mozlog 04:22:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Running setup.py install for moznetwork 04:22:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Running setup.py install for mozprocess 04:22:02 INFO - Running setup.py install for mozprofile 04:22:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Running setup.py install for mozrunner 04:22:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:22:02 INFO - Running setup.py install for mozscreenshot 04:22:03 INFO - Running setup.py install for moztest 04:22:03 INFO - Running setup.py install for mozversion 04:22:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:22:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:22:03 INFO - Cleaning up... 04:22:03 INFO - Return code: 0 04:22:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:03 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 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:22:03 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 04:22:03 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 04:22:03 INFO - Using env: {'DISPLAY': ':0', 04:22:03 INFO - 'HOME': '/home/cltbld', 04:22:03 INFO - 'LANG': 'en_US.UTF-8', 04:22:03 INFO - 'LANGUAGE': 'en_US:en', 04:22:03 INFO - 'LOGNAME': 'cltbld', 04:22:03 INFO - 'MAIL': '/var/mail/cltbld', 04:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:03 INFO - 'MOZ_NO_REMOTE': '1', 04:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:03 INFO - 'NO_EM_RESTART': '1', 04:22:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:03 INFO - 'PWD': '/builds/slave/test', 04:22:03 INFO - 'SHELL': '/bin/bash', 04:22:03 INFO - 'SHLVL': '1', 04:22:03 INFO - 'TERM': 'linux', 04:22:03 INFO - 'TMOUT': '86400', 04:22:03 INFO - 'USER': 'cltbld', 04:22:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:03 INFO - '_': '/tools/buildbot/bin/python'} 04:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:22:03 INFO - Running setup.py (path:/tmp/pip-XgTFu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:22:03 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)) 04:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:22:03 INFO - Running setup.py (path:/tmp/pip-0ObOWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:22:03 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)) 04:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:22:03 INFO - Running setup.py (path:/tmp/pip-EYvbtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:22:03 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)) 04:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:22:03 INFO - Running setup.py (path:/tmp/pip-36Q6RR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:22:03 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)) 04:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:22:03 INFO - Running setup.py (path:/tmp/pip-rMEHdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:22:03 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)) 04:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:22:03 INFO - Running setup.py (path:/tmp/pip-ovOBQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:22:03 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)) 04:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:22:03 INFO - Running setup.py (path:/tmp/pip-D1mcQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:22:04 INFO - Running setup.py (path:/tmp/pip-wva0MU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:22:04 INFO - Running setup.py (path:/tmp/pip-90ElsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:22:04 INFO - Running setup.py (path:/tmp/pip-li78jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:22:04 INFO - Running setup.py (path:/tmp/pip-_T3SPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:22:04 INFO - Running setup.py (path:/tmp/pip-qoimGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:22:04 INFO - Running setup.py (path:/tmp/pip-zZz1OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:22:04 INFO - Running setup.py (path:/tmp/pip-oI5C1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:22:04 INFO - Running setup.py (path:/tmp/pip-JWkyDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:22:04 INFO - Running setup.py (path:/tmp/pip-iUMTsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:22:04 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)) 04:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:22:04 INFO - Running setup.py (path:/tmp/pip-bpv_yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:22:04 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)) 04:22:04 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)) 04:22:04 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)) 04:22:04 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)) 04:22:04 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)) 04:22:04 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)) 04:22:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:22: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 04:22: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 04:22: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 04:22: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 04:22: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 04:22: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 04:22:06 INFO - Downloading blessings-1.6.tar.gz 04:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:22:06 INFO - Installing collected packages: blessings 04:22:06 INFO - Running setup.py install for blessings 04:22:06 INFO - Successfully installed blessings 04:22:06 INFO - Cleaning up... 04:22:06 INFO - Return code: 0 04:22:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:22:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:22:07 INFO - Reading from file tmpfile_stdout 04:22:07 INFO - Current package versions: 04:22:07 INFO - argparse == 1.2.1 04:22:07 INFO - blessings == 1.6 04:22:07 INFO - blobuploader == 1.2.4 04:22:07 INFO - docopt == 0.6.1 04:22:07 INFO - functools32 == 3.2.3-2 04:22:07 INFO - jsonschema == 2.5.1 04:22:07 INFO - manifestparser == 1.1 04:22:07 INFO - mozInstall == 1.12 04:22:07 INFO - mozcrash == 0.17 04:22:07 INFO - mozdebug == 0.1 04:22:07 INFO - mozdevice == 0.48 04:22:07 INFO - mozfile == 1.2 04:22:07 INFO - mozhttpd == 0.7 04:22:07 INFO - mozinfo == 0.9 04:22:07 INFO - mozleak == 0.1 04:22:07 INFO - mozlog == 3.3 04:22:07 INFO - moznetwork == 0.27 04:22:07 INFO - mozprocess == 0.23 04:22:07 INFO - mozprofile == 0.28 04:22:07 INFO - mozrunner == 6.12 04:22:07 INFO - mozscreenshot == 0.1 04:22:07 INFO - mozsystemmonitor == 0.3 04:22:07 INFO - moztest == 0.7 04:22:07 INFO - mozversion == 1.4 04:22:07 INFO - psutil == 3.1.1 04:22:07 INFO - requests == 1.2.3 04:22:07 INFO - wsgiref == 0.1.2 04:22:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:22:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:07 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 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:22:07 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 04:22:07 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 04:22:07 INFO - Using env: {'DISPLAY': ':0', 04:22:07 INFO - 'HOME': '/home/cltbld', 04:22:07 INFO - 'LANG': 'en_US.UTF-8', 04:22:07 INFO - 'LANGUAGE': 'en_US:en', 04:22:07 INFO - 'LOGNAME': 'cltbld', 04:22:07 INFO - 'MAIL': '/var/mail/cltbld', 04:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:07 INFO - 'MOZ_NO_REMOTE': '1', 04:22:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:07 INFO - 'NO_EM_RESTART': '1', 04:22:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:07 INFO - 'PWD': '/builds/slave/test', 04:22:07 INFO - 'SHELL': '/bin/bash', 04:22:07 INFO - 'SHLVL': '1', 04:22:07 INFO - 'TERM': 'linux', 04:22:07 INFO - 'TMOUT': '86400', 04:22:07 INFO - 'USER': 'cltbld', 04:22:07 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:07 INFO - '_': '/tools/buildbot/bin/python'} 04:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:07 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)) 04:22:07 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)) 04:22:07 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)) 04:22:07 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)) 04:22:07 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)) 04:22:07 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)) 04:22:07 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)) 04:22:07 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)) 04:22:07 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)) 04:22:07 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)) 04:22:07 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)) 04:22:07 INFO - Cleaning up... 04:22:07 INFO - Return code: 0 04:22:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:22:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:22:07 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 0x22fa030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x245d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', '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 04:22:07 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 04:22:07 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 04:22:07 INFO - Using env: {'DISPLAY': ':0', 04:22:07 INFO - 'HOME': '/home/cltbld', 04:22:07 INFO - 'LANG': 'en_US.UTF-8', 04:22:07 INFO - 'LANGUAGE': 'en_US:en', 04:22:07 INFO - 'LOGNAME': 'cltbld', 04:22:07 INFO - 'MAIL': '/var/mail/cltbld', 04:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:07 INFO - 'MOZ_NO_REMOTE': '1', 04:22:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:07 INFO - 'NO_EM_RESTART': '1', 04:22:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:07 INFO - 'PWD': '/builds/slave/test', 04:22:07 INFO - 'SHELL': '/bin/bash', 04:22:07 INFO - 'SHLVL': '1', 04:22:07 INFO - 'TERM': 'linux', 04:22:07 INFO - 'TMOUT': '86400', 04:22:07 INFO - 'USER': 'cltbld', 04:22:07 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:07 INFO - '_': '/tools/buildbot/bin/python'} 04:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:22:07 INFO - Cleaning up... 04:22:07 INFO - Return code: 0 04:22:07 INFO - Running post-action listener: _resource_record_post_action 04:22:07 INFO - Running post-action listener: _start_resource_monitoring 04:22:07 INFO - Starting resource monitoring. 04:22:07 INFO - [mozharness: 2016-09-20 11:22:07.603877Z] Finished create-virtualenv step (success) 04:22:07 INFO - [mozharness: 2016-09-20 11:22:07.604353Z] Running install step. 04:22:07 INFO - Running pre-action listener: _resource_record_pre_action 04:22:07 INFO - Running main action method: install 04:22:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:22:07 INFO - Reading from file tmpfile_stdout 04:22:07 INFO - Detecting whether we're running mozinstall >=1.0... 04:22:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:22:07 INFO - Reading from file tmpfile_stdout 04:22:07 INFO - Output received: 04:22:07 INFO - Usage: mozinstall [options] installer 04:22:07 INFO - Options: 04:22:07 INFO - -h, --help show this help message and exit 04:22:07 INFO - -d DEST, --destination=DEST 04:22:07 INFO - Directory to install application into. [default: 04:22:07 INFO - "/builds/slave/test"] 04:22:07 INFO - --app=APP Application being installed. [default: firefox] 04:22:07 INFO - mkdir: /builds/slave/test/build/application 04:22:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:22:24 INFO - Reading from file tmpfile_stdout 04:22:24 INFO - Output received: 04:22:24 INFO - /builds/slave/test/build/application/firefox/firefox 04:22:24 INFO - Running post-action listener: _resource_record_post_action 04:22:24 INFO - [mozharness: 2016-09-20 11:22:24.826479Z] Finished install step (success) 04:22:24 INFO - [mozharness: 2016-09-20 11:22:24.826729Z] Running run-tests step. 04:22:24 INFO - Running pre-action listener: _resource_record_pre_action 04:22:24 INFO - Running main action method: run_tests 04:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:22:24 INFO - Python 2.7.3 04:22:24 INFO - Return code: 0 04:22:24 INFO - grabbing minidump binary from tooltool 04:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:24 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 0x25b93b0>, '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 0x25ba2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ba740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:22:24 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 04:22:24 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 04:22:24 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 04:22:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:22:24 INFO - Return code: 0 04:22:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:22:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:22:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:22:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:22:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:22:24 INFO - Using env: {'DISPLAY': ':0', 04:22:24 INFO - 'HOME': '/home/cltbld', 04:22:24 INFO - 'LANG': 'en_US.UTF-8', 04:22:24 INFO - 'LANGUAGE': 'en_US:en', 04:22:24 INFO - 'LOGNAME': 'cltbld', 04:22:24 INFO - 'MAIL': '/var/mail/cltbld', 04:22:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:22:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:24 INFO - 'MOZ_NO_REMOTE': '1', 04:22:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:22:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:24 INFO - 'NO_EM_RESTART': '1', 04:22:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:24 INFO - 'PWD': '/builds/slave/test', 04:22:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:22:24 INFO - 'SHELL': '/bin/bash', 04:22:24 INFO - 'SHLVL': '1', 04:22:24 INFO - 'TERM': 'linux', 04:22:24 INFO - 'TMOUT': '86400', 04:22:24 INFO - 'USER': 'cltbld', 04:22:24 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1474370492.718580-1159804280', 04:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:24 INFO - '_': '/tools/buildbot/bin/python'} 04:22:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:22:25 INFO - mozversion application_buildid: 20160920021501 04:22:25 INFO - mozversion application_changeset: 4a3775a4a1ab7ec6adef8ca6518bad4ab3f4e4bc 04:22:25 INFO - mozversion application_display_name: Nightly 04:22:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:22:25 INFO - mozversion application_name: Firefox 04:22:25 INFO - mozversion application_remotingname: firefox 04:22:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:22:25 INFO - mozversion application_vendor: Mozilla 04:22:25 INFO - mozversion application_version: 51.0a1 04:22:25 INFO - mozversion platform_buildid: 20160920021501 04:22:25 INFO - mozversion platform_changeset: 4a3775a4a1ab7ec6adef8ca6518bad4ab3f4e4bc 04:22:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:22:25 INFO - mozversion platform_version: 51.0a1 04:22:25 INFO - using testdate: 1474370545 04:22:25 INFO - actual date: 1474370545 04:22:25 INFO - starting webserver on 'localhost:52057' 04:22:25 INFO - SUITE-START | Running 2 tests 04:22:25 INFO - TEST-START | dromaeo_css 04:22:25 INFO - operating with platform_type : linux_ 04:22:25 INFO - Initialising browser for dromaeo_css test... 04:22:25 INFO - TEST-INFO | started process 30390 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOf57fY/profile http://localhost:52057/getInfo.html) 04:22:29 INFO - PROCESS | 30390 | __metrics Screen width/height:1600/1200 04:22:29 INFO - PROCESS | 30390 | colorDepth:24 04:22:29 INFO - PROCESS | 30390 | Browser inner width/height: 1024/697 04:22:29 INFO - PROCESS | 30390 | __metrics 04:22:30 INFO - PROCESS | 30390 | 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" 04:22:30 INFO - PROCESS | 30390 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:22:30 INFO - PROCESS | 30390 | console.error: PushService: 04:22:30 INFO - PROCESS | 30390 | stateChangeProcessEnqueue: Error transitioning state 04:22:30 INFO - PROCESS | 30390 | UnknownError 04:22:31 INFO - TEST-INFO | 30390: exit 0 04:22:31 INFO - Browser initialized. 04:22:31 INFO - Running cycle 1/1 for dromaeo_css test... 04:22:31 INFO - TEST-INFO | started process 30475 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOf57fY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:22:43 INFO - PROCESS | 30475 | RSS: Main: 156557312 04:22:43 INFO - PROCESS | 30475 | 04:23:34 INFO - PROCESS | 30475 | 1474370614833 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] 04:25:44 INFO - PROCESS | 30475 | Cycle 1(1): loaded http://localhost:52057/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52057/tests/dromaeo/cssquery-ext.html) 04:25:45 INFO - PROCESS | 30475 | RSS: Main: 260509696 04:25:45 INFO - PROCESS | 30475 | 04:28:47 INFO - PROCESS | 30475 | Cycle 1(1): loaded http://localhost:52057/tests/dromaeo/cssquery-ext.html (next: http://localhost:52057/tests/dromaeo/cssquery-jquery.html) 04:28:48 INFO - PROCESS | 30475 | RSS: Main: 244023296 04:28:48 INFO - PROCESS | 30475 | 04:31:50 INFO - PROCESS | 30475 | Cycle 1(1): loaded http://localhost:52057/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52057/tests/dromaeo/cssquery-mootools.html) 04:31:51 INFO - PROCESS | 30475 | RSS: Main: 185716736 04:31:51 INFO - PROCESS | 30475 | 04:34:52 INFO - PROCESS | 30475 | Cycle 1(1): loaded http://localhost:52057/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52057/tests/dromaeo/cssquery-prototype.html) 04:34:53 INFO - PROCESS | 30475 | RSS: Main: 222871552 04:34:53 INFO - PROCESS | 30475 | 04:37:56 INFO - PROCESS | 30475 | Cycle 1(1): loaded http://localhost:52057/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52057/tests/dromaeo/cssquery-yui.html) 04:37:56 INFO - PROCESS | 30475 | RSS: Main: 186818560 04:37:56 INFO - PROCESS | 30475 | 04:40:59 INFO - PROCESS | 30475 | Cycle 1(1): loaded http://localhost:52057/tests/dromaeo/cssquery-yui.html (next: http://localhost:52057/tests/dromaeo/cssquery-dojo.html) 04:40:59 INFO - PROCESS | 30475 | RSS: Main: 279273472 04:40:59 INFO - PROCESS | 30475 | 04:40:59 INFO - PROCESS | 30475 | __start_tp_report 04:40:59 INFO - PROCESS | 30475 | _x_x_mozilla_page_load 04:40:59 INFO - PROCESS | 30475 | _x_x_mozilla_page_load_details 04:40:59 INFO - PROCESS | 30475 | |i|pagename|runs| 04:40:59 INFO - PROCESS | 30475 | |0;dojo.html;3008.0073575720016;3011.9096427107193;3040.3159289159935;3046.3450358173004;3055.6638769735323;5181.715005674687;5212.739363031852;5238.659487133338;5325.174597937316;5366.275552800371;5176.611754118447;5219;5300.337457817773;5362.1420572708375;5371.919421208675;4668.463126740435;4741.7154970701695;4746.121967436018;4758.571728544431;4953.876153096184;4921.212605983043;4925.581325587312;4931.753412329387;4951.455339912607;4967.627427942919;4752.4297084349755;4803.135435621587;4830.359411826243;4875.68308059975;4897.951020489803;4706.670533062687;4713.175194340976;4720.834770783041;4816.879578010543;4859.052484765472;4704.811807527695;4724.645651576111;4769.523047695237;4803.663743537953;4808.086463571945;5134.460881607452;5211.609129315317;5220.321358223407;5233.241180028913;5269.552088072518;5622.8313150605545;5691.715414229313;5692.743826527775;5694.544436445074;5697.800576979827;5701.401352857932;5713.5429165667065;5726.484616384545;5738.799142030009;5741.741621627037;5119.0273847968765;5175.922361164586;5263.052650522943;5317.361916569997;5324.387695415038;5780.306363236438;5780.335261444988;5786.537077033827;5833.912491312633;5836.854078648068;5562.3047119110115;5567.220589117527;5607.186957891083;5610.158476228598;5614.803481878114;5482.533984611353;5521.503064724114;5539.695316757617;5657.632253903483;5710.771569137271;5560.944390556124;5567.359753628303;5569.248151499496;5605.859853503695;5615.6630602164005;5523.613347065747;5563.916541251884;5571.8885622288135;5574.637648552913;5575.888482230333;4248.065425606362;4261.850835220753;4319.092990471973;4345.869623911288;4361.498427680857;3901.258760835432;3910.765354078764;3952.881413557598;3961.9801900990888;3976.463177471004;5349.358077030781;5351.438098999549;5362.892742145135;5366.409694933554;5411.485908838654;5468.699221542774;5556.944430555724;5691.658500489983;5702;5702.173184888168;5255.237990491434;5298.0463516566615;5300.3639563252655;5322.866928326823;5329.467053294717;4800.231962885921;4806.423229212517;4807.014562014779;4809.879753006133;4850.781714822841;5728.198052272685;5785.929603023455;5797.855053623609;5834.824955251349;5848.152017957373;5635.915461268166;5675.631083979528;5676.432356764456;5726.685032323096;5726.942730572808;5912.822615321547;5915.881682366243;5919.141724449844;5919.644821310735;5919.792807251725;5271.947280527145;5476.151196564539;5521.944780552143;5524.116141417354;5588.832335029793;5599.160125981135;5641.210230567806;5654.321481422091;5692.943070569407;5705.286839145107;5475.123980163314;5504.587155963399;5512.779488820403;5513;5520.337559492887;5330.520253177233;5344.331958505187;5502.972485137709;5512.80705175317;5564.137558678412;5271.947280527145;5340.172273297645;5345.4654534546235;5384.569234461155;5393.8112166074;5470.507654311131;5475.370332411664;5514.035043867388;5518.889622207614;5525.944740552543;5288.180332048539;5336.439673834347;5339.7063161526485;5343.305370301836;5346.331708536433 04:40:59 INFO - PROCESS | 30475 | |1;ext.html;576.6251936241442;589.1839801874404;607.3167686352853;607.7417097733462;627.3444250757957;44186.55813441864;45413;45640.08719825606;45651.77174114129;50030.24954625683;47317.29024064633;47732.28401573971;47732.522674773245;47780.522194778045;47840.52159478404;8188.959055204716;9120.17918387345;9248.12142846432;9275.072492750705;10794.190435717313;10584.470776461185;10976.725581860477;10979.780404391908;10980.450977451135;10981.945090274538;11070.501827417746;11112.277701949388;11123.777524449506;11142.164337674667;11250.887491125066;11099;11105.611303604335;11140.721481962935;11142.721431964224;11146.442677866155;10853.620123295646;10938.835917461245;10944.069754070913;10946;10953.726156846064;44422.777886110685;45041.54958450439;45076;45091.54908450939;45103.09793804106;3588.8385022673783;4161.771102589357;4321.286987647034;4323.848665296699;4469.150861336336;34261;34655.826720866484;34837.651623483696;34851.302973940634;34999.30001399983;4383.956160438387;4396.868093957187;4401.9119617607785;4417.624501917308;4537.659675524349;236075;237057.81471092708;237304;237523;240394;62348;62593.68703156455;62728;62770;62845.37154628486;64441.67779161074;64754.67622661951;64918.35081649123;65028;65093.349066509676;16416.67166656684;16661.833381666027;16702.08138552367;16721.414750483676;16723.749143762852;11255.493502792395;11517.078633709283;11528.654140375802;11604.593839215755;12626.494940202472;18321.541961450803;18423.447296581126;18445.7233141503;18459;18723;6242.344553821874;6281;6342.143810585604;6369.331220221903;6549.181352330958;991.3506653142204;1026.650938680852;1080.4381721504774;1113.8663360396804;1119.5297974850582;32409.51385729216;32505.67494325074;32726.672733272837;32862.17844553905;33415.83292083524;1054.593981317183;1062.2033474893828;1073.7798751255973;1087.1900434176432;1131.9886801131884;16849.831501685072;17210.655786884192;17325;17355.21901514435;17368.91315543414;482890;483301;485853;486667;488589;60602.696986514784;60677.69661151666;60728.69635651793;60764;60811.08783368253;1210.7941649919535;1315.848677402111;1357.5995081450997;1362.6252780485247;1385.896057795649;32811.34377312488;33866;34379.312413752086;34593.13517162091;34751.47872781961;52817.7359113202;53746.46253537571;53810.46189538211;53814;53965.460345396044;310104;310176;310331;311408;311977;28981.10188981085;29574.556381654722;29632;30814.383712326075;31112.222194444414;49433.752831237056;49721.75139124281;49945.25082123699;50620.49379506158;50748.74625626848;41259.96850078772;41927.58072419359;41989;42323.57676423196;43460.34809477797;41383.586164139175;41872.581274186865;42204.155916880874;43285.35071973986;43402.782986084865;42177.57822421737;42481.57518424776;42518.5748142527;42603.7869810649;43787;36113.81943090268;38120.809395953955;38658.42012369761;38864.80567597144;38890.85498671561;29043.85478072596;29225.85387073051;29279;29471.557926631547;29480.705192947797 04:40:59 INFO - PROCESS | 30475 | |2;jquery.html;380.4692454026635;388.65992256775337;389.495603193864;400.51537639456257;417.1323646814626;14380.527673790815;17831.37590184342;20044.098015589298;20183;20235.595288094228;19597.314094006713;19607.705884411746;19632.116554755034;19859.404217873485;19958.50103747407;5544.750486228129;5597.63615365002;5702.230198923155;5733.827985160452;5779.306483222019;5707.400722924095;5724.685142317192;5763.308402991626;5769.855753606152;5770.394108618577;5514.80698175564;5673.177389278552;5702.543796496291;5721.628094173865;5722.685252311121;5656.038473459501;5686.3744988051285;5699.686517241571;5699.715014249313;5702.258706368167;4939.925901111486;5102.438731739506;5127.051495473349;5128.615353848439;5134.229865520165;22453.32640020802;22984.885075574683;23121.653175202384;23571.17500887461;23718.881405592863;2458.2379462366725;2817.29567608098;2967.6735559088484;3005.834679092649;3073.0319949215927;10346.741331466774;10672;10698.786024279549;13346.733065338736;13878.861211387857;1932.183936724957;2004.3986803958753;2013.0035632362074;2038.6840039793856;2039.5309078911932;82595.58702206511;86649.56675216583;91362;92670;95356;10871.945640271748;10951.34291942486;11339.433028348549;11522.423878806027;11599.826002609967;15899.761503577456;15940.760888586681;15959.202039897958;15964.042157470356;15973.680526389408;7828.256315650005;8117.512949222947;8150.2664760171865;8189.420332595213;8365.456245344034;7323.597202153826;7569.962150189213;7818.4136189785995;7916.485428447132;8161.55111468863;4694.50707675696;4744.928826067612;4796.841421360332;4806.158922188603;5018.949810501848;1941.951451213731;1965.4496740912568;1995.5210749420035;1995.7405537280351;2028.7869773673847;944.6788092048737;950.8098380323963;968.9176420004237;1000.68478429296;1026.389298367485;19921.800781992286;20265.797342026686;20311;20312.492187695425;20544.38366848997;975.7170420578095;976.4678250353575;978.1001478639669;991.6826615482976;1016.6187679620142;10371.325863818827;10815.405152716676;11278.436078196057;11281.266717663326;11294.152938529478;475097;478538;484575;489325;490239;94807;97546.51226743821;98544;99531.50234248783;99746.5012674932;996.1333639733479;1019.3730855523947;1039.6309310194922;1063.3779239145188;1068.166829872685;17853.46439606814;18220.180091895632;18743;18761.155747991645;18818.778798388965;44942;45361.54638453573;46101.76949115233;46356.30465543087;46694.29958550556;60424.697876510334;61595.384046158964;65838;66272.66863665651;66942;7633.88549171774;7875.094364147967;7977.920220797718;8340.374471914753;8380;8322.50064996102;9564.947855735725;11880.702982425508;13499.39252733649;14587.124772513684;12114.818277726017;12123.878761212627;12369.319687416919;12505.874941250835;12648.936755316165;11961.880381196077;12086.879131208927;12147.75704485923;12254.4485498151;12415.62753117408;12266.38668066611;12339.62981110568;12428.875711242772;12520.121981702818;12525.56160534377;7877.330426913776;8063.87904181426;8599.656013759379;8882;8930.687425940061;11270;11497.195196336175;11514.654560363202;11640.88359116398;11869.881301186877 04:40:59 INFO - PROCESS | 30475 | |3;mootools.html;1336.6190635668836;1423.7935499352593;1498.3931507739367;1586.3575252022927;1806.5303021214488;8240.093589705135;8645.049044605097;8661.78345541361;8870.645174193025;8874.55627218638;8672.47965122093;8760.693375731851;8799.032106468283;8804.075572064938;8807.86788198176;2321.106374045996;2364.9645255321184;2365.95268094638;2366.526694661066;2368.692070030894;2304.539092181562;2351.353377821096;2371.620540713486;2373;2374.95250094998;2327.3367090379247;2352.270796053229;2354.152505098163;2358.705161854768;2360.0323867214447;2275.6927814744963;2291.4042348989215;2299.37916762474;2308.4575124845646;2311.2603966730653;2256.9548609027893;2259.401258666455;2266;2269.931902042941;2271.806896413807;8694.434861734031;8726.214640682369;8774.122587741238;8783.209511143963;8786.824263514758;5111.182210846247;5259.500347467023;5261.289725886994;5323.707196104213;5330.626856120073;3395.5246265522846;3545.2377738786286;3584.354816133095;3598.9100272493133;3604.4052731299303;1074.1782536359692;1125.3697929159614;1132.1735133352615;1146.8107762219224;1147.8565179352581;12408.317542535156;12449.75100497994;12520.81218781719;12522.874771252262;12523.185992910523;4409.955900440955;4509.864704058883;4663.790129444184;4667.883302917454;4669.929951050737;9787.804243915225;9790.21678265737;9802.607895684236;9804.411735295906;9809.607615695293;5248.029114613809;5257.211418287287;5266.7629956652045;5267.683938963674;5269.1832765921345;4347.804348804311;4643.930341044887;4830.961343311205;4852.320675105488;4941.802327906916;3699.4635777812605;3803.391457366808;3839.2705385977133;3874.728768986144;3971.3447281198855;1167.8598568171874;1173.3546549397797;1176.817593273044;1182.5447202826974;1193.3078814287692;957.7749228931195;991.8859331176862;993.5380048277585;1009.1321463541349;1010.9545070471846;5801.854953626109;5869.413058694182;5904.114382842608;6187.659678717714;6304.18045654062;455.16477264220526;456.1993701054628;463.6615270852297;470.9529047095263;477.6704074188868;4046.4132700758223;4195.664346852244;4219.683523735671;4271.594198551133;4288.378185163197;10838.620648277272;11200.10399168081;11273.887261127447;11277.605283815026;11290.266132701512;10350.741231469274;10644.924239773325;11189;11228.438578071064;11229.775404491864;8172.34621230312;8361.623726932303;8381.329493640616;8391.706290279812;8410.03284622264;5986.371430999857;6120.969395152996;6208.751649933952;6211.81364559064;6228.875422491434;10093.5457904393;10146.086852183338;10218.948905255425;10344.017318354745;10398.116160126474;10255.7436064099;10398.636047738595;10446.68659940203;10526.842097368393;10606.734831629272;7873.330766884879;8094.31198348147;8181.345492360716;8201.13888041747;8239.711610093858;8299.543525106177;8359.832803344021;8362.372822038249;8363.581820909052;8369.916300836914;7630.236976302547;7887.9605601971625;7920.287174154354;7967.681292748225;7999.800004999689;7676.577788221478;7719.150893401834;7784.416168787477;7873.763787086396;8014.599270036591;6840.8289792753585;7217.855642887008;7307.086614173229;7312;7317.414606831547;5042.4705405931845;5247.790088396574;5252.4747525248695;5316.229146773773;5349.705766182898;5066.6200034998255;5134.435212126588;5212.113940630026;5214.009338225725;5365.273024847524 04:40:59 INFO - PROCESS | 30475 | |4;prototype.html;321.7393910932145;332.1629493675937;340.4399762390867;341.96238413774495;342.71554609673973;5259.710715910624;5279.4192638809745;5283.867903302419;5292.497212764782;5311.893762124765;4939.851804445872;4966.776495057722;5144.868129011611;5166.844994650157;5268.367795864492;4130.401091841696;4132.400801883725;4141.5444301127;4156.480439945007;4160.999999999993;4150.605692459211;4153.107081977374;4155.480564929384;4163.50037995441;4196.139791342783;3901.2587608354465;3925.3915643075225;4091.611296926802;4095.5699651536606;4106.548279689232;4079.673626109904;4082.836686532535;4087.591240875918;4090.202410529963;4092.6521245694003;3617.113807117273;3617.4212126059706;3621.4748861414946;3621.5654121505327;3626;4997.275395067695;5038.825202079953;5068.645194836363;5105.668131571473;5106.770195341219;4695.201815691306;4698.603712106816;4703.929441058387;4706.105839890444;4709;5386.91919621206;5416.539594134503;5430.484104010152;5437.700926449043;5440.836774896759;3672.8714494992546;3923.4899463069905;3984.2629113613784;3988.182422603388;3988.4615576897036;5624.465675760796;5653.547716182696;5663.470862867003;5667.6316039457715;5669.461401166883;4038.8990275243355;4044.3124668805785;4050;4051.6150965658217;4059.4316795648633;3910.354791459434;4127;4138.317177665712;4142.958570414257;4146.963259185204;4651.837185698484;4652.604528615106;4655.2784318430695;4655.766221951186;4678.391809064799;5418.539424148991;5455.454454554513;5485.286912701324;5504.284443022381;5518.310211223597;1370.465518447806;1391.872583207605;1408.3310427546833;1413.3675604676614;1430.3777856632398;878.7846977490556;879.8210398066622;880.0011986395488;882.3955590420583;892.8705337725993;4646.233371493666;4659.883502912454;4663.836765713251;4670.626349892001;4693.319468677023;4926.9014619707405;4931.238506436407;4947.60419166473;4951.638299467603;4951.90096198074;4676.766161691902;4687.812487500461;4889.633277504201;5268.4994925482015;5285.709285989307;2944.131481213047;2945.572891930658;2946.499095153829;2953.6012638293987;2969.2576855786056;5674.602777805514;5829.475347218686;5830.125481177773;5833.854153646108;5839.532837373;5609.27079479665;5618.943810561924;5636.802711905064;5638.182463542846;5645.379008309083;5765.818007308491;5803.796867109715;5807.593468457168;5814.5463634091475;5827.388124246893;5353.2773075634295;5533.557315414838;5583.55331573465;5587.69267690281;5597.608167428241;5520.227168196376;5528.944710552843;5538.833834984957;5538.97230513861;5539.390667026704;5770.13447982806;5787.623804452697;5801.245838041028;5819.272590926134;5827.125931110368;3791.009467625459;3959.1883663849417;3988.461557689791;3990;4016.758994460342;5361.329833770778;5416.2688037114485;5571.247881535941;5591.580631452739;5606.579506536945;5407.7025763581805;5431;5439.48324909133;5441.2044025470705;5447.428020057995;5276.968367178587;5410.891782164414;5419.945800541944;5498.477644623755;5499.780008799603;5373.382061063028;5385.40760516335;5412.8646783829145;5412.891742165056;5438.401775804738;5463.879904619464;5486.369067557282;5488.4237155098135;5493.697846618475;5509.063459211863;5193.246979188072;5292.9470705294;5325.254464375068;5330.946690533045;5373.596980226421 04:40:59 INFO - PROCESS | 30475 | |5;yui.html;727.741651713642;729.8832186850103;767.1982777996992;785.6396996668495;875.6453636277308;15853;17060.914695426538;17106.14469276536;17330.653386932252;17898.64202715945;16863;16952;16967.15164241789;17000.744988825147;17001.659966800657;683.9794806155824;805.2591615713558;818.2962652119188;839.7606682095604;845.8308338332328;809.4576633655436;812.6180695073335;822.7943014246439;823.8846561481397;825.3892119831341;870.9635533714859;871.6164887449502;871.7559083456642;872.0189786490198;872.9301655867547;701.7473709464589;841.8358420108082;869.4826578186;869.5043825019741;869.8695195720629;627.466653344658;627.9183706118221;628.5223230344925;629.697745082363;629.9527535434861;16766;16781.160941952854;16800.83199168005;16802.411915582896;16802.83197168025;14697.55907322782;14740.926295368454;14800.11199328033;14870.405183792634;14881.33034013472;13543.59369218925;13571.660708482268;13710.794338084937;13739.038267321292;14491.710165796625;4554.954450455486;4556.7265964041935;4582.358469814229;4765.046990601859;4813.614910807127;86290;87394.99999999936;87989;89484;92620.53689731508;11595.344708944629;11728.413579321;11815.763684726257;11910.702232444259;12016.09879259059;21416.78583214148;21449.785502145092;21632.134714611555;21683.24108656221;21771.564568708538;8355.749327520183;8388.454750441202;8480.236778689947;8508.532030738244;8571.914280857112;4877.5366340197625;5014.34813474246;5030.622703297267;5037.278241618633;5044.596432285409;3302.091924720721;3392.2197894484407;3392.5759280089987;3392.8473218705217;3394.1853955051106;618.5700937848152;619.801663467695;621.4841681404423;637.4900079936125;646.8156575375996;580.8489792653941;633.616661919532;643.652129862774;668.7258224128182;695.9408450281682;9728.95135524332;9887.851682224773;10103.84844227337;10377.117944974761;10443.84334234987;618.6937465954381;666.1074160624786;679.5918468878577;688.8897776355859;704.1831475488409;4143.647789937829;4211.894702632459;4480.70875393105;4564.726116433025;4574.908501829945;97779;108276.4586177064;122713;123955;126509.36745316215;95927.52036239774;101727.4913625442;102512.48743756233;103834;104488.64090872712;54049.45950540444;83783.16216837875;83869.16130838613;87744;90198.09801902028;10089.243306751876;10256.897431025594;10300.577977425768;10454.477276136315;10535.4732263385;45744;53890.73054634702;53895.46104538904;55185.72407137939;55278;70197.19211231494;77005;77355;78630.6068469654;79826.60086699529;13171.538996135472;13237.735245295233;13370.130941488773;13634.250116243702;13637.590872273848;15178.924105379403;15366.385344586091;15367.846321536641;15398.846011539741;15944.362225511313;14255.215963122128;14450.277486125862;14507.564773056825;14551.85448145505;14816.777748333569;13892.861071389158;14410.279486025867;14440.783388248974;14441;14525.564233073024;14321.713565728418;14888.925555372154;15133.54599362021;15283;15308;11584.652460426201;11624.709382265512;11884.584039558575;11951.701207469536;12124.696882578006;10490.947545262225;10576.629817956335;12200.450979705756;12220.144589878622;12615.621531354074 04:40:59 INFO - PROCESS | 30475 | __end_tp_report 04:40:59 INFO - PROCESS | 30475 | __start_cc_report 04:40:59 INFO - PROCESS | 30475 | _x_x_mozilla_cycle_collect,994 04:40:59 INFO - PROCESS | 30475 | __end_cc_report 04:40:59 INFO - PROCESS | 30475 | __startTimestamp1474371659851__endTimestamp 04:40:59 INFO - PROCESS | 30475 | 04:40:59 INFO - PROCESS | 30475 | ------- Summary: start ------- 04:40:59 INFO - PROCESS | 30475 | Number of tests: 6 04:40:59 INFO - PROCESS | 30475 | 04:40:59 INFO - PROCESS | 30475 | [#0] dojo.html Cycles:165 Average:5232.36 Median:5366.28 stddev:589.97 (11.0%) stddev-sans-first:565.39 04:40:59 INFO - PROCESS | 30475 | Values: 3008.0 3011.9 3040.3 3046.3 3055.7 5181.7 5212.7 5238.7 5325.2 5366.3 5176.6 5219.0 5300.3 5362.1 5371.9 4668.5 4741.7 4746.1 4758.6 4953.9 4921.2 4925.6 4931.8 4951.5 4967.6 4752.4 4803.1 4830.4 4875.7 4898.0 4706.7 4713.2 4720.8 4816.9 4859.1 4704.8 4724.6 4769.5 4803.7 4808.1 5134.5 5211.6 5220.3 5233.2 5269.6 5622.8 5691.7 5692.7 5694.5 5697.8 5701.4 5713.5 5726.5 5738.8 5741.7 5119.0 5175.9 5263.1 5317.4 5324.4 5780.3 5780.3 5786.5 5833.9 5836.9 5562.3 5567.2 5607.2 5610.2 5614.8 5482.5 5521.5 5539.7 5657.6 5710.8 5560.9 5567.4 5569.2 5605.9 5615.7 5523.6 5563.9 5571.9 5574.6 5575.9 4248.1 4261.9 4319.1 4345.9 4361.5 3901.3 3910.8 3952.9 3962.0 3976.5 5349.4 5351.4 5362.9 5366.4 5411.5 5468.7 5556.9 5691.7 5702.0 5702.2 5255.2 5298.0 5300.4 5322.9 5329.5 4800.2 4806.4 4807.0 4809.9 4850.8 5728.2 5785.9 5797.9 5834.8 5848.2 5635.9 5675.6 5676.4 5726.7 5726.9 5912.8 5915.9 5919.1 5919.6 5919.8 5271.9 5476.2 5521.9 5524.1 5588.8 5599.2 5641.2 5654.3 5692.9 5705.3 5475.1 5504.6 5512.8 5513.0 5520.3 5330.5 5344.3 5503.0 5512.8 5564.1 5271.9 5340.2 5345.5 5384.6 5393.8 5470.5 5475.4 5514.0 5518.9 5525.9 5288.2 5336.4 5339.7 5343.3 5346.3 04:40:59 INFO - PROCESS | 30475 | 04:40:59 INFO - PROCESS | 30475 | [#1] ext.html Cycles:180 Average:53543.43 Median:19851.33 stddev:94980.27 (478.5%) stddev-sans-first:95163.46 04:40:59 INFO - PROCESS | 30475 | Values: 576.6 589.2 607.3 607.7 627.3 44186.6 45413.0 45640.1 45651.8 50030.2 47317.3 47732.3 47732.5 47780.5 47840.5 8189.0 9120.2 9248.1 9275.1 10794.2 10584.5 10976.7 10979.8 10980.5 10981.9 11070.5 11112.3 11123.8 11142.2 11250.9 11099.0 11105.6 11140.7 11142.7 11146.4 10853.6 10938.8 10944.1 10946.0 10953.7 44422.8 45041.5 45076.0 45091.5 45103.1 3588.8 4161.8 4321.3 4323.8 4469.2 34261.0 34655.8 34837.7 34851.3 34999.3 4384.0 4396.9 4401.9 4417.6 4537.7 236075.0 237057.8 237304.0 237523.0 240394.0 62348.0 62593.7 62728.0 62770.0 62845.4 64441.7 64754.7 64918.4 65028.0 65093.3 16416.7 16661.8 16702.1 16721.4 16723.7 11255.5 11517.1 11528.7 11604.6 12626.5 18321.5 18423.4 18445.7 18459.0 18723.0 6242.3 6281.0 6342.1 6369.3 6549.2 991.4 1026.7 1080.4 1113.9 1119.5 32409.5 32505.7 32726.7 32862.2 33415.8 1054.6 1062.2 1073.8 1087.2 1132.0 16849.8 17210.7 17325.0 17355.2 17368.9 482890.0 483301.0 485853.0 486667.0 488589.0 60602.7 60677.7 60728.7 60764.0 60811.1 1210.8 1315.8 1357.6 1362.6 1385.9 32811.3 33866.0 34379.3 34593.1 34751.5 52817.7 53746.5 53810.5 53814.0 53965.5 310104.0 310176.0 310331.0 311408.0 311977.0 28981.1 29574.6 29632.0 30814.4 31112.2 49433.8 49721.8 49945.3 50620.5 50748.7 41260.0 41927.6 41989.0 42323.6 43460.3 41383.6 41872.6 42204.2 43285.4 43402.8 42177.6 42481.6 42518.6 42603.8 43787.0 36113.8 38120.8 38658.4 38864.8 38890.9 29043.9 29225.9 29279.0 29471.6 29480.7 04:40:59 INFO - PROCESS | 30475 | 04:40:59 INFO - PROCESS | 30475 | [#2] jquery.html Cycles:180 Average:29639.14 Median:384.56 stddev:80156.69 (20843.5%) stddev-sans-first:80351.44 04:40:59 INFO - PROCESS | 30475 | Values: 380.5 388.7 389.5 400.5 417.1 14380.5 17831.4 20044.1 20183.0 20235.6 19597.3 19607.7 19632.1 19859.4 19958.5 5544.8 5597.6 5702.2 5733.8 5779.3 5707.4 5724.7 5763.3 5769.9 5770.4 5514.8 5673.2 5702.5 5721.6 5722.7 5656.0 5686.4 5699.7 5699.7 5702.3 4939.9 5102.4 5127.1 5128.6 5134.2 22453.3 22984.9 23121.7 23571.2 23718.9 2458.2 2817.3 2967.7 3005.8 3073.0 10346.7 10672.0 10698.8 13346.7 13878.9 1932.2 2004.4 2013.0 2038.7 2039.5 82595.6 86649.6 91362.0 92670.0 95356.0 10871.9 10951.3 11339.4 11522.4 11599.8 15899.8 15940.8 15959.2 15964.0 15973.7 7828.3 8117.5 8150.3 8189.4 8365.5 7323.6 7570.0 7818.4 7916.5 8161.6 4694.5 4744.9 4796.8 4806.2 5018.9 1942.0 1965.4 1995.5 1995.7 2028.8 944.7 950.8 968.9 1000.7 1026.4 19921.8 20265.8 20311.0 20312.5 20544.4 975.7 976.5 978.1 991.7 1016.6 10371.3 10815.4 11278.4 11281.3 11294.2 475097.0 478538.0 484575.0 489325.0 490239.0 94807.0 97546.5 98544.0 99531.5 99746.5 996.1 1019.4 1039.6 1063.4 1068.2 17853.5 18220.2 18743.0 18761.2 18818.8 44942.0 45361.5 46101.8 46356.3 46694.3 60424.7 61595.4 65838.0 66272.7 66942.0 7633.9 7875.1 7977.9 8340.4 8380.0 8322.5 9564.9 11880.7 13499.4 14587.1 12114.8 12123.9 12369.3 12505.9 12648.9 11961.9 12086.9 12147.8 12254.4 12415.6 12266.4 12339.6 12428.9 12520.1 12525.6 7877.3 8063.9 8599.7 8882.0 8930.7 11270.0 11497.2 11514.7 11640.9 11869.9 04:40:59 INFO - PROCESS | 30475 | 04:40:59 INFO - PROCESS | 30475 | [#3] mootools.html Cycles:180 Average:5830.51 Median:4668.91 stddev:3346.89 (71.7%) stddev-sans-first:3339.24 04:40:59 INFO - PROCESS | 30475 | Values: 1336.6 1423.8 1498.4 1586.4 1806.5 8240.1 8645.0 8661.8 8870.6 8874.6 8672.5 8760.7 8799.0 8804.1 8807.9 2321.1 2365.0 2366.0 2366.5 2368.7 2304.5 2351.4 2371.6 2373.0 2375.0 2327.3 2352.3 2354.2 2358.7 2360.0 2275.7 2291.4 2299.4 2308.5 2311.3 2257.0 2259.4 2266.0 2269.9 2271.8 8694.4 8726.2 8774.1 8783.2 8786.8 5111.2 5259.5 5261.3 5323.7 5330.6 3395.5 3545.2 3584.4 3598.9 3604.4 1074.2 1125.4 1132.2 1146.8 1147.9 12408.3 12449.8 12520.8 12522.9 12523.2 4410.0 4509.9 4663.8 4667.9 4669.9 9787.8 9790.2 9802.6 9804.4 9809.6 5248.0 5257.2 5266.8 5267.7 5269.2 4347.8 4643.9 4831.0 4852.3 4941.8 3699.5 3803.4 3839.3 3874.7 3971.3 1167.9 1173.4 1176.8 1182.5 1193.3 957.8 991.9 993.5 1009.1 1011.0 5801.9 5869.4 5904.1 6187.7 6304.2 455.2 456.2 463.7 471.0 477.7 4046.4 4195.7 4219.7 4271.6 4288.4 10838.6 11200.1 11273.9 11277.6 11290.3 10350.7 10644.9 11189.0 11228.4 11229.8 8172.3 8361.6 8381.3 8391.7 8410.0 5986.4 6121.0 6208.8 6211.8 6228.9 10093.5 10146.1 10218.9 10344.0 10398.1 10255.7 10398.6 10446.7 10526.8 10606.7 7873.3 8094.3 8181.3 8201.1 8239.7 8299.5 8359.8 8362.4 8363.6 8369.9 7630.2 7888.0 7920.3 7967.7 7999.8 7676.6 7719.2 7784.4 7873.8 8014.6 6840.8 7217.9 7307.1 7312.0 7317.4 5042.5 5247.8 5252.5 5316.2 5349.7 5066.6 5134.4 5212.1 5214.0 5365.3 04:40:59 INFO - PROCESS | 30475 | 04:40:59 INFO - PROCESS | 30475 | [#4] prototype.html Cycles:180 Average:4561.34 Median:5155.86 stddev:1331.61 (25.8%) stddev-sans-first:1296.77 04:40:59 INFO - PROCESS | 30475 | Values: 321.7 332.2 340.4 342.0 342.7 5259.7 5279.4 5283.9 5292.5 5311.9 4939.9 4966.8 5144.9 5166.8 5268.4 4130.4 4132.4 4141.5 4156.5 4161.0 4150.6 4153.1 4155.5 4163.5 4196.1 3901.3 3925.4 4091.6 4095.6 4106.5 4079.7 4082.8 4087.6 4090.2 4092.7 3617.1 3617.4 3621.5 3621.6 3626.0 4997.3 5038.8 5068.6 5105.7 5106.8 4695.2 4698.6 4703.9 4706.1 4709.0 5386.9 5416.5 5430.5 5437.7 5440.8 3672.9 3923.5 3984.3 3988.2 3988.5 5624.5 5653.5 5663.5 5667.6 5669.5 4038.9 4044.3 4050.0 4051.6 4059.4 3910.4 4127.0 4138.3 4143.0 4147.0 4651.8 4652.6 4655.3 4655.8 4678.4 5418.5 5455.5 5485.3 5504.3 5518.3 1370.5 1391.9 1408.3 1413.4 1430.4 878.8 879.8 880.0 882.4 892.9 4646.2 4659.9 4663.8 4670.6 4693.3 4926.9 4931.2 4947.6 4951.6 4951.9 4676.8 4687.8 4889.6 5268.5 5285.7 2944.1 2945.6 2946.5 2953.6 2969.3 5674.6 5829.5 5830.1 5833.9 5839.5 5609.3 5618.9 5636.8 5638.2 5645.4 5765.8 5803.8 5807.6 5814.5 5827.4 5353.3 5533.6 5583.6 5587.7 5597.6 5520.2 5528.9 5538.8 5539.0 5539.4 5770.1 5787.6 5801.2 5819.3 5827.1 3791.0 3959.2 3988.5 3990.0 4016.8 5361.3 5416.3 5571.2 5591.6 5606.6 5407.7 5431.0 5439.5 5441.2 5447.4 5277.0 5410.9 5419.9 5498.5 5499.8 5373.4 5385.4 5412.9 5412.9 5438.4 5463.9 5486.4 5488.4 5493.7 5509.1 5193.2 5292.9 5325.3 5330.9 5373.6 04:40:59 INFO - PROCESS | 30475 | 04:40:59 INFO - PROCESS | 30475 | [#5] yui.html Cycles:180 Average:21599.89 Median:3768.92 stddev:30725.59 (815.2%) stddev-sans-first:30771.82 04:40:59 INFO - PROCESS | 30475 | Values: 727.7 729.9 767.2 785.6 875.6 15853.0 17060.9 17106.1 17330.7 17898.6 16863.0 16952.0 16967.2 17000.7 17001.7 684.0 805.3 818.3 839.8 845.8 809.5 812.6 822.8 823.9 825.4 871.0 871.6 871.8 872.0 872.9 701.7 841.8 869.5 869.5 869.9 627.5 627.9 628.5 629.7 630.0 16766.0 16781.2 16800.8 16802.4 16802.8 14697.6 14740.9 14800.1 14870.4 14881.3 13543.6 13571.7 13710.8 13739.0 14491.7 4555.0 4556.7 4582.4 4765.0 4813.6 86290.0 87395.0 87989.0 89484.0 92620.5 11595.3 11728.4 11815.8 11910.7 12016.1 21416.8 21449.8 21632.1 21683.2 21771.6 8355.7 8388.5 8480.2 8508.5 8571.9 4877.5 5014.3 5030.6 5037.3 5044.6 3302.1 3392.2 3392.6 3392.8 3394.2 618.6 619.8 621.5 637.5 646.8 580.8 633.6 643.7 668.7 695.9 9729.0 9887.9 10103.8 10377.1 10443.8 618.7 666.1 679.6 688.9 704.2 4143.6 4211.9 4480.7 4564.7 4574.9 97779.0 108276.5 122713.0 123955.0 126509.4 95927.5 101727.5 102512.5 103834.0 104488.6 54049.5 83783.2 83869.2 87744.0 90198.1 10089.2 10256.9 10300.6 10454.5 10535.5 45744.0 53890.7 53895.5 55185.7 55278.0 70197.2 77005.0 77355.0 78630.6 79826.6 13171.5 13237.7 13370.1 13634.3 13637.6 15178.9 15366.4 15367.8 15398.8 15944.4 14255.2 14450.3 14507.6 14551.9 14816.8 13892.9 14410.3 14440.8 14441.0 14525.6 14321.7 14888.9 15133.5 15283.0 15308.0 11584.7 11624.7 11884.6 11951.7 12124.7 10490.9 10576.6 12200.5 12220.1 12615.6 04:40:59 INFO - PROCESS | 30475 | -------- Summary: end -------- 04:40:59 INFO - PROCESS | 30475 | 04:41:00 INFO - TEST-INFO | 30475: exit 0 04:41:00 INFO - TEST-OK | dromaeo_css | took 1115589ms 04:41:00 INFO - TEST-START | kraken 04:41:00 INFO - operating with platform_type : linux_ 04:41:00 INFO - Initialising browser for kraken test... 04:41:00 INFO - TEST-INFO | started process 30664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP8HUcZ/profile http://localhost:52057/getInfo.html) 04:41:05 INFO - PROCESS | 30664 | __metrics Screen width/height:1600/1200 04:41:05 INFO - PROCESS | 30664 | colorDepth:24 04:41:05 INFO - PROCESS | 30664 | Browser inner width/height: 1024/697 04:41:05 INFO - PROCESS | 30664 | __metrics 04:41:06 INFO - PROCESS | 30664 | 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" 04:41:06 INFO - PROCESS | 30664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:41:06 INFO - PROCESS | 30664 | console.error: PushService: 04:41:06 INFO - PROCESS | 30664 | stateChangeProcessEnqueue: Error transitioning state 04:41:06 INFO - PROCESS | 30664 | UnknownError 04:41:07 INFO - TEST-INFO | 30664: exit 0 04:41:07 INFO - Browser initialized. 04:41:07 INFO - Running cycle 1/1 for kraken test... 04:41:07 INFO - TEST-INFO | started process 30745 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP8HUcZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:41:19 INFO - PROCESS | 30745 | RSS: Main: 157261824 04:41:19 INFO - PROCESS | 30745 | 04:41:21 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52057/tests/kraken/driver.html?testName=audio-beat-detection) 04:41:22 INFO - PROCESS | 30745 | RSS: Main: 213618688 04:41:22 INFO - PROCESS | 30745 | 04:41:25 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52057/tests/kraken/driver.html?testName=audio-dft) 04:41:25 INFO - PROCESS | 30745 | RSS: Main: 186720256 04:41:25 INFO - PROCESS | 30745 | 04:41:28 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52057/tests/kraken/driver.html?testName=audio-fft) 04:41:29 INFO - PROCESS | 30745 | RSS: Main: 269627392 04:41:29 INFO - PROCESS | 30745 | 04:41:31 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52057/tests/kraken/driver.html?testName=audio-oscillator) 04:41:32 INFO - PROCESS | 30745 | RSS: Main: 180215808 04:41:32 INFO - PROCESS | 30745 | 04:41:34 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52057/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:41:34 INFO - PROCESS | 30745 | RSS: Main: 184729600 04:41:34 INFO - PROCESS | 30745 | 04:41:37 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52057/tests/kraken/driver.html?testName=imaging-darkroom) 04:41:38 INFO - PROCESS | 30745 | RSS: Main: 220884992 04:41:38 INFO - PROCESS | 30745 | 04:41:41 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52057/tests/kraken/driver.html?testName=imaging-desaturate) 04:41:42 INFO - PROCESS | 30745 | RSS: Main: 220246016 04:41:42 INFO - PROCESS | 30745 | 04:41:45 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52057/tests/kraken/driver.html?testName=json-parse-financial) 04:41:45 INFO - PROCESS | 30745 | RSS: Main: 219766784 04:41:45 INFO - PROCESS | 30745 | 04:41:47 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52057/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:41:47 INFO - PROCESS | 30745 | RSS: Main: 184135680 04:41:47 INFO - PROCESS | 30745 | 04:41:49 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52057/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:41:50 INFO - PROCESS | 30745 | RSS: Main: 188854272 04:41:50 INFO - PROCESS | 30745 | 04:41:52 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52057/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:41:52 INFO - PROCESS | 30745 | RSS: Main: 194273280 04:41:52 INFO - PROCESS | 30745 | 04:41:55 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52057/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:41:56 INFO - PROCESS | 30745 | RSS: Main: 189181952 04:41:56 INFO - PROCESS | 30745 | 04:41:58 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52057/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:41:59 INFO - PROCESS | 30745 | RSS: Main: 192282624 04:41:59 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | Cycle 1(1): loaded http://localhost:52057/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52057/tests/kraken/driver.html?testName=ai-astar) 04:42:01 INFO - PROCESS | 30745 | RSS: Main: 243036160 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | __start_tp_report 04:42:01 INFO - PROCESS | 30745 | _x_x_mozilla_page_load 04:42:01 INFO - PROCESS | 30745 | _x_x_mozilla_page_load_details 04:42:01 INFO - PROCESS | 30745 | |i|pagename|runs| 04:42:01 INFO - PROCESS | 30745 | |0;ai-astar;103;111;113;110;112;111;111;113;110;111 04:42:01 INFO - PROCESS | 30745 | |1;audio-beat-detection;152;119;120;172;121;120;151;117;163;121 04:42:01 INFO - PROCESS | 30745 | |2;audio-dft;162;161;160;161;160;162;160;162;160;163 04:42:01 INFO - PROCESS | 30745 | |3;audio-fft;75;75;76;116;75;76;75;74;107;75 04:42:01 INFO - PROCESS | 30745 | |4;audio-oscillator;83;85;114;84;84;84;120;84;84;122 04:42:01 INFO - PROCESS | 30745 | |5;imaging-gaussian-blur;116;117;117;117;116;117;116;117;118;117 04:42:01 INFO - PROCESS | 30745 | |6;imaging-darkroom;149;147;148;150;149;148;148;148;149;144 04:42:01 INFO - PROCESS | 30745 | |7;imaging-desaturate;95;96;95;95;95;95;95;95;96;95 04:42:01 INFO - PROCESS | 30745 | |8;json-parse-financial;49;49;49;49;49;48;50;49;48;49 04:42:01 INFO - PROCESS | 30745 | |9;json-stringify-tinderbox;60;61;59;59;61;59;60;60;59;60 04:42:01 INFO - PROCESS | 30745 | |10;stanford-crypto-aes;76;75;75;76;77;76;75;76;75;76 04:42:01 INFO - PROCESS | 30745 | |11;stanford-crypto-ccm;130;130;122;128;120;121;124;122;162;121 04:42:01 INFO - PROCESS | 30745 | |12;stanford-crypto-pbkdf2;159;163;162;164;161;163;160;158;158;160 04:42:01 INFO - PROCESS | 30745 | |13;stanford-crypto-sha256-iterative;63;64;64;63;63;63;62;63;62;60 04:42:01 INFO - PROCESS | 30745 | __end_tp_report 04:42:01 INFO - PROCESS | 30745 | __start_cc_report 04:42:01 INFO - PROCESS | 30745 | _x_x_mozilla_cycle_collect,1678 04:42:01 INFO - PROCESS | 30745 | __end_cc_report 04:42:01 INFO - PROCESS | 30745 | __startTimestamp1474371721329__endTimestamp 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | ------- Summary: start ------- 04:42:01 INFO - PROCESS | 30745 | Number of tests: 14 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:2.84 (2.6%) stddev-sans-first:1.12 04:42:01 INFO - PROCESS | 30745 | Values: 103.0 111.0 113.0 110.0 112.0 111.0 111.0 113.0 110.0 111.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:136.00 stddev:21.39 (15.7%) stddev-sans-first:21.84 04:42:01 INFO - PROCESS | 30745 | Values: 152.0 119.0 120.0 172.0 121.0 120.0 151.0 117.0 163.0 121.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.10 (0.7%) stddev-sans-first:1.12 04:42:01 INFO - PROCESS | 30745 | Values: 162.0 161.0 160.0 161.0 160.0 162.0 160.0 162.0 160.0 163.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.00 stddev:15.49 (20.7%) stddev-sans-first:16.20 04:42:01 INFO - PROCESS | 30745 | Values: 75.0 75.0 76.0 116.0 75.0 76.0 75.0 74.0 107.0 75.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:84.00 stddev:16.87 (20.1%) stddev-sans-first:17.38 04:42:01 INFO - PROCESS | 30745 | Values: 83.0 85.0 114.0 84.0 84.0 84.0 120.0 84.0 84.0 122.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 04:42:01 INFO - PROCESS | 30745 | Values: 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 118.0 117.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.50 stddev:1.63 (1.1%) stddev-sans-first:1.69 04:42:01 INFO - PROCESS | 30745 | Values: 149.0 147.0 148.0 150.0 149.0 148.0 148.0 148.0 149.0 144.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:42:01 INFO - PROCESS | 30745 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#8] json-parse-financial Cycles:10 Average:48.90 Median:49.00 stddev:0.57 (1.2%) stddev-sans-first:0.60 04:42:01 INFO - PROCESS | 30745 | Values: 49.0 49.0 49.0 49.0 49.0 48.0 50.0 49.0 48.0 49.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.79 (1.3%) stddev-sans-first:0.83 04:42:01 INFO - PROCESS | 30745 | Values: 60.0 61.0 59.0 59.0 61.0 59.0 60.0 60.0 59.0 60.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:76.00 stddev:0.67 (0.9%) stddev-sans-first:0.71 04:42:01 INFO - PROCESS | 30745 | Values: 76.0 75.0 75.0 76.0 77.0 76.0 75.0 76.0 75.0 76.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:126.00 stddev:12.53 (9.9%) stddev-sans-first:13.27 04:42:01 INFO - PROCESS | 30745 | Values: 130.0 130.0 122.0 128.0 120.0 121.0 124.0 122.0 162.0 121.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:161.50 stddev:2.15 (1.3%) stddev-sans-first:2.18 04:42:01 INFO - PROCESS | 30745 | Values: 159.0 163.0 162.0 164.0 161.0 163.0 160.0 158.0 158.0 160.0 04:42:01 INFO - PROCESS | 30745 | 04:42:01 INFO - PROCESS | 30745 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.16 (1.8%) stddev-sans-first:1.22 04:42:01 INFO - PROCESS | 30745 | Values: 63.0 64.0 64.0 63.0 63.0 63.0 62.0 63.0 62.0 60.0 04:42:01 INFO - PROCESS | 30745 | -------- Summary: end -------- 04:42:01 INFO - PROCESS | 30745 | 04:42:02 INFO - TEST-INFO | 30745: exit 0 04:42:02 INFO - TEST-OK | kraken | took 61693ms 04:42:02 INFO - SUITE-END | took 1177s 04:42:02 INFO - Completed test suite (00:19:37) 04:42:02 INFO - javascript benchmark 04:42:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3008.0073575720016, 3011.9096427107193, 3040.3159289159935, 3046.3450358173004, 3055.6638769735323, 5181.715005674687, 5212.739363031852, 5238.659487133338, 5325.174597937316, 5366.275552800371, 5176.611754118447, 5219.0, 5300.337457817773, 5362.1420572708375, 5371.919421208675, 4668.463126740435, 4741.7154970701695, 4746.121967436018, 4758.571728544431, 4953.876153096184, 4921.212605983043, 4925.581325587312, 4931.753412329387, 4951.455339912607, 4967.627427942919, 4752.4297084349755, 4803.135435621587, 4830.359411826243, 4875.68308059975, 4897.951020489803, 4706.670533062687, 4713.175194340976, 4720.834770783041, 4816.879578010543, 4859.052484765472, 4704.811807527695, 4724.645651576111, 4769.523047695237, 4803.663743537953, 4808.086463571945, 5134.460881607452, 5211.609129315317, 5220.321358223407, 5233.241180028913, 5269.552088072518, 5622.8313150605545, 5691.715414229313, 5692.743826527775, 5694.544436445074, 5697.800576979827, 5701.401352857932, 5713.5429165667065, 5726.484616384545, 5738.799142030009, 5741.741621627037, 5119.0273847968765, 5175.922361164586, 5263.052650522943, 5317.361916569997, 5324.387695415038, 5780.306363236438, 5780.335261444988, 5786.537077033827, 5833.912491312633, 5836.854078648068, 5562.3047119110115, 5567.220589117527, 5607.186957891083, 5610.158476228598, 5614.803481878114, 5482.533984611353, 5521.503064724114, 5539.695316757617, 5657.632253903483, 5710.771569137271, 5560.944390556124, 5567.359753628303, 5569.248151499496, 5605.859853503695, 5615.6630602164005, 5523.613347065747, 5563.916541251884, 5571.8885622288135, 5574.637648552913, 5575.888482230333, 4248.065425606362, 4261.850835220753, 4319.092990471973, 4345.869623911288, 4361.498427680857, 3901.258760835432, 3910.765354078764, 3952.881413557598, 3961.9801900990888, 3976.463177471004, 5349.358077030781, 5351.438098999549, 5362.892742145135, 5366.409694933554, 5411.485908838654, 5468.699221542774, 5556.944430555724, 5691.658500489983, 5702.0, 5702.173184888168, 5255.237990491434, 5298.0463516566615, 5300.3639563252655, 5322.866928326823, 5329.467053294717, 4800.231962885921, 4806.423229212517, 4807.014562014779, 4809.879753006133, 4850.781714822841, 5728.198052272685, 5785.929603023455, 5797.855053623609, 5834.824955251349, 5848.152017957373, 5635.915461268166, 5675.631083979528, 5676.432356764456, 5726.685032323096, 5726.942730572808, 5912.822615321547, 5915.881682366243, 5919.141724449844, 5919.644821310735, 5919.792807251725, 5271.947280527145, 5476.151196564539, 5521.944780552143, 5524.116141417354, 5588.832335029793, 5599.160125981135, 5641.210230567806, 5654.321481422091, 5692.943070569407, 5705.286839145107, 5475.123980163314, 5504.587155963399, 5512.779488820403, 5513.0, 5520.337559492887, 5330.520253177233, 5344.331958505187, 5502.972485137709, 5512.80705175317, 5564.137558678412, 5271.947280527145, 5340.172273297645, 5345.4654534546235, 5384.569234461155, 5393.8112166074, 5470.507654311131, 5475.370332411664, 5514.035043867388, 5518.889622207614, 5525.944740552543, 5288.180332048539, 5336.439673834347, 5339.7063161526485, 5343.305370301836, 5346.331708536433], "value": 5192.879139695599, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.6251936241442, 589.1839801874404, 607.3167686352853, 607.7417097733462, 627.3444250757957, 44186.55813441864, 45413.0, 45640.08719825606, 45651.77174114129, 50030.24954625683, 47317.29024064633, 47732.28401573971, 47732.522674773245, 47780.522194778045, 47840.52159478404, 8188.959055204716, 9120.17918387345, 9248.12142846432, 9275.072492750705, 10794.190435717313, 10584.470776461185, 10976.725581860477, 10979.780404391908, 10980.450977451135, 10981.945090274538, 11070.501827417746, 11112.277701949388, 11123.777524449506, 11142.164337674667, 11250.887491125066, 11099.0, 11105.611303604335, 11140.721481962935, 11142.721431964224, 11146.442677866155, 10853.620123295646, 10938.835917461245, 10944.069754070913, 10946.0, 10953.726156846064, 44422.777886110685, 45041.54958450439, 45076.0, 45091.54908450939, 45103.09793804106, 3588.8385022673783, 4161.771102589357, 4321.286987647034, 4323.848665296699, 4469.150861336336, 34261.0, 34655.826720866484, 34837.651623483696, 34851.302973940634, 34999.30001399983, 4383.956160438387, 4396.868093957187, 4401.9119617607785, 4417.624501917308, 4537.659675524349, 236075.0, 237057.81471092708, 237304.0, 237523.0, 240394.0, 62348.0, 62593.68703156455, 62728.0, 62770.0, 62845.37154628486, 64441.67779161074, 64754.67622661951, 64918.35081649123, 65028.0, 65093.349066509676, 16416.67166656684, 16661.833381666027, 16702.08138552367, 16721.414750483676, 16723.749143762852, 11255.493502792395, 11517.078633709283, 11528.654140375802, 11604.593839215755, 12626.494940202472, 18321.541961450803, 18423.447296581126, 18445.7233141503, 18459.0, 18723.0, 6242.344553821874, 6281.0, 6342.143810585604, 6369.331220221903, 6549.181352330958, 991.3506653142204, 1026.650938680852, 1080.4381721504774, 1113.8663360396804, 1119.5297974850582, 32409.51385729216, 32505.67494325074, 32726.672733272837, 32862.17844553905, 33415.83292083524, 1054.593981317183, 1062.2033474893828, 1073.7798751255973, 1087.1900434176432, 1131.9886801131884, 16849.831501685072, 17210.655786884192, 17325.0, 17355.21901514435, 17368.91315543414, 482890.0, 483301.0, 485853.0, 486667.0, 488589.0, 60602.696986514784, 60677.69661151666, 60728.69635651793, 60764.0, 60811.08783368253, 1210.7941649919535, 1315.848677402111, 1357.5995081450997, 1362.6252780485247, 1385.896057795649, 32811.34377312488, 33866.0, 34379.312413752086, 34593.13517162091, 34751.47872781961, 52817.7359113202, 53746.46253537571, 53810.46189538211, 53814.0, 53965.460345396044, 310104.0, 310176.0, 310331.0, 311408.0, 311977.0, 28981.10188981085, 29574.556381654722, 29632.0, 30814.383712326075, 31112.222194444414, 49433.752831237056, 49721.75139124281, 49945.25082123699, 50620.49379506158, 50748.74625626848, 41259.96850078772, 41927.58072419359, 41989.0, 42323.57676423196, 43460.34809477797, 41383.586164139175, 41872.581274186865, 42204.155916880874, 43285.35071973986, 43402.782986084865, 42177.57822421737, 42481.57518424776, 42518.5748142527, 42603.7869810649, 43787.0, 36113.81943090268, 38120.809395953955, 38658.42012369761, 38864.80567597144, 38890.85498671561, 29043.85478072596, 29225.85387073051, 29279.0, 29471.557926631547, 29480.705192947797], "value": 20649.14847485859, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.4692454026635, 388.65992256775337, 389.495603193864, 400.51537639456257, 417.1323646814626, 14380.527673790815, 17831.37590184342, 20044.098015589298, 20183.0, 20235.595288094228, 19597.314094006713, 19607.705884411746, 19632.116554755034, 19859.404217873485, 19958.50103747407, 5544.750486228129, 5597.63615365002, 5702.230198923155, 5733.827985160452, 5779.306483222019, 5707.400722924095, 5724.685142317192, 5763.308402991626, 5769.855753606152, 5770.394108618577, 5514.80698175564, 5673.177389278552, 5702.543796496291, 5721.628094173865, 5722.685252311121, 5656.038473459501, 5686.3744988051285, 5699.686517241571, 5699.715014249313, 5702.258706368167, 4939.925901111486, 5102.438731739506, 5127.051495473349, 5128.615353848439, 5134.229865520165, 22453.32640020802, 22984.885075574683, 23121.653175202384, 23571.17500887461, 23718.881405592863, 2458.2379462366725, 2817.29567608098, 2967.6735559088484, 3005.834679092649, 3073.0319949215927, 10346.741331466774, 10672.0, 10698.786024279549, 13346.733065338736, 13878.861211387857, 1932.183936724957, 2004.3986803958753, 2013.0035632362074, 2038.6840039793856, 2039.5309078911932, 82595.58702206511, 86649.56675216583, 91362.0, 92670.0, 95356.0, 10871.945640271748, 10951.34291942486, 11339.433028348549, 11522.423878806027, 11599.826002609967, 15899.761503577456, 15940.760888586681, 15959.202039897958, 15964.042157470356, 15973.680526389408, 7828.256315650005, 8117.512949222947, 8150.2664760171865, 8189.420332595213, 8365.456245344034, 7323.597202153826, 7569.962150189213, 7818.4136189785995, 7916.485428447132, 8161.55111468863, 4694.50707675696, 4744.928826067612, 4796.841421360332, 4806.158922188603, 5018.949810501848, 1941.951451213731, 1965.4496740912568, 1995.5210749420035, 1995.7405537280351, 2028.7869773673847, 944.6788092048737, 950.8098380323963, 968.9176420004237, 1000.68478429296, 1026.389298367485, 19921.800781992286, 20265.797342026686, 20311.0, 20312.492187695425, 20544.38366848997, 975.7170420578095, 976.4678250353575, 978.1001478639669, 991.6826615482976, 1016.6187679620142, 10371.325863818827, 10815.405152716676, 11278.436078196057, 11281.266717663326, 11294.152938529478, 475097.0, 478538.0, 484575.0, 489325.0, 490239.0, 94807.0, 97546.51226743821, 98544.0, 99531.50234248783, 99746.5012674932, 996.1333639733479, 1019.3730855523947, 1039.6309310194922, 1063.3779239145188, 1068.166829872685, 17853.46439606814, 18220.180091895632, 18743.0, 18761.155747991645, 18818.778798388965, 44942.0, 45361.54638453573, 46101.76949115233, 46356.30465543087, 46694.29958550556, 60424.697876510334, 61595.384046158964, 65838.0, 66272.66863665651, 66942.0, 7633.88549171774, 7875.094364147967, 7977.920220797718, 8340.374471914753, 8380.0, 8322.50064996102, 9564.947855735725, 11880.702982425508, 13499.39252733649, 14587.124772513684, 12114.818277726017, 12123.878761212627, 12369.319687416919, 12505.874941250835, 12648.936755316165, 11961.880381196077, 12086.879131208927, 12147.75704485923, 12254.4485498151, 12415.62753117408, 12266.38668066611, 12339.62981110568, 12428.875711242772, 12520.121981702818, 12525.56160534377, 7877.330426913776, 8063.87904181426, 8599.656013759379, 8882.0, 8930.687425940061, 11270.0, 11497.195196336175, 11514.654560363202, 11640.88359116398, 11869.881301186877], "value": 9419.720148372024, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.6190635668836, 1423.7935499352593, 1498.3931507739367, 1586.3575252022927, 1806.5303021214488, 8240.093589705135, 8645.049044605097, 8661.78345541361, 8870.645174193025, 8874.55627218638, 8672.47965122093, 8760.693375731851, 8799.032106468283, 8804.075572064938, 8807.86788198176, 2321.106374045996, 2364.9645255321184, 2365.95268094638, 2366.526694661066, 2368.692070030894, 2304.539092181562, 2351.353377821096, 2371.620540713486, 2373.0, 2374.95250094998, 2327.3367090379247, 2352.270796053229, 2354.152505098163, 2358.705161854768, 2360.0323867214447, 2275.6927814744963, 2291.4042348989215, 2299.37916762474, 2308.4575124845646, 2311.2603966730653, 2256.9548609027893, 2259.401258666455, 2266.0, 2269.931902042941, 2271.806896413807, 8694.434861734031, 8726.214640682369, 8774.122587741238, 8783.209511143963, 8786.824263514758, 5111.182210846247, 5259.500347467023, 5261.289725886994, 5323.707196104213, 5330.626856120073, 3395.5246265522846, 3545.2377738786286, 3584.354816133095, 3598.9100272493133, 3604.4052731299303, 1074.1782536359692, 1125.3697929159614, 1132.1735133352615, 1146.8107762219224, 1147.8565179352581, 12408.317542535156, 12449.75100497994, 12520.81218781719, 12522.874771252262, 12523.185992910523, 4409.955900440955, 4509.864704058883, 4663.790129444184, 4667.883302917454, 4669.929951050737, 9787.804243915225, 9790.21678265737, 9802.607895684236, 9804.411735295906, 9809.607615695293, 5248.029114613809, 5257.211418287287, 5266.7629956652045, 5267.683938963674, 5269.1832765921345, 4347.804348804311, 4643.930341044887, 4830.961343311205, 4852.320675105488, 4941.802327906916, 3699.4635777812605, 3803.391457366808, 3839.2705385977133, 3874.728768986144, 3971.3447281198855, 1167.8598568171874, 1173.3546549397797, 1176.817593273044, 1182.5447202826974, 1193.3078814287692, 957.7749228931195, 991.8859331176862, 993.5380048277585, 1009.1321463541349, 1010.9545070471846, 5801.854953626109, 5869.413058694182, 5904.114382842608, 6187.659678717714, 6304.18045654062, 455.16477264220526, 456.1993701054628, 463.6615270852297, 470.9529047095263, 477.6704074188868, 4046.4132700758223, 4195.664346852244, 4219.683523735671, 4271.594198551133, 4288.378185163197, 10838.620648277272, 11200.10399168081, 11273.887261127447, 11277.605283815026, 11290.266132701512, 10350.741231469274, 10644.924239773325, 11189.0, 11228.438578071064, 11229.775404491864, 8172.34621230312, 8361.623726932303, 8381.329493640616, 8391.706290279812, 8410.03284622264, 5986.371430999857, 6120.969395152996, 6208.751649933952, 6211.81364559064, 6228.875422491434, 10093.5457904393, 10146.086852183338, 10218.948905255425, 10344.017318354745, 10398.116160126474, 10255.7436064099, 10398.636047738595, 10446.68659940203, 10526.842097368393, 10606.734831629272, 7873.330766884879, 8094.31198348147, 8181.345492360716, 8201.13888041747, 8239.711610093858, 8299.543525106177, 8359.832803344021, 8362.372822038249, 8363.581820909052, 8369.916300836914, 7630.236976302547, 7887.9605601971625, 7920.287174154354, 7967.681292748225, 7999.800004999689, 7676.577788221478, 7719.150893401834, 7784.416168787477, 7873.763787086396, 8014.599270036591, 6840.8289792753585, 7217.855642887008, 7307.086614173229, 7312.0, 7317.414606831547, 5042.4705405931845, 5247.790088396574, 5252.4747525248695, 5316.229146773773, 5349.705766182898, 5066.6200034998255, 5134.435212126588, 5212.113940630026, 5214.009338225725, 5365.273024847524], "value": 4573.456111537957, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.7393910932145, 332.1629493675937, 340.4399762390867, 341.96238413774495, 342.71554609673973, 5259.710715910624, 5279.4192638809745, 5283.867903302419, 5292.497212764782, 5311.893762124765, 4939.851804445872, 4966.776495057722, 5144.868129011611, 5166.844994650157, 5268.367795864492, 4130.401091841696, 4132.400801883725, 4141.5444301127, 4156.480439945007, 4160.999999999993, 4150.605692459211, 4153.107081977374, 4155.480564929384, 4163.50037995441, 4196.139791342783, 3901.2587608354465, 3925.3915643075225, 4091.611296926802, 4095.5699651536606, 4106.548279689232, 4079.673626109904, 4082.836686532535, 4087.591240875918, 4090.202410529963, 4092.6521245694003, 3617.113807117273, 3617.4212126059706, 3621.4748861414946, 3621.5654121505327, 3626.0, 4997.275395067695, 5038.825202079953, 5068.645194836363, 5105.668131571473, 5106.770195341219, 4695.201815691306, 4698.603712106816, 4703.929441058387, 4706.105839890444, 4709.0, 5386.91919621206, 5416.539594134503, 5430.484104010152, 5437.700926449043, 5440.836774896759, 3672.8714494992546, 3923.4899463069905, 3984.2629113613784, 3988.182422603388, 3988.4615576897036, 5624.465675760796, 5653.547716182696, 5663.470862867003, 5667.6316039457715, 5669.461401166883, 4038.8990275243355, 4044.3124668805785, 4050.0, 4051.6150965658217, 4059.4316795648633, 3910.354791459434, 4127.0, 4138.317177665712, 4142.958570414257, 4146.963259185204, 4651.837185698484, 4652.604528615106, 4655.2784318430695, 4655.766221951186, 4678.391809064799, 5418.539424148991, 5455.454454554513, 5485.286912701324, 5504.284443022381, 5518.310211223597, 1370.465518447806, 1391.872583207605, 1408.3310427546833, 1413.3675604676614, 1430.3777856632398, 878.7846977490556, 879.8210398066622, 880.0011986395488, 882.3955590420583, 892.8705337725993, 4646.233371493666, 4659.883502912454, 4663.836765713251, 4670.626349892001, 4693.319468677023, 4926.9014619707405, 4931.238506436407, 4947.60419166473, 4951.638299467603, 4951.90096198074, 4676.766161691902, 4687.812487500461, 4889.633277504201, 5268.4994925482015, 5285.709285989307, 2944.131481213047, 2945.572891930658, 2946.499095153829, 2953.6012638293987, 2969.2576855786056, 5674.602777805514, 5829.475347218686, 5830.125481177773, 5833.854153646108, 5839.532837373, 5609.27079479665, 5618.943810561924, 5636.802711905064, 5638.182463542846, 5645.379008309083, 5765.818007308491, 5803.796867109715, 5807.593468457168, 5814.5463634091475, 5827.388124246893, 5353.2773075634295, 5533.557315414838, 5583.55331573465, 5587.69267690281, 5597.608167428241, 5520.227168196376, 5528.944710552843, 5538.833834984957, 5538.97230513861, 5539.390667026704, 5770.13447982806, 5787.623804452697, 5801.245838041028, 5819.272590926134, 5827.125931110368, 3791.009467625459, 3959.1883663849417, 3988.461557689791, 3990.0, 4016.758994460342, 5361.329833770778, 5416.2688037114485, 5571.247881535941, 5591.580631452739, 5606.579506536945, 5407.7025763581805, 5431.0, 5439.48324909133, 5441.2044025470705, 5447.428020057995, 5276.968367178587, 5410.891782164414, 5419.945800541944, 5498.477644623755, 5499.780008799603, 5373.382061063028, 5385.40760516335, 5412.8646783829145, 5412.891742165056, 5438.401775804738, 5463.879904619464, 5486.369067557282, 5488.4237155098135, 5493.697846618475, 5509.063459211863, 5193.246979188072, 5292.9470705294, 5325.254464375068, 5330.946690533045, 5373.596980226421], "value": 4137.77467632308, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.741651713642, 729.8832186850103, 767.1982777996992, 785.6396996668495, 875.6453636277308, 15853.0, 17060.914695426538, 17106.14469276536, 17330.653386932252, 17898.64202715945, 16863.0, 16952.0, 16967.15164241789, 17000.744988825147, 17001.659966800657, 683.9794806155824, 805.2591615713558, 818.2962652119188, 839.7606682095604, 845.8308338332328, 809.4576633655436, 812.6180695073335, 822.7943014246439, 823.8846561481397, 825.3892119831341, 870.9635533714859, 871.6164887449502, 871.7559083456642, 872.0189786490198, 872.9301655867547, 701.7473709464589, 841.8358420108082, 869.4826578186, 869.5043825019741, 869.8695195720629, 627.466653344658, 627.9183706118221, 628.5223230344925, 629.697745082363, 629.9527535434861, 16766.0, 16781.160941952854, 16800.83199168005, 16802.411915582896, 16802.83197168025, 14697.55907322782, 14740.926295368454, 14800.11199328033, 14870.405183792634, 14881.33034013472, 13543.59369218925, 13571.660708482268, 13710.794338084937, 13739.038267321292, 14491.710165796625, 4554.954450455486, 4556.7265964041935, 4582.358469814229, 4765.046990601859, 4813.614910807127, 86290.0, 87394.99999999936, 87989.0, 89484.0, 92620.53689731508, 11595.344708944629, 11728.413579321, 11815.763684726257, 11910.702232444259, 12016.09879259059, 21416.78583214148, 21449.785502145092, 21632.134714611555, 21683.24108656221, 21771.564568708538, 8355.749327520183, 8388.454750441202, 8480.236778689947, 8508.532030738244, 8571.914280857112, 4877.5366340197625, 5014.34813474246, 5030.622703297267, 5037.278241618633, 5044.596432285409, 3302.091924720721, 3392.2197894484407, 3392.5759280089987, 3392.8473218705217, 3394.1853955051106, 618.5700937848152, 619.801663467695, 621.4841681404423, 637.4900079936125, 646.8156575375996, 580.8489792653941, 633.616661919532, 643.652129862774, 668.7258224128182, 695.9408450281682, 9728.95135524332, 9887.851682224773, 10103.84844227337, 10377.117944974761, 10443.84334234987, 618.6937465954381, 666.1074160624786, 679.5918468878577, 688.8897776355859, 704.1831475488409, 4143.647789937829, 4211.894702632459, 4480.70875393105, 4564.726116433025, 4574.908501829945, 97779.0, 108276.4586177064, 122713.0, 123955.0, 126509.36745316215, 95927.52036239774, 101727.4913625442, 102512.48743756233, 103834.0, 104488.64090872712, 54049.45950540444, 83783.16216837875, 83869.16130838613, 87744.0, 90198.09801902028, 10089.243306751876, 10256.897431025594, 10300.577977425768, 10454.477276136315, 10535.4732263385, 45744.0, 53890.73054634702, 53895.46104538904, 55185.72407137939, 55278.0, 70197.19211231494, 77005.0, 77355.0, 78630.6068469654, 79826.60086699529, 13171.538996135472, 13237.735245295233, 13370.130941488773, 13634.250116243702, 13637.590872273848, 15178.924105379403, 15366.385344586091, 15367.846321536641, 15398.846011539741, 15944.362225511313, 14255.215963122128, 14450.277486125862, 14507.564773056825, 14551.85448145505, 14816.777748333569, 13892.861071389158, 14410.279486025867, 14440.783388248974, 14441.0, 14525.564233073024, 14321.713565728418, 14888.925555372154, 15133.54599362021, 15283.0, 15308.0, 11584.652460426201, 11624.709382265512, 11884.584039558575, 11951.701207469536, 12124.696882578006, 10490.947545262225, 10576.629817956335, 12200.450979705756, 12220.144589878622, 12615.621531354074], "value": 7826.715664265592, "unit": "score"}], "name": "dromaeo_css", "value": 7286.147639319538, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 113.0, 110.0, 112.0, 111.0, 111.0, 113.0, 110.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 119.0, 120.0, 172.0, 121.0, 120.0, 151.0, 117.0, 163.0, 121.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 161.0, 160.0, 162.0, 160.0, 162.0, 160.0, 163.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 116.0, 75.0, 76.0, 75.0, 74.0, 107.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 114.0, 84.0, 84.0, 84.0, 120.0, 84.0, 84.0, 122.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 118.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 148.0, 150.0, 149.0, 148.0, 148.0, 148.0, 149.0, 144.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 49.0, 49.0, 48.0, 50.0, 49.0, 48.0, 49.0], "value": 48.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 59.0, 61.0, 59.0, 60.0, 60.0, 59.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 76.0, 77.0, 76.0, 75.0, 76.0, 75.0, 76.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 130.0, 122.0, 128.0, 120.0, 121.0, 124.0, 122.0, 162.0, 121.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 163.0, 162.0, 164.0, 161.0, 163.0, 160.0, 158.0, 158.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 63.0, 63.0, 63.0, 62.0, 63.0, 62.0, 60.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1479.9, "alertThreshold": 2.0}]} 04:42:02 INFO - Return code: 0 04:42:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:42:02 INFO - # TBPL SUCCESS # 04:42:02 INFO - Running post-action listener: _resource_record_post_action 04:42:02 INFO - [mozharness: 2016-09-20 11:42:02.657470Z] Finished run-tests step (success) 04:42:02 INFO - Running post-run listener: _resource_record_post_run 04:42:02 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 553652224; Read time: 8; Write time: 1031108 04:42:02 INFO - TinderboxPrint: CPU usage
13.1% 04:42:02 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 04:42:02 INFO - TinderboxPrint: I/O write bytes / time
553,652,224 / 1,031,108 04:42:02 INFO - TinderboxPrint: CPU idle
8,287.7 (86.9%) 04:42:02 INFO - TinderboxPrint: CPU user
1,196.1 (12.5%) 04:42:02 INFO - TinderboxPrint: Swap in / out
0 / 0 04:42:02 INFO - install - Wall time: 17s; CPU: 17.0%; Read bytes: 0; Write bytes: 263000064; Read time: 0; Write time: 667592 04:42:02 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 290304000; Read time: 0; Write time: 363512 04:42:03 INFO - Running post-run listener: _upload_blobber_files 04:42:03 INFO - Blob upload gear active. 04:42:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:42:03 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 04:42:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:42:03 INFO - (blobuploader) - INFO - Open directory for files ... 04:42:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:42:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:42:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:42:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:42:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:42:07 INFO - (blobuploader) - INFO - Done attempting. 04:42:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:42:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:42:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:42:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:42:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:42:08 INFO - (blobuploader) - INFO - Done attempting. 04:42:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:42:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:42:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:42:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:42:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:42:09 INFO - (blobuploader) - INFO - Done attempting. 04:42:09 INFO - (blobuploader) - INFO - Iteration through files over. 04:42:09 INFO - Return code: 0 04:42:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:42:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:42:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc0fc8e82c468926e390a1d0007071961bb35426bc87ec688c33ec5ec9e5e1e33d7e8a6348737d036de3b6020dd05f2feca377156f2f838e2e9cf4f4303391c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ccc9dd7daebe418e91862cbb12a3f00d550312c5f7eb349dc14d66314e4c53a5fa77c233b6be21d0c4c80810941d8150fb7a3aebf468a7f0a51e0799a335ce51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/960ad96283e4e71fb42cb08f44c44da996189b4bae713d8b77a72c8fe29a6fdc9b134211b12c6451e3a1c7645eb9a36eadef08e86e31d96da03b4b7db859ce6b"} 04:42:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:42:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:42:09 INFO - Contents: 04:42:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc0fc8e82c468926e390a1d0007071961bb35426bc87ec688c33ec5ec9e5e1e33d7e8a6348737d036de3b6020dd05f2feca377156f2f838e2e9cf4f4303391c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ccc9dd7daebe418e91862cbb12a3f00d550312c5f7eb349dc14d66314e4c53a5fa77c233b6be21d0c4c80810941d8150fb7a3aebf468a7f0a51e0799a335ce51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/960ad96283e4e71fb42cb08f44c44da996189b4bae713d8b77a72c8fe29a6fdc9b134211b12c6451e3a1c7645eb9a36eadef08e86e31d96da03b4b7db859ce6b"} 04:42:09 INFO - Running post-run listener: copy_logs_to_upload_dir 04:42:09 INFO - Copying logs to upload dir... 04:42:09 INFO - mkdir: /builds/slave/test/build/upload/logs 04:42:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.544557 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-20 04:42:08.827881) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 04:42:08.834168) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1474370492.718580-1159804280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc0fc8e82c468926e390a1d0007071961bb35426bc87ec688c33ec5ec9e5e1e33d7e8a6348737d036de3b6020dd05f2feca377156f2f838e2e9cf4f4303391c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ccc9dd7daebe418e91862cbb12a3f00d550312c5f7eb349dc14d66314e4c53a5fa77c233b6be21d0c4c80810941d8150fb7a3aebf468a7f0a51e0799a335ce51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/960ad96283e4e71fb42cb08f44c44da996189b4bae713d8b77a72c8fe29a6fdc9b134211b12c6451e3a1c7645eb9a36eadef08e86e31d96da03b4b7db859ce6b"} build_url:https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/BFHohf2iREuMmsBRTLhwJA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc0fc8e82c468926e390a1d0007071961bb35426bc87ec688c33ec5ec9e5e1e33d7e8a6348737d036de3b6020dd05f2feca377156f2f838e2e9cf4f4303391c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ccc9dd7daebe418e91862cbb12a3f00d550312c5f7eb349dc14d66314e4c53a5fa77c233b6be21d0c4c80810941d8150fb7a3aebf468a7f0a51e0799a335ce51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/960ad96283e4e71fb42cb08f44c44da996189b4bae713d8b77a72c8fe29a6fdc9b134211b12c6451e3a1c7645eb9a36eadef08e86e31d96da03b4b7db859ce6b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 04:42:08.862810) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:42:08.863193) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1474370492.718580-1159804280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004482 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 04:42:08.945582) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 04:42:08.949010) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 04:42:08.949388) ========= ========= Total master_lag: 0.59 =========