builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1471389651.47 results: success (0) buildid: 20160816133002 builduid: 1089b5e52bda4c9bb90b128f75312d7e revision: 1eaa34d5dba1e3698785c7ffab4e5bd0f9529a0f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:51.465417) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:51.466009) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:51.466313) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:51.590589) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:51.590851) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 16:20:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 12.7M=0.001s 2016-08-16 16:20:52 (12.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134133 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:51.743668) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:51.743955) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018128 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:51.807636) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:51.807970) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1eaa34d5dba1e3698785c7ffab4e5bd0f9529a0f --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 1eaa34d5dba1e3698785c7ffab4e5bd0f9529a0f --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 16:20:52,266 truncating revision to first 12 chars 2016-08-16 16:20:52,267 Setting DEBUG logging. 2016-08-16 16:20:52,267 attempt 1/10 2016-08-16 16:20:52,267 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1eaa34d5dba1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 16:20:52,572 unpacking tar archive at: mozilla-inbound-1eaa34d5dba1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.654231 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:52.479301) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:52.479583) ========= script_repo_revision: 1eaa34d5dba1e3698785c7ffab4e5bd0f9529a0f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:52.479973) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:52.480262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 16:20:52.491484) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-16 16:20:52.491798) ========= /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=337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:20:53 INFO - MultiFileLogger online at 20160816 16:20:53 in /builds/slave/test 16:20:53 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 16:20:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:20:53 INFO - {'append_to_log': False, 16:20:53 INFO - 'base_work_dir': '/builds/slave/test', 16:20:53 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:20:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:20:53 INFO - 'branch': 'Mozilla-Inbound', 16:20:53 INFO - 'buildbot_json_path': 'buildprops.json', 16:20:53 INFO - 'config_files': ('talos/linux_config.py',), 16:20:53 INFO - 'default_actions': ('clobber', 16:20:53 INFO - 'read-buildbot-config', 16:20:53 INFO - 'download-and-extract', 16:20:53 INFO - 'populate-webroot', 16:20:53 INFO - 'create-virtualenv', 16:20:53 INFO - 'install', 16:20:53 INFO - 'run-tests'), 16:20:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:20:53 INFO - 'download_minidump_stackwalk': True, 16:20:53 INFO - 'download_symbols': 'ondemand', 16:20:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:20:53 INFO - 'tooltool.py': '/tools/tooltool.py', 16:20:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:20:53 INFO - '/tools/misc-python/virtualenv.py')}, 16:20:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:20:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:20:53 INFO - 'installer_path': 'installer.exe', 16:20:53 INFO - 'log_level': 'info', 16:20:53 INFO - 'log_name': 'talos', 16:20:53 INFO - 'log_to_console': True, 16:20:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:20:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:20:53 INFO - 'opt_config_files': (), 16:20:53 INFO - 'pip_index': False, 16:20:53 INFO - 'sps_profile': False, 16:20:53 INFO - 'sps_profile_interval': 0, 16:20:53 INFO - 'suite': 'dromaeojs', 16:20:53 INFO - 'system_bits': '32', 16:20:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:20:53 INFO - 'title': 'talos-linux64-ix-060', 16:20:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:20:53 INFO - 'use_talos_json': True, 16:20:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:20:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:20:53 INFO - 'work_dir': 'build'} 16:20:53 INFO - [mozharness: 2016-08-16 23:20:53.039991Z] Running clobber step. 16:20:53 INFO - Running pre-action listener: _resource_record_pre_action 16:20:53 INFO - Running main action method: clobber 16:20:53 INFO - rmtree: /builds/slave/test/build 16:20:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:20:54 INFO - Running post-action listener: _resource_record_post_action 16:20:54 INFO - [mozharness: 2016-08-16 23:20:54.018444Z] Finished clobber step (success) 16:20:54 INFO - [mozharness: 2016-08-16 23:20:54.018547Z] Running read-buildbot-config step. 16:20:54 INFO - Running pre-action listener: _resource_record_pre_action 16:20:54 INFO - Running main action method: read_buildbot_config 16:20:54 INFO - Using buildbot properties: 16:20:54 INFO - { 16:20:54 INFO - "project": "", 16:20:54 INFO - "product": "firefox", 16:20:54 INFO - "script_repo_revision": "production", 16:20:54 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:20:54 INFO - "repository": "", 16:20:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:20:54 INFO - "buildid": "20160816133002", 16:20:54 INFO - "pgo_build": "True", 16:20:54 INFO - "basedir": "/builds/slave/test", 16:20:54 INFO - "buildnumber": 1537, 16:20:54 INFO - "slavename": "talos-linux64-ix-060", 16:20:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:20:54 INFO - "platform": "ubuntu64_hw", 16:20:54 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:20:54 INFO - "branch": "mozilla-inbound", 16:20:54 INFO - "repo_path": "integration/mozilla-inbound", 16:20:54 INFO - "revision": "1eaa34d5dba1e3698785c7ffab4e5bd0f9529a0f", 16:20:54 INFO - "stage_platform": "linux64-pgo", 16:20:54 INFO - "builduid": "1089b5e52bda4c9bb90b128f75312d7e", 16:20:54 INFO - "slavebuilddir": "test" 16:20:54 INFO - } 16:20:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:20:54 INFO - Running post-action listener: _resource_record_post_action 16:20:54 INFO - [mozharness: 2016-08-16 23:20:54.020978Z] Finished read-buildbot-config step (success) 16:20:54 INFO - [mozharness: 2016-08-16 23:20:54.021071Z] Running download-and-extract step. 16:20:54 INFO - Running pre-action listener: _resource_record_pre_action 16:20:54 INFO - Running main action method: download_and_extract 16:20:54 INFO - mkdir: /builds/slave/test/build/tests 16:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:20:54 INFO - trying https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:20:54 INFO - Downloading https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:20:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:20:54 INFO - Downloaded 1361 bytes. 16:20:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:20:54 INFO - Using the following test package requirements: 16:20:54 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:20:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:20:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:20:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:20:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:20:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:20:54 INFO - u'jsshell-linux-x86_64.zip'], 16:20:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:20:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:20:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:20:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:20:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:20:54 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:20:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:20:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:20:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:20:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:20:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:20:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:20:54 INFO - trying https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:20:54 INFO - Downloading https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:20:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:20:55 INFO - Downloaded 21902827 bytes. 16:20:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 16:20:59 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 16:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:20:59 INFO - trying https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:20:59 INFO - Downloading https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:20:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:21:00 INFO - Downloaded 21902827 bytes. 16:21:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 16:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:05 INFO - trying https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:21:05 INFO - Downloading https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:21:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:21:05 INFO - Downloaded 14413719 bytes. 16:21:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 16:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:06 INFO - trying https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:21:06 INFO - Downloading https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:21:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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 16:21:07 INFO - Downloaded 59543314 bytes. 16:21:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:21:07 INFO - mkdir: /builds/slave/test/properties 16:21:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:21:07 INFO - Writing to file /builds/slave/test/properties/build_url 16:21:07 INFO - Contents: 16:21:07 INFO - build_url:https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:21:08 INFO - Running post-action listener: _resource_record_post_action 16:21:08 INFO - Running post-action listener: set_extra_try_arguments 16:21:08 INFO - [mozharness: 2016-08-16 23:21:08.305426Z] Finished download-and-extract step (success) 16:21:08 INFO - [mozharness: 2016-08-16 23:21:08.305650Z] Running populate-webroot step. 16:21:08 INFO - Running pre-action listener: _resource_record_pre_action 16:21:08 INFO - Running main action method: populate_webroot 16:21:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:21:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:21:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:21:08 INFO - '--tppagecycles', 16:21:08 INFO - '7'], 16:21:08 INFO - 'tests': ['tsvgm']}}, 16:21:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:21:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:21:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:21:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:21:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:08 INFO - 'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:21:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:21:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:08 INFO - 'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['damp', 'tps']}, 16:21:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:08 INFO - 'tests': ['damp', 'tps']}, 16:21:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['dromaeo_dom']}, 16:21:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:21:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['basic_compositor_video']}, 16:21:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:21:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['a11yr', 16:21:08 INFO - 'ts_paint', 16:21:08 INFO - 'tpaint', 16:21:08 INFO - 'sessionrestore', 16:21:08 INFO - 'sessionrestore_no_auto_restore', 16:21:08 INFO - 'tabpaint']}, 16:21:08 INFO - 'other-e10s': {'tests': ['a11yr', 16:21:08 INFO - 'ts_paint', 16:21:08 INFO - 'tpaint', 16:21:08 INFO - 'sessionrestore', 16:21:08 INFO - 'sessionrestore_no_auto_restore', 16:21:08 INFO - 'tabpaint']}, 16:21:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:21:08 INFO - 'ts_paint', 16:21:08 INFO - 'tpaint', 16:21:08 INFO - 'sessionrestore', 16:21:08 INFO - 'sessionrestore_no_auto_restore', 16:21:08 INFO - 'tabpaint']}, 16:21:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:21:08 INFO - 'ts_paint', 16:21:08 INFO - 'tpaint', 16:21:08 INFO - 'sessionrestore', 16:21:08 INFO - 'sessionrestore_no_auto_restore', 16:21:08 INFO - 'tabpaint']}, 16:21:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['a11yr', 16:21:08 INFO - 'ts_paint', 16:21:08 INFO - 'tpaint', 16:21:08 INFO - 'sessionrestore', 16:21:08 INFO - 'sessionrestore_no_auto_restore', 16:21:08 INFO - 'tabpaint']}, 16:21:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['a11yr', 16:21:08 INFO - 'ts_paint', 16:21:08 INFO - 'tpaint', 16:21:08 INFO - 'sessionrestore', 16:21:08 INFO - 'sessionrestore_no_auto_restore', 16:21:08 INFO - 'tabpaint']}, 16:21:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['tsvgx', 16:21:08 INFO - 'tsvgr_opacity', 16:21:08 INFO - 'tart', 16:21:08 INFO - 'tscrollx', 16:21:08 INFO - 'cart']}, 16:21:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:21:08 INFO - 'tsvgr_opacity', 16:21:08 INFO - 'tart', 16:21:08 INFO - 'tscrollx', 16:21:08 INFO - 'cart']}, 16:21:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:08 INFO - 'talos_options': ['--disable-e10s'], 16:21:08 INFO - 'tests': ['tp5o']}, 16:21:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:08 INFO - 'tests': ['tp5o']}, 16:21:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:08 INFO - 'talos_options': ['--disable-e10s', 16:21:08 INFO - '--xperf_path', 16:21:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:21:08 INFO - 'tests': ['tp5n']}, 16:21:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:21:08 INFO - 'talos_options': ['--xperf_path', 16:21:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:21:08 INFO - 'tests': ['tp5n']}}, 16:21:08 INFO - 'talos.zip': {'path': '', 16:21:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:21:08 INFO - Running post-action listener: _resource_record_post_action 16:21:08 INFO - [mozharness: 2016-08-16 23:21:08.323614Z] Finished populate-webroot step (success) 16:21:08 INFO - [mozharness: 2016-08-16 23:21:08.323703Z] Running create-virtualenv step. 16:21:08 INFO - Running pre-action listener: _resource_record_pre_action 16:21:08 INFO - Running main action method: create_virtualenv 16:21:08 INFO - Creating virtualenv /builds/slave/test/build/venv 16:21:08 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 16:21:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:21:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:21:08 INFO - Using real prefix '/usr' 16:21:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:21:08 INFO - Installing distribute.............................................................................................................................................................................................done. 16:21:10 INFO - Installing pip.................done. 16:21:10 INFO - Return code: 0 16:21:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:10 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 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:10 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 16:21:10 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 16:21:10 INFO - Using env: {'DISPLAY': ':0', 16:21:10 INFO - 'HOME': '/home/cltbld', 16:21:10 INFO - 'LANG': 'en_US.UTF-8', 16:21:10 INFO - 'LANGUAGE': 'en_US:en', 16:21:10 INFO - 'LOGNAME': 'cltbld', 16:21:10 INFO - 'MAIL': '/var/mail/cltbld', 16:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:10 INFO - 'MOZ_NO_REMOTE': '1', 16:21:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:10 INFO - 'NO_EM_RESTART': '1', 16:21:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:10 INFO - 'PWD': '/builds/slave/test', 16:21:10 INFO - 'SHELL': '/bin/bash', 16:21:10 INFO - 'SHLVL': '1', 16:21:10 INFO - 'TERM': 'linux', 16:21:10 INFO - 'TMOUT': '86400', 16:21:10 INFO - 'USER': 'cltbld', 16:21:10 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:10 INFO - '_': '/tools/buildbot/bin/python'} 16:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:11 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 16:21:11 INFO - Cleaning up... 16:21:11 INFO - Return code: 0 16:21:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:21:11 INFO - Using env: {'DISPLAY': ':0', 16:21:11 INFO - 'HOME': '/home/cltbld', 16:21:11 INFO - 'LANG': 'en_US.UTF-8', 16:21:11 INFO - 'LANGUAGE': 'en_US:en', 16:21:11 INFO - 'LOGNAME': 'cltbld', 16:21:11 INFO - 'MAIL': '/var/mail/cltbld', 16:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:11 INFO - 'MOZ_NO_REMOTE': '1', 16:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:11 INFO - 'NO_EM_RESTART': '1', 16:21:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:11 INFO - 'PWD': '/builds/slave/test', 16:21:11 INFO - 'SHELL': '/bin/bash', 16:21:11 INFO - 'SHLVL': '1', 16:21:11 INFO - 'TERM': 'linux', 16:21:11 INFO - 'TMOUT': '86400', 16:21:11 INFO - 'USER': 'cltbld', 16:21:11 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:11 INFO - '_': '/tools/buildbot/bin/python'} 16:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:11 INFO - Downloading/unpacking psutil>=3.1.1 16:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:21:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:21:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:21:13 INFO - Installing collected packages: psutil 16:21:13 INFO - Running setup.py install for psutil 16:21:13 INFO - building 'psutil._psutil_linux' extension 16:21:13 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 16:21:13 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 16:21:13 INFO - building 'psutil._psutil_posix' extension 16:21:13 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 16:21:13 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 16:21:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:21:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:21:13 INFO - Successfully installed psutil 16:21:13 INFO - Cleaning up... 16:21:13 INFO - Return code: 0 16:21:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:13 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 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:13 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 16:21:13 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 16:21:13 INFO - Using env: {'DISPLAY': ':0', 16:21:13 INFO - 'HOME': '/home/cltbld', 16:21:13 INFO - 'LANG': 'en_US.UTF-8', 16:21:13 INFO - 'LANGUAGE': 'en_US:en', 16:21:13 INFO - 'LOGNAME': 'cltbld', 16:21:13 INFO - 'MAIL': '/var/mail/cltbld', 16:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:13 INFO - 'MOZ_NO_REMOTE': '1', 16:21:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:13 INFO - 'NO_EM_RESTART': '1', 16:21:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:13 INFO - 'PWD': '/builds/slave/test', 16:21:13 INFO - 'SHELL': '/bin/bash', 16:21:13 INFO - 'SHLVL': '1', 16:21:13 INFO - 'TERM': 'linux', 16:21:13 INFO - 'TMOUT': '86400', 16:21:13 INFO - 'USER': 'cltbld', 16:21:13 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:13 INFO - '_': '/tools/buildbot/bin/python'} 16:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:21:16 INFO - Installing collected packages: mozsystemmonitor 16:21:16 INFO - Running setup.py install for mozsystemmonitor 16:21:16 INFO - Successfully installed mozsystemmonitor 16:21:16 INFO - Cleaning up... 16:21:16 INFO - Return code: 0 16:21:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:21:16 INFO - Using env: {'DISPLAY': ':0', 16:21:16 INFO - 'HOME': '/home/cltbld', 16:21:16 INFO - 'LANG': 'en_US.UTF-8', 16:21:16 INFO - 'LANGUAGE': 'en_US:en', 16:21:16 INFO - 'LOGNAME': 'cltbld', 16:21:16 INFO - 'MAIL': '/var/mail/cltbld', 16:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:16 INFO - 'MOZ_NO_REMOTE': '1', 16:21:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:16 INFO - 'NO_EM_RESTART': '1', 16:21:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:16 INFO - 'PWD': '/builds/slave/test', 16:21:16 INFO - 'SHELL': '/bin/bash', 16:21:16 INFO - 'SHLVL': '1', 16:21:16 INFO - 'TERM': 'linux', 16:21:16 INFO - 'TMOUT': '86400', 16:21:16 INFO - 'USER': 'cltbld', 16:21:16 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:16 INFO - '_': '/tools/buildbot/bin/python'} 16:21:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:16 INFO - Downloading/unpacking jsonschema==2.5.1 16:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:21:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:21:18 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 16:21:18 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 16:21:18 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 16:21:18 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 16:21:18 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 16:21:18 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 16:21:18 INFO - Downloading functools32-3.2.3-2.tar.gz 16:21:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:21:18 INFO - warning: no files found matching '*.txt' 16:21:18 INFO - no previously-included directories found matching 'build' 16:21:18 INFO - no previously-included directories found matching 'dist' 16:21:18 INFO - no previously-included directories found matching '.git*' 16:21:18 INFO - Installing collected packages: jsonschema, functools32 16:21:18 INFO - Running setup.py install for functools32 16:21:18 INFO - warning: no files found matching '*.txt' 16:21:18 INFO - no previously-included directories found matching 'build' 16:21:18 INFO - no previously-included directories found matching 'dist' 16:21:18 INFO - no previously-included directories found matching '.git*' 16:21:18 INFO - Successfully installed jsonschema functools32 16:21:18 INFO - Cleaning up... 16:21:18 INFO - Return code: 0 16:21:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:18 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 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:18 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 16:21:18 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 16:21:18 INFO - Using env: {'DISPLAY': ':0', 16:21:18 INFO - 'HOME': '/home/cltbld', 16:21:18 INFO - 'LANG': 'en_US.UTF-8', 16:21:18 INFO - 'LANGUAGE': 'en_US:en', 16:21:18 INFO - 'LOGNAME': 'cltbld', 16:21:18 INFO - 'MAIL': '/var/mail/cltbld', 16:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:18 INFO - 'MOZ_NO_REMOTE': '1', 16:21:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:18 INFO - 'NO_EM_RESTART': '1', 16:21:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:18 INFO - 'PWD': '/builds/slave/test', 16:21:18 INFO - 'SHELL': '/bin/bash', 16:21:18 INFO - 'SHLVL': '1', 16:21:18 INFO - 'TERM': 'linux', 16:21:18 INFO - 'TMOUT': '86400', 16:21:18 INFO - 'USER': 'cltbld', 16:21:18 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:18 INFO - '_': '/tools/buildbot/bin/python'} 16:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:21:18 INFO - Cleaning up... 16:21:18 INFO - Return code: 0 16:21:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:18 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 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:18 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 16:21:18 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 16:21:18 INFO - Using env: {'DISPLAY': ':0', 16:21:18 INFO - 'HOME': '/home/cltbld', 16:21:18 INFO - 'LANG': 'en_US.UTF-8', 16:21:18 INFO - 'LANGUAGE': 'en_US:en', 16:21:18 INFO - 'LOGNAME': 'cltbld', 16:21:18 INFO - 'MAIL': '/var/mail/cltbld', 16:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:18 INFO - 'MOZ_NO_REMOTE': '1', 16:21:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:18 INFO - 'NO_EM_RESTART': '1', 16:21:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:18 INFO - 'PWD': '/builds/slave/test', 16:21:18 INFO - 'SHELL': '/bin/bash', 16:21:18 INFO - 'SHLVL': '1', 16:21:18 INFO - 'TERM': 'linux', 16:21:18 INFO - 'TMOUT': '86400', 16:21:18 INFO - 'USER': 'cltbld', 16:21:18 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:18 INFO - '_': '/tools/buildbot/bin/python'} 16:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:19 INFO - Downloading/unpacking blobuploader==1.2.4 16:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:21 INFO - Downloading blobuploader-1.2.4.tar.gz 16:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:21:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:21:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:21 INFO - Downloading docopt-0.6.1.tar.gz 16:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:21:21 INFO - Installing collected packages: blobuploader, requests, docopt 16:21:21 INFO - Running setup.py install for blobuploader 16:21:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:21:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:21:21 INFO - Running setup.py install for requests 16:21:22 INFO - Running setup.py install for docopt 16:21:22 INFO - Successfully installed blobuploader requests docopt 16:21:22 INFO - Cleaning up... 16:21:22 INFO - Return code: 0 16:21:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:22 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 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:22 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 16:21:22 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 16:21:22 INFO - Using env: {'DISPLAY': ':0', 16:21:22 INFO - 'HOME': '/home/cltbld', 16:21:22 INFO - 'LANG': 'en_US.UTF-8', 16:21:22 INFO - 'LANGUAGE': 'en_US:en', 16:21:22 INFO - 'LOGNAME': 'cltbld', 16:21:22 INFO - 'MAIL': '/var/mail/cltbld', 16:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:22 INFO - 'MOZ_NO_REMOTE': '1', 16:21:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:22 INFO - 'NO_EM_RESTART': '1', 16:21:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:22 INFO - 'PWD': '/builds/slave/test', 16:21:22 INFO - 'SHELL': '/bin/bash', 16:21:22 INFO - 'SHLVL': '1', 16:21:22 INFO - 'TERM': 'linux', 16:21:22 INFO - 'TMOUT': '86400', 16:21:22 INFO - 'USER': 'cltbld', 16:21:22 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:22 INFO - '_': '/tools/buildbot/bin/python'} 16:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:22 INFO - Running setup.py (path:/tmp/pip-M4e2Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:22 INFO - Running setup.py (path:/tmp/pip-lFCkbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:22 INFO - Running setup.py (path:/tmp/pip-XV6INK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:22 INFO - Running setup.py (path:/tmp/pip-w1OoQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:22 INFO - Running setup.py (path:/tmp/pip-d_GpYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:23 INFO - Running setup.py (path:/tmp/pip-oHeq0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:23 INFO - Running setup.py (path:/tmp/pip-rdUER6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:23 INFO - Running setup.py (path:/tmp/pip-GRA07p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:23 INFO - Running setup.py (path:/tmp/pip-c9Ucm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:23 INFO - Running setup.py (path:/tmp/pip-8uJg3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:23 INFO - Running setup.py (path:/tmp/pip-rlD9QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:23 INFO - Running setup.py (path:/tmp/pip-rp7PFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:23 INFO - Running setup.py (path:/tmp/pip-JzMzLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:23 INFO - Running setup.py (path:/tmp/pip-yRWvuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:23 INFO - Running setup.py (path:/tmp/pip-OVB0q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:23 INFO - Running setup.py (path:/tmp/pip-MCigRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:23 INFO - Running setup.py (path:/tmp/pip-tYmmpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:21:23 INFO - Running setup.py install for manifestparser 16:21:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:21:23 INFO - Running setup.py install for mozcrash 16:21:24 INFO - Running setup.py install for mozdebug 16:21:24 INFO - Running setup.py install for mozdevice 16:21:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:21:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:21:24 INFO - Running setup.py install for mozfile 16:21:24 INFO - Running setup.py install for mozhttpd 16:21:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:21:24 INFO - Running setup.py install for mozinfo 16:21:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:21:24 INFO - Running setup.py install for mozInstall 16:21:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:21:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:21:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:21:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:21:24 INFO - Running setup.py install for mozleak 16:21:24 INFO - Running setup.py install for mozlog 16:21:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:21:24 INFO - Running setup.py install for moznetwork 16:21:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:21:25 INFO - Running setup.py install for mozprocess 16:21:25 INFO - Running setup.py install for mozprofile 16:21:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:21:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:21:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:21:25 INFO - Running setup.py install for mozrunner 16:21:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:21:25 INFO - Running setup.py install for mozscreenshot 16:21:25 INFO - Running setup.py install for moztest 16:21:25 INFO - Running setup.py install for mozversion 16:21:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:21:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:21:25 INFO - Cleaning up... 16:21:25 INFO - Return code: 0 16:21:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:25 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')]} 16:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:25 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')]} 16:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:25 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 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:25 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 16:21:25 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 16:21:25 INFO - Using env: {'DISPLAY': ':0', 16:21:25 INFO - 'HOME': '/home/cltbld', 16:21:25 INFO - 'LANG': 'en_US.UTF-8', 16:21:25 INFO - 'LANGUAGE': 'en_US:en', 16:21:25 INFO - 'LOGNAME': 'cltbld', 16:21:25 INFO - 'MAIL': '/var/mail/cltbld', 16:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:25 INFO - 'MOZ_NO_REMOTE': '1', 16:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:25 INFO - 'NO_EM_RESTART': '1', 16:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:25 INFO - 'PWD': '/builds/slave/test', 16:21:25 INFO - 'SHELL': '/bin/bash', 16:21:25 INFO - 'SHLVL': '1', 16:21:25 INFO - 'TERM': 'linux', 16:21:25 INFO - 'TMOUT': '86400', 16:21:25 INFO - 'USER': 'cltbld', 16:21:25 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:25 INFO - '_': '/tools/buildbot/bin/python'} 16:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:25 INFO - Running setup.py (path:/tmp/pip-LojoK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:25 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)) 16:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:25 INFO - Running setup.py (path:/tmp/pip-mVLEs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:26 INFO - Running setup.py (path:/tmp/pip-KNhAJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:26 INFO - Running setup.py (path:/tmp/pip-DqNEqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:26 INFO - Running setup.py (path:/tmp/pip-WWKera-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:26 INFO - Running setup.py (path:/tmp/pip-4fqIrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:26 INFO - Running setup.py (path:/tmp/pip-cgy7HG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:26 INFO - Running setup.py (path:/tmp/pip-qThup1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:26 INFO - Running setup.py (path:/tmp/pip-WX2jLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:26 INFO - Running setup.py (path:/tmp/pip-eCof48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:26 INFO - Running setup.py (path:/tmp/pip-yLBZrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:26 INFO - Running setup.py (path:/tmp/pip-dV7bQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:26 INFO - Running setup.py (path:/tmp/pip-96Pztq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:26 INFO - Running setup.py (path:/tmp/pip-75hq6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:26 INFO - Running setup.py (path:/tmp/pip-xI9lep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:26 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)) 16:21:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:26 INFO - Running setup.py (path:/tmp/pip-tnXZK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:27 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)) 16:21:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:27 INFO - Running setup.py (path:/tmp/pip-MnY6Ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:27 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)) 16:21:27 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)) 16:21:27 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)) 16:21:27 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)) 16:21:27 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)) 16:21:27 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)) 16:21:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:29 INFO - Downloading blessings-1.6.tar.gz 16:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:21:29 INFO - Installing collected packages: blessings 16:21:29 INFO - Running setup.py install for blessings 16:21:29 INFO - Successfully installed blessings 16:21:29 INFO - Cleaning up... 16:21:29 INFO - Return code: 0 16:21:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:21:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:29 INFO - Reading from file tmpfile_stdout 16:21:29 INFO - Current package versions: 16:21:29 INFO - argparse == 1.2.1 16:21:29 INFO - blessings == 1.6 16:21:29 INFO - blobuploader == 1.2.4 16:21:29 INFO - docopt == 0.6.1 16:21:29 INFO - functools32 == 3.2.3-2 16:21:29 INFO - jsonschema == 2.5.1 16:21:29 INFO - manifestparser == 1.1 16:21:29 INFO - mozInstall == 1.12 16:21:29 INFO - mozcrash == 0.17 16:21:29 INFO - mozdebug == 0.1 16:21:29 INFO - mozdevice == 0.48 16:21:29 INFO - mozfile == 1.2 16:21:29 INFO - mozhttpd == 0.7 16:21:29 INFO - mozinfo == 0.9 16:21:29 INFO - mozleak == 0.1 16:21:29 INFO - mozlog == 3.3 16:21:29 INFO - moznetwork == 0.27 16:21:29 INFO - mozprocess == 0.23 16:21:29 INFO - mozprofile == 0.28 16:21:29 INFO - mozrunner == 6.12 16:21:29 INFO - mozscreenshot == 0.1 16:21:29 INFO - mozsystemmonitor == 0.3 16:21:29 INFO - moztest == 0.7 16:21:29 INFO - mozversion == 1.4 16:21:29 INFO - psutil == 3.1.1 16:21:29 INFO - requests == 1.2.3 16:21:29 INFO - wsgiref == 0.1.2 16:21:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:29 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 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:29 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 16:21:29 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 16:21:29 INFO - Using env: {'DISPLAY': ':0', 16:21:29 INFO - 'HOME': '/home/cltbld', 16:21:29 INFO - 'LANG': 'en_US.UTF-8', 16:21:29 INFO - 'LANGUAGE': 'en_US:en', 16:21:29 INFO - 'LOGNAME': 'cltbld', 16:21:29 INFO - 'MAIL': '/var/mail/cltbld', 16:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:29 INFO - 'MOZ_NO_REMOTE': '1', 16:21:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:29 INFO - 'NO_EM_RESTART': '1', 16:21:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:29 INFO - 'PWD': '/builds/slave/test', 16:21:29 INFO - 'SHELL': '/bin/bash', 16:21:29 INFO - 'SHLVL': '1', 16:21:29 INFO - 'TERM': 'linux', 16:21:29 INFO - 'TMOUT': '86400', 16:21:29 INFO - 'USER': 'cltbld', 16:21:29 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:29 INFO - '_': '/tools/buildbot/bin/python'} 16:21:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:29 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)) 16:21:29 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)) 16:21:29 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)) 16:21:29 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)) 16:21:29 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)) 16:21:29 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)) 16:21:29 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)) 16:21:29 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)) 16:21:29 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)) 16:21:29 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)) 16:21:29 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)) 16:21:29 INFO - Cleaning up... 16:21:29 INFO - Return code: 0 16:21:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f65226eef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f36420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', '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 16:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:21:29 INFO - Using env: {'DISPLAY': ':0', 16:21:29 INFO - 'HOME': '/home/cltbld', 16:21:29 INFO - 'LANG': 'en_US.UTF-8', 16:21:29 INFO - 'LANGUAGE': 'en_US:en', 16:21:29 INFO - 'LOGNAME': 'cltbld', 16:21:29 INFO - 'MAIL': '/var/mail/cltbld', 16:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:29 INFO - 'MOZ_NO_REMOTE': '1', 16:21:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:29 INFO - 'NO_EM_RESTART': '1', 16:21:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:29 INFO - 'PWD': '/builds/slave/test', 16:21:29 INFO - 'SHELL': '/bin/bash', 16:21:29 INFO - 'SHLVL': '1', 16:21:29 INFO - 'TERM': 'linux', 16:21:29 INFO - 'TMOUT': '86400', 16:21:29 INFO - 'USER': 'cltbld', 16:21:29 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:29 INFO - '_': '/tools/buildbot/bin/python'} 16:21:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:21:29 INFO - Cleaning up... 16:21:29 INFO - Return code: 0 16:21:29 INFO - Running post-action listener: _resource_record_post_action 16:21:29 INFO - Running post-action listener: _start_resource_monitoring 16:21:29 INFO - Starting resource monitoring. 16:21:29 INFO - [mozharness: 2016-08-16 23:21:29.950360Z] Finished create-virtualenv step (success) 16:21:29 INFO - [mozharness: 2016-08-16 23:21:29.950828Z] Running install step. 16:21:29 INFO - Running pre-action listener: _resource_record_pre_action 16:21:29 INFO - Running main action method: install 16:21:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:30 INFO - Reading from file tmpfile_stdout 16:21:30 INFO - Detecting whether we're running mozinstall >=1.0... 16:21:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:21:30 INFO - Reading from file tmpfile_stdout 16:21:30 INFO - Output received: 16:21:30 INFO - Usage: mozinstall [options] installer 16:21:30 INFO - Options: 16:21:30 INFO - -h, --help show this help message and exit 16:21:30 INFO - -d DEST, --destination=DEST 16:21:30 INFO - Directory to install application into. [default: 16:21:30 INFO - "/builds/slave/test"] 16:21:30 INFO - --app=APP Application being installed. [default: firefox] 16:21:30 INFO - mkdir: /builds/slave/test/build/application 16:21:30 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'] 16:21:30 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 16:21:47 INFO - Reading from file tmpfile_stdout 16:21:47 INFO - Output received: 16:21:47 INFO - /builds/slave/test/build/application/firefox/firefox 16:21:47 INFO - Running post-action listener: _resource_record_post_action 16:21:47 INFO - [mozharness: 2016-08-16 23:21:47.699851Z] Finished install step (success) 16:21:47 INFO - [mozharness: 2016-08-16 23:21:47.700098Z] Running run-tests step. 16:21:47 INFO - Running pre-action listener: _resource_record_pre_action 16:21:47 INFO - Running main action method: run_tests 16:21:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:21:47 INFO - Python 2.7.3 16:21:47 INFO - Return code: 0 16:21:47 INFO - grabbing minidump binary from tooltool 16:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:47 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 0x1fc4d20>, '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 0x1fc5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fc60b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:21:47 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 16:21:47 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 16:21:47 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 16:21:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:21:47 INFO - Return code: 0 16:21:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:21:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:21:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:21:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:21:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:21:47 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/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:21:47 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/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:21:47 INFO - Using env: {'DISPLAY': ':0', 16:21:47 INFO - 'HOME': '/home/cltbld', 16:21:47 INFO - 'LANG': 'en_US.UTF-8', 16:21:47 INFO - 'LANGUAGE': 'en_US:en', 16:21:47 INFO - 'LOGNAME': 'cltbld', 16:21:47 INFO - 'MAIL': '/var/mail/cltbld', 16:21:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:21:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:47 INFO - 'MOZ_NO_REMOTE': '1', 16:21:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:21:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:47 INFO - 'NO_EM_RESTART': '1', 16:21:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:47 INFO - 'PWD': '/builds/slave/test', 16:21:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:21:47 INFO - 'SHELL': '/bin/bash', 16:21:47 INFO - 'SHLVL': '1', 16:21:47 INFO - 'TERM': 'linux', 16:21:47 INFO - 'TMOUT': '86400', 16:21:47 INFO - 'USER': 'cltbld', 16:21:47 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083', 16:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:47 INFO - '_': '/tools/buildbot/bin/python'} 16:21:47 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/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:21:47 INFO - mozversion application_buildid: 20160816133002 16:21:47 INFO - mozversion application_changeset: 1eaa34d5dba1e3698785c7ffab4e5bd0f9529a0f 16:21:47 INFO - mozversion application_display_name: Nightly 16:21:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:21:47 INFO - mozversion application_name: Firefox 16:21:47 INFO - mozversion application_remotingname: firefox 16:21:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:21:47 INFO - mozversion application_vendor: Mozilla 16:21:47 INFO - mozversion application_version: 51.0a1 16:21:47 INFO - mozversion platform_buildid: 20160816133002 16:21:47 INFO - mozversion platform_changeset: 1eaa34d5dba1e3698785c7ffab4e5bd0f9529a0f 16:21:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:21:47 INFO - mozversion platform_version: 51.0a1 16:21:47 INFO - using testdate: 1471389707 16:21:47 INFO - actual date: 1471389707 16:21:47 INFO - starting webserver on 'localhost:58987' 16:21:47 INFO - SUITE-START | Running 2 tests 16:21:47 INFO - TEST-START | dromaeo_css 16:21:47 INFO - operating with platform_type : linux_ 16:21:47 INFO - Initialising browser for dromaeo_css test... 16:21:47 INFO - TEST-INFO | started process 13902 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprfLig0/profile http://localhost:58987/getInfo.html) 16:21:51 INFO - PROCESS | 13902 | __metrics Screen width/height:1600/1200 16:21:51 INFO - PROCESS | 13902 | colorDepth:24 16:21:51 INFO - PROCESS | 13902 | Browser inner width/height: 1024/697 16:21:51 INFO - PROCESS | 13902 | __metrics 16:21:52 INFO - PROCESS | 13902 | 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" 16:21:52 INFO - PROCESS | 13902 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:21:53 INFO - TEST-INFO | 13902: exit 0 16:21:53 INFO - Browser initialized. 16:21:53 INFO - Running cycle 1/1 for dromaeo_css test... 16:21:53 INFO - TEST-INFO | started process 13984 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprfLig0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:22:05 INFO - PROCESS | 13984 | RSS: Main: 155881472 16:22:05 INFO - PROCESS | 13984 | 16:22:57 INFO - PROCESS | 13984 | 1471389777101 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] 16:25:06 INFO - PROCESS | 13984 | Cycle 1(1): loaded http://localhost:58987/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58987/tests/dromaeo/cssquery-ext.html) 16:25:07 INFO - PROCESS | 13984 | RSS: Main: 265785344 16:25:07 INFO - PROCESS | 13984 | 16:28:09 INFO - PROCESS | 13984 | Cycle 1(1): loaded http://localhost:58987/tests/dromaeo/cssquery-ext.html (next: http://localhost:58987/tests/dromaeo/cssquery-jquery.html) 16:28:10 INFO - PROCESS | 13984 | RSS: Main: 240136192 16:28:10 INFO - PROCESS | 13984 | 16:31:12 INFO - PROCESS | 13984 | Cycle 1(1): loaded http://localhost:58987/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58987/tests/dromaeo/cssquery-mootools.html) 16:31:13 INFO - PROCESS | 13984 | RSS: Main: 187994112 16:31:13 INFO - PROCESS | 13984 | 16:34:15 INFO - PROCESS | 13984 | Cycle 1(1): loaded http://localhost:58987/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58987/tests/dromaeo/cssquery-prototype.html) 16:34:15 INFO - PROCESS | 13984 | RSS: Main: 217649152 16:34:15 INFO - PROCESS | 13984 | 16:37:18 INFO - PROCESS | 13984 | Cycle 1(1): loaded http://localhost:58987/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58987/tests/dromaeo/cssquery-yui.html) 16:37:19 INFO - PROCESS | 13984 | RSS: Main: 189579264 16:37:19 INFO - PROCESS | 13984 | 16:40:22 INFO - PROCESS | 13984 | Cycle 1(1): loaded http://localhost:58987/tests/dromaeo/cssquery-yui.html (next: http://localhost:58987/tests/dromaeo/cssquery-dojo.html) 16:40:22 INFO - PROCESS | 13984 | RSS: Main: 280346624 16:40:22 INFO - PROCESS | 13984 | 16:40:22 INFO - PROCESS | 13984 | __start_tp_report 16:40:22 INFO - PROCESS | 13984 | _x_x_mozilla_page_load 16:40:22 INFO - PROCESS | 13984 | _x_x_mozilla_page_load_details 16:40:22 INFO - PROCESS | 13984 | |i|pagename|runs| 16:40:22 INFO - PROCESS | 13984 | |0;dojo.html;3048.3446059097296;3054.7403470705017;3074.707902749238;3078.538219267108;3093.3503964167526;5240.240165176047;5315.309009828727;5429.864253393662;5464.945350546498;5550.333959924809;5396.973015134919;5447.645903016301;5471.50756431921;5480.821623350978;5527.032769265383;4868.075065737498;4910.852674419755;4944.233643785219;4956.950430495703;5102.821401250957;5000.7249601271915;5024.995000999815;5046.419661738911;5057.291979122926;5060.721660308682;4770.833020844271;4871.732054736988;4875.926861097087;4938.555530002317;5007.173816320321;4903.534164254425;4909.3126962225315;4911.68074075184;4914.16459201933;4971.328870602495;4931.358923339979;4967.205247160437;4972.154733695281;4986.551210391082;5002.874928126789;5215;5361.678299302054;5431.646942948696;5446.537044351235;5463.617546771728;5802.47777700009;5868.207791948075;5868.207791948075;5874.618149820248;5881.705914704247;5893.941060589382;5908.556858235649;5920.851978700523;5932.406759324076;5939.049752039653;5235.712035838027;5359.678419294854;5441.700706461103;5443.755031023614;5509.752061157257;6018.398160183947;6023.488003519662;6031.939680603138;6034.788782392595;6035.245594300713;5788.797392091256;5790.71046447676;5792.88414231713;5794.101914203306;5795;5526.005319042611;5734.369219385864;5791.305043394819;5798.188253644493;5867.354590994987;5748.942510574924;5757.050086735641;5778.219940308089;5803.564732645069;5837.562182836305;5760.193572899798;5802.187693722883;5810.50610698087;5812.244408226988;5824.3302020267365;4270.12462445198;4351.891202719894;4415.51429342772;4416.293393057095;4418.491873434532;3949.1706741584017;3950.9802450987563;3952.3676211806546;3977.343738283209;3987.1228329767405;5537.861553461115;5538.723063846872;5541.33503979517;5584.329880414376;5584.497395234367;5539.639923404966;5738.8278351649515;5788.7684492620665;5803.100519419497;5872.236609240771;5493.587980901369;5495.203195536705;5498.092814685532;5498.202760599691;5509.862253443696;4887.657863949489;4899.289603007615;4899.314096026559;4900.460949295574;4942.579880710108;5846.912296315559;6030;6032.276126864805;6057.969710151421;6099.603525770811;5846.035404158266;5846.970765146147;5847.795327163529;5853.795117171049;5887.58786884931;6042.667653279112;6044.214252147192;6044.546659000681;6045.697715114315;6054.33402325735;5525.419830917695;5683.033884239607;5685.573581981451;5730.713464326684;5732.283464566929;5803.506701930383;5844.766209351578;5859.179714840011;5863.912041319469;5909.261342332208;5723.427657234243;5734.569907257056;5736.0822268438515;5739.540836733135;5746.597738158289;5492.478214569769;5567.832965011056;5624;5646.2942132233475;5701.714914254354;5674.319081710056;5678.3186017678145;5682.9147562785765;5685.772569097356;5687.744051517774;5729.570282228766;5735.225744524602;5762.942370576408;5772.422757724362;5787.681677507778;5309.920351194658;5434.619576629598;5522.723863806874;5549.334079910436;5582.804601839079 16:40:22 INFO - PROCESS | 13984 | |1;ext.html;566.5439321346316;592.8547505861064;598.3567664760384;611.2267981004028;637.5250438423375;33292.83353583232;46972.530274697245;48771;48777.04891804328;48851;46165;46633.30050049252;46665.76667116668;46719.532804671944;46933.765331173294;8960.372773905812;9087.273018158532;9104.317176211778;9105.908940910605;9115.361924665276;8902.243309318716;8902.821943561124;8914.73255802327;8937.374383793138;9003.5048072356;8946.373753837233;8988.505632190227;8991.415557988743;8997.370184087116;9012.143846334588;8756.69351572695;8781.077986811357;8787.209151176361;8795.516246606434;8853.335999800041;8676.34927380449;8749.212570868589;8753.168448997336;8755.78110547235;8759;43356.349654755206;43474.782626086984;43611.563884361065;43718.56281437177;43784.56215437836;3445.6037555680914;3589.0668426209113;3665.6334366563397;3808.5048943637426;4107.753534787892;32626.510602340983;32990.50514242288;33565.16087097818;33629.327413451596;33741.6625833741;4206.305959516707;4259.254630439661;4322.546132656057;4333.2200203963275;4454.487733910577;348500;349759;350684;351019.99999999744;351744;57148.71425642845;57499.42500575024;57680.981165659;57740.711296443245;57792;60673.69663151656;60940.695296523234;60983.34099953928;61006;61175.38824611786;16171.272292746855;16442.83557164413;16447.424340148038;16479.093649849132;16513.83486165147;12677.492900284069;12708.872911270953;12723.745525089447;12730.872691273154;12737.681557961125;13428.597142085753;16245.51263462098;16252.674946501003;16267;16296.592585185464;5107.668001579886;5126.615503837227;5153.45888681683;5169.896602067937;5301.602379821529;838.7399906029151;901.2609660078673;932.47315266874;939.5114540438933;1048.8793788714395;26104.8694756525;26548;26984.730152698223;26991.730082699316;27030.459390812073;1008.5461542305992;1022.0239671114068;1031.9122874555746;1055.5619417941623;1072.099436473366;12833.803882905306;13549.525766598123;13705.862941370458;13844.792328115087;13878.653033674238;501587;502156;503576;504853;505534;59946.40053599495;59966.10050849241;60121.398786012454;60508.092378614354;60676.393236067954;1238.5355491690616;1325.1651459580596;1341.3896677011935;1395.4278745714005;1396.5391420831102;25274;26497.735022649525;26986;27129;27359.86320068387;42394;42480.36279455749;42730.78634606807;43486.34770478508;44991.77504112459;423002;423395;424569;425407;425914;35985.82007089948;40386;40973.728127591865;41572;44072.338914916945;38529.22941541098;45338;47655.76172119117;48491.5150848487;48749.268760967905;40254.59745402508;40660.66456376495;41732.58267417287;42995.78502107469;43009.784951075046;40522;40684.38973415342;42074.36888446737;42119.36820947627;43433.565664342954;37635.24729505449;37836.81081594574;38729;39567.2086558273;39595;43037.56962430335;44287.778561106985;44733.77633111813;45198.32202516899;45305.546944530135;30430.543541846448;30452.238694032825;30742;30745.231369215948;30941.845290773403 16:40:22 INFO - PROCESS | 13984 | |2;jquery.html;391.5145219927291;394.26076107298803;398.34869987570335;403.61253196930943;426.5457287988292;13170;18537.258509659616;19295.421137365865;20317.898410507943;20368.490787730316;15369.462068827594;18194.909025454854;18726.719099213522;18819.247230110814;18953.810461895377;5636.859078523049;5816.214811000525;5819.428754236354;5820.068788993762;5821.359650438449;5591.888162236753;5743;5749.482546570808;5812.970935145318;5815.040518314473;5756.453136951983;5763.308402991647;5764.193012978166;5764.510016648569;5784.421557844224;5575.303087114111;5640.012997725382;5712;5785.305763308409;5787.768489260425;5145.588352931794;5147.3051138096625;5149.433562308143;5155.61332900034;5166.250893620441;22928.77071229283;23043.53912921749;23496.765032349627;23876.761232387627;23962.880185599133;2513.8240323177383;2531.7594828491265;2596.6364708940764;2971.7176868197485;3012.4276387486525;14246.216458094801;14329.64175895611;14890;14955.626109347244;14960.401583936738;1886.141805478504;1901.9429417117508;1914.8276655101108;1957.2171131547352;1981.960360792776;81246;81834;82770;83658.58170709168;84886;10325.63860264887;10419.531121099417;10763.623273185402;10783.191260655325;11201.215914886043;15224.46714364992;15241.923790381199;15250.618734531503;15270.770938435933;15283;7998.200179982072;8191.262786349257;8249.8350032999;8453.661853525913;8518.70184543538;7877.4091943104495;7957.3634109271115;7971.880421793678;8175;8211.17888211174;3971.860984865516;5846.619969702041;6204.689765511706;6521.543491955517;6524.151860258136;2004.829589484881;2010.4672261850621;2025.3215172917237;2035.450428384328;2075.450005748478;782.6556315221285;808.3613944983515;880.3309484791605;912.6942474271061;939.492673956056;15993.28030238642;16192;16276.186190690418;16818.159092045593;17681.11594420274;877.6971944679076;883.88067610873;896.9775755606162;914.1726737302752;928.2898582584328;7553.5845528495365;9609;9744.31789774723;9771.706848794547;9798.951005245071;490648;490705;495435;501817;502602;91249;91581.5420922891;92610.53694731618;94041;94270;846.2637505370244;855.170484629908;856.2678909532385;861.4701958295603;864.6627815152222;14858;15128.470503532324;15188.696226075195;15335.76996345078;15770.811947374807;43044.78477607592;43291;44633.55366446294;44634;45358;56287.43712562822;57083.429165707814;58895.41104588899;60138.699306503186;60402.69798650979;8070.87893681607;8305.252527272574;8356.749297521084;8683.480390931689;8833.734987950373;7194.892076618959;12032.157748957488;12070.45682944252;12484.188527745668;12885.549005784753;10518.894811051792;10755.89244107549;10886.40124793144;10953.288036277616;11367.090632749525;11122.61070862516;11260.380679062402;11560.531840448;11927.0458363329;12047.397630118283;10806;10826.079783218514;10877.728056798644;11105.833412498658;11217.2147949646;8400.243978042006;8422;8445.619947102272;8623.353248506262;8652.610632521677;10008.799824003334;10742.462876855969;10954.671359859218;11052.171087168334;11356.886431135583 16:40:22 INFO - PROCESS | 13984 | |3;mootools.html;1474.167095590991;1480.6890552983873;1648.8021437427503;1751.5095773183514;1799.1723807048766;8010.91989080109;8647.308215342773;8734.825303493926;9277.165055145044;9297.953510232448;8756.605952732127;9122.407043542165;9126.49804260767;9128.224100951427;9135;2432.6959130108735;2455.1161581830524;2459.434330104077;2463.7289898111285;2467.173496878539;2097.3393381084984;2320.6170981787986;2364.6689463475127;2382.5949588569983;2386.904523819045;2415.903363865443;2428.781409673129;2429.6962879640046;2443.6945381827272;2444.083468699238;2371.8932648030795;2398.6042303019894;2428.514297140579;2430.4409985703387;2431.3070774829266;2367.2661474942824;2378.3578433822945;2388;2388.581998150317;2390.470099136554;8968.730938071869;9183.265338772882;9190.678326258549;9195.356325057248;9206.17144457002;5230.63385563007;5323.467653234684;5329.866753331198;5363.597730170214;5367.597430192712;3307.832634120612;3376.4766461198556;3416.9145771355916;3434.9484757728655;3452.223249768807;1185.407296351824;1237.9442925068392;1246.731952630189;1255.0900597067146;1276.2916581297377;12281.201721888048;12348.073894457952;12367.690807729789;12429.502819887193;12433.254004759743;4691.437027556715;4797.7601119943865;4802.577615803636;4880.365552478155;4901.877453063702;10366.118879895294;10397.896021039844;10430.947845260725;10439.686809395731;10449.425281609585;5304;5330.840074797762;5332.733363331896;5333;5333.866653333697;4852.1508735971765;4871.464138944713;4909.828156014522;4909.901801963941;4920.803167873317;4000.6599439047427;4070.1452694933805;4200.684948628804;4201.33215408218;4262.87211383659;1220.6643173127284;1248.0888951065592;1251.9812202816966;1286.9935650321875;1304.9608511744664;890.3500444675424;907.5643691028212;910.1581037540249;916.6287653500343;921.1525396635112;5490.972545137249;5576.379101822137;5612.214289999403;5650.434956504317;5708.828735137952;936.6300311376968;942.1661829280998;949.6154057606681;951.2152474208806;981.3572110267786;3747.269282489888;3892.9416058759143;3904.3557812960544;3915.2169566086895;3917.4515567820526;11021.283616564897;11448.885511144781;11499.942500287445;11508.309501429942;11547.942260288644;11399.316041037564;11409.60066397672;11410.657680269605;11411;11413.029892459233;7908.536920669694;8183.3453323732765;8195.83608327842;8205.302549283379;8310.376721745894;5574.9721251393485;5810.82567522975;5863;5865.882682346414;6074.240719910012;9583.664571739955;9781.755456113655;10050.09549140581;10129.189664826648;10172.440515771703;9933.602655893972;10234.590616375262;10260.538275777757;10323.896761032294;10375.014373634493;7963.840723185388;8128.715494957648;8213.178682131738;8405.915940840514;8433.915660843313;8523;8571.828563428571;8586.184312490303;8588;8588.527630980356;7361;7654.693812247448;7665.6933722650465;7674.117476490275;7896.644650990834;7281.381082608038;7510.662020208994;7595.164531901597;7654.387648988179;7656.310932016146;6519.934800652122;6606.108175396452;6627.469802415699;6630;6631;4903.289023091703;4962.454130045764;5001.924971125509;5005.599552035901;5104.515071068243;4819.469858315652;4908.435529914105;5056.393232812086;5096.872578185575;5184.248283998874 16:40:22 INFO - PROCESS | 13984 | |4;prototype.html;321.1681744282308;329.1606403670641;330.301412512536;334.94138525757995;343.63231342463575;5149.742512874355;5179.430262671112;5185.637005409628;5187.766550505232;5194.168932970725;4881.682690625107;4991.500849915006;5009.524095210958;5027.622928280384;5188.72876145344;3896.688264938798;3981.5819338969354;4023.2355852387955;4105.466289382391;4135.441715368432;4085.12169883475;4091.3249313863175;4103.733257338278;4105.568915263899;4142.7514349139;3868.4719535783347;4043.332850079748;4057.5536690963972;4076.266272071041;4103.343465045593;4016.518017837849;4028.3756017817286;4043.7978101095123;4051.129007263423;4054.4931883514414;3554.862444017915;3562.5012498250267;3563.982180089083;3579.5167652367118;3598.2443686825536;4810.446798618169;5056.570191533724;5073.644844860862;5077.2637967494875;5088.033273677989;4604.539546045402;4645.907081858378;4648.070385922795;4650.302454631798;4656.557627025427;5410.458954104597;5425.647332923348;5430;5431.728413579344;5450.454954504517;3863.459115723801;3912.6087391260644;3913.62820532049;3940.5074365704286;3955.011247188203;5557.444255574451;5572.693501857396;5581.972090139564;5590.301212348497;5594.86012849682;3969.960300396959;3972.1658451724884;3973.980130099389;3978;3983.132144855699;4010.8997275068355;4012.9333393302472;4022.4971878515184;4031.637152656275;4033.8184781684895;4390.473143222834;4628.652851036186;4635.281531362644;4649.907271791127;4658.930116048262;5409.43200963901;5426.34883813937;5433.619646624698;5437.184422336682;5444.265024221759;1328.4752522753458;1403.3404299978993;1405.9648508787157;1408.7393832141086;1427.4647007372234;950.8811398575178;968.8207681578931;976.7204961500457;985;986.3440811860032;5243.737813109329;5244.7902083916215;5250.188684903702;5252.185911183773;5291.920621190685;5524.917126243109;5537.252470916455;5563.666180029211;5583.4137415571595;5597.132444471114;5074.847754567369;5244.737763111829;5258.868528286747;5292.497212764782;5314.661548918463;2926.5024945758846;2941.279386550309;2948.837813920255;2953.61602991614;2964.925876853096;5510.228568000483;5760.5022694099835;5760.942390576124;5785.161151633074;5803.622764520293;5713.628614140068;5715.914261286084;5717.313922329264;5718.285214348207;5722.513586345124;5823.941760582425;5828.591998560145;5830.125481177773;5864.325602555676;5866.560007999332;5491.588130890279;5511.338639363302;5616.747246373841;5616.943830561642;5635.520980716685;5439.347278326468;5520.564653190119;5541.420695101877;5542.307211598551;5559.33288005442;5581.972090139686;5710.743016564347;5802.94197058024;5822.214001109796;5831.212786273799;3964.6035396460125;4017.5379831318605;4020.4170395293104;4049.7772622506045;4052.3313653248065;5595.244641973444;5602.663840169603;5612.306093075428;5613.719314034363;5635.718214089197;5376.4623537647485;5405.621606487508;5460.972695136499;5488.9725551371985;5490.972545137249;5408.702521361363;5413.404525502113;5427.647202931955;5432.158015507603;5433;5202.167653175474;5322.54758345545;5322.973385133049;5419.539339156216;5424.728763561727;5146.588272938081;5340.866478338073;5378.327709036371;5401.8939110011115;5455.18172274162;5327.121025030827;5328.227407026037;5362.436944120811;5363.061464243664;5363.41002489718 16:40:22 INFO - PROCESS | 13984 | |5;yui.html;730.9305615966481;736.8636802191592;773.912090757876;778.353966208047;859.2996707683237;14271.571852844405;15342.463013794506;15349.079055256692;15405.3837846486;15487.84512154878;14133.293335333243;15855.920720396382;16116.355345786184;16136.193190340495;16192.75710864338;792.9088154862206;818.4925346285312;820.6553247636007;820.9384296177781;821.3347188777079;666.5567397680561;797.6211299632657;815.8368326334727;816.677412422093;818.7789296889836;864.3776480933718;865.3596338709341;865.9523726195057;866.030046348091;866.2896424931558;857.2584714222215;857.4083882121379;857.5197889182047;857.7726902370877;857.9099194584603;510.4793111026769;611.5627326461602;620.2866703291206;627.0406278394064;628.6825153297556;14606.926965365212;14612.853871461255;14618.342174602169;14626.04930679513;14631.780523292158;14664.926675366662;14947.70104597902;15182.446911652805;15361.923190383977;15649.530514084596;12719.236845789283;12925.418356173996;12967.193776224869;13364;13432.731345373135;4549.522300158502;4591.793369298356;4594.2419500782335;4611.723296602181;4629.1436084324505;83345.16654833435;83674.58162709148;84436.57781711113;84472.35402052884;84629.57685211596;11627.941860290644;11681.707957300965;11700;11708.356040417859;12038.39808009596;22937.88531057334;22966.65550016751;22999.885000575225;23016.194433194756;23092.4226894329;8183.386246031571;8285.95857020711;8340.791480213044;8479.533625650525;8728.91271087281;4696.694714843593;4913.65604407688;4914.5822605078965;4921.87695307613;4928.876778080505;3347.0126312737316;3450.258194488197;3451.2062225688233;3456.1532424556144;3461.8961431157104;571.6255852416674;579.958973642313;583.9051777916407;585.7627660797299;596.385722705614;671.3378520172448;677.3700458573476;715.47770127807;717.3042149115349;732.8644200822865;9542.42745435276;9793.804123917482;9810.751535721036;9905.658041351988;10188.545851941475;567.0926517571852;633.8288662061218;647.7829926974422;651.680676468527;656.0487293424553;4698.271767875985;4868.756562171877;4906.803727850846;4912.140375434285;4976.328195693607;76650;87191.56404218065;131871;134428.32785836008;135360;88569;91272;92076;112272;113137.43431282791;76901;77650.61174694203;88262.55868720615;89555;90105;9847.753806154617;9983.351082179635;10388.48057597102;10429.426381549089;10646.20153488476;47945.28082078702;52355.73822130865;53912;54283.72858135684;55664.16503752366;71572.64213679107;76173;76761.61619191869;78337.60831195807;79618.6019069901;11845.289282643069;12239.32683702405;12314.630561083182;12675.55635552788;12742.936285318514;14913.552593422215;14981;15128;15568.455104071303;15880.126593037494;13204.60386188416;13355.866441335851;13524;14264.144151350953;14286.571402857931;12414.937925310316;12491.750164996467;13982.930085349508;14196.148231106166;14983.550493485212;13963.441462341392;14176.432942682177;14411.351489182802;14634.780478293047;14718.116912985255;10842;11770.470328835052;11809.940950295193;11826.34955077479;11899.940500297444;12225.633231003085;12419.93790031044;12440.883988855763;12624.55814046504;12673.80989285143 16:40:22 INFO - PROCESS | 13984 | __end_tp_report 16:40:22 INFO - PROCESS | 13984 | __start_cc_report 16:40:22 INFO - PROCESS | 13984 | _x_x_mozilla_cycle_collect,1033 16:40:22 INFO - PROCESS | 13984 | __end_cc_report 16:40:22 INFO - PROCESS | 13984 | __startTimestamp1471390822355__endTimestamp 16:40:22 INFO - PROCESS | 13984 | 16:40:22 INFO - PROCESS | 13984 | ------- Summary: start ------- 16:40:22 INFO - PROCESS | 13984 | Number of tests: 6 16:40:22 INFO - PROCESS | 13984 | 16:40:22 INFO - PROCESS | 13984 | [#0] dojo.html Cycles:165 Average:5399.44 Median:5582.80 stddev:633.54 (11.3%) stddev-sans-first:608.04 16:40:22 INFO - PROCESS | 13984 | Values: 3048.3 3054.7 3074.7 3078.5 3093.4 5240.2 5315.3 5429.9 5464.9 5550.3 5397.0 5447.6 5471.5 5480.8 5527.0 4868.1 4910.9 4944.2 4957.0 5102.8 5000.7 5025.0 5046.4 5057.3 5060.7 4770.8 4871.7 4875.9 4938.6 5007.2 4903.5 4909.3 4911.7 4914.2 4971.3 4931.4 4967.2 4972.2 4986.6 5002.9 5215.0 5361.7 5431.6 5446.5 5463.6 5802.5 5868.2 5868.2 5874.6 5881.7 5893.9 5908.6 5920.9 5932.4 5939.0 5235.7 5359.7 5441.7 5443.8 5509.8 6018.4 6023.5 6031.9 6034.8 6035.2 5788.8 5790.7 5792.9 5794.1 5795.0 5526.0 5734.4 5791.3 5798.2 5867.4 5748.9 5757.1 5778.2 5803.6 5837.6 5760.2 5802.2 5810.5 5812.2 5824.3 4270.1 4351.9 4415.5 4416.3 4418.5 3949.2 3951.0 3952.4 3977.3 3987.1 5537.9 5538.7 5541.3 5584.3 5584.5 5539.6 5738.8 5788.8 5803.1 5872.2 5493.6 5495.2 5498.1 5498.2 5509.9 4887.7 4899.3 4899.3 4900.5 4942.6 5846.9 6030.0 6032.3 6058.0 6099.6 5846.0 5847.0 5847.8 5853.8 5887.6 6042.7 6044.2 6044.5 6045.7 6054.3 5525.4 5683.0 5685.6 5730.7 5732.3 5803.5 5844.8 5859.2 5863.9 5909.3 5723.4 5734.6 5736.1 5739.5 5746.6 5492.5 5567.8 5624.0 5646.3 5701.7 5674.3 5678.3 5682.9 5685.8 5687.7 5729.6 5735.2 5762.9 5772.4 5787.7 5309.9 5434.6 5522.7 5549.3 5582.8 16:40:22 INFO - PROCESS | 13984 | 16:40:22 INFO - PROCESS | 13984 | [#1] ext.html Cycles:180 Average:59063.82 Median:43023.68 stddev:113980.33 (264.9%) stddev-sans-first:114215.46 16:40:22 INFO - PROCESS | 13984 | Values: 566.5 592.9 598.4 611.2 637.5 33292.8 46972.5 48771.0 48777.0 48851.0 46165.0 46633.3 46665.8 46719.5 46933.8 8960.4 9087.3 9104.3 9105.9 9115.4 8902.2 8902.8 8914.7 8937.4 9003.5 8946.4 8988.5 8991.4 8997.4 9012.1 8756.7 8781.1 8787.2 8795.5 8853.3 8676.3 8749.2 8753.2 8755.8 8759.0 43356.3 43474.8 43611.6 43718.6 43784.6 3445.6 3589.1 3665.6 3808.5 4107.8 32626.5 32990.5 33565.2 33629.3 33741.7 4206.3 4259.3 4322.5 4333.2 4454.5 348500.0 349759.0 350684.0 351020.0 351744.0 57148.7 57499.4 57681.0 57740.7 57792.0 60673.7 60940.7 60983.3 61006.0 61175.4 16171.3 16442.8 16447.4 16479.1 16513.8 12677.5 12708.9 12723.7 12730.9 12737.7 13428.6 16245.5 16252.7 16267.0 16296.6 5107.7 5126.6 5153.5 5169.9 5301.6 838.7 901.3 932.5 939.5 1048.9 26104.9 26548.0 26984.7 26991.7 27030.5 1008.5 1022.0 1031.9 1055.6 1072.1 12833.8 13549.5 13705.9 13844.8 13878.7 501587.0 502156.0 503576.0 504853.0 505534.0 59946.4 59966.1 60121.4 60508.1 60676.4 1238.5 1325.2 1341.4 1395.4 1396.5 25274.0 26497.7 26986.0 27129.0 27359.9 42394.0 42480.4 42730.8 43486.3 44991.8 423002.0 423395.0 424569.0 425407.0 425914.0 35985.8 40386.0 40973.7 41572.0 44072.3 38529.2 45338.0 47655.8 48491.5 48749.3 40254.6 40660.7 41732.6 42995.8 43009.8 40522.0 40684.4 42074.4 42119.4 43433.6 37635.2 37836.8 38729.0 39567.2 39595.0 43037.6 44287.8 44733.8 45198.3 45305.5 30430.5 30452.2 30742.0 30745.2 30941.8 16:40:22 INFO - PROCESS | 13984 | 16:40:22 INFO - PROCESS | 13984 | [#2] jquery.html Cycles:180 Average:29132.22 Median:490676.50 stddev:81891.22 (16.7%) stddev-sans-first:82092.52 16:40:22 INFO - PROCESS | 13984 | Values: 391.5 394.3 398.3 403.6 426.5 13170.0 18537.3 19295.4 20317.9 20368.5 15369.5 18194.9 18726.7 18819.2 18953.8 5636.9 5816.2 5819.4 5820.1 5821.4 5591.9 5743.0 5749.5 5813.0 5815.0 5756.5 5763.3 5764.2 5764.5 5784.4 5575.3 5640.0 5712.0 5785.3 5787.8 5145.6 5147.3 5149.4 5155.6 5166.3 22928.8 23043.5 23496.8 23876.8 23962.9 2513.8 2531.8 2596.6 2971.7 3012.4 14246.2 14329.6 14890.0 14955.6 14960.4 1886.1 1901.9 1914.8 1957.2 1982.0 81246.0 81834.0 82770.0 83658.6 84886.0 10325.6 10419.5 10763.6 10783.2 11201.2 15224.5 15241.9 15250.6 15270.8 15283.0 7998.2 8191.3 8249.8 8453.7 8518.7 7877.4 7957.4 7971.9 8175.0 8211.2 3971.9 5846.6 6204.7 6521.5 6524.2 2004.8 2010.5 2025.3 2035.5 2075.5 782.7 808.4 880.3 912.7 939.5 15993.3 16192.0 16276.2 16818.2 17681.1 877.7 883.9 897.0 914.2 928.3 7553.6 9609.0 9744.3 9771.7 9799.0 490648.0 490705.0 495435.0 501817.0 502602.0 91249.0 91581.5 92610.5 94041.0 94270.0 846.3 855.2 856.3 861.5 864.7 14858.0 15128.5 15188.7 15335.8 15770.8 43044.8 43291.0 44633.6 44634.0 45358.0 56287.4 57083.4 58895.4 60138.7 60402.7 8070.9 8305.3 8356.7 8683.5 8833.7 7194.9 12032.2 12070.5 12484.2 12885.5 10518.9 10755.9 10886.4 10953.3 11367.1 11122.6 11260.4 11560.5 11927.0 12047.4 10806.0 10826.1 10877.7 11105.8 11217.2 8400.2 8422.0 8445.6 8623.4 8652.6 10008.8 10742.5 10954.7 11052.2 11356.9 16:40:22 INFO - PROCESS | 13984 | 16:40:22 INFO - PROCESS | 13984 | [#3] mootools.html Cycles:180 Average:5853.96 Median:4905.86 stddev:3348.58 (68.3%) stddev-sans-first:3341.79 16:40:22 INFO - PROCESS | 13984 | Values: 1474.2 1480.7 1648.8 1751.5 1799.2 8010.9 8647.3 8734.8 9277.2 9298.0 8756.6 9122.4 9126.5 9128.2 9135.0 2432.7 2455.1 2459.4 2463.7 2467.2 2097.3 2320.6 2364.7 2382.6 2386.9 2415.9 2428.8 2429.7 2443.7 2444.1 2371.9 2398.6 2428.5 2430.4 2431.3 2367.3 2378.4 2388.0 2388.6 2390.5 8968.7 9183.3 9190.7 9195.4 9206.2 5230.6 5323.5 5329.9 5363.6 5367.6 3307.8 3376.5 3416.9 3434.9 3452.2 1185.4 1237.9 1246.7 1255.1 1276.3 12281.2 12348.1 12367.7 12429.5 12433.3 4691.4 4797.8 4802.6 4880.4 4901.9 10366.1 10397.9 10430.9 10439.7 10449.4 5304.0 5330.8 5332.7 5333.0 5333.9 4852.2 4871.5 4909.8 4909.9 4920.8 4000.7 4070.1 4200.7 4201.3 4262.9 1220.7 1248.1 1252.0 1287.0 1305.0 890.4 907.6 910.2 916.6 921.2 5491.0 5576.4 5612.2 5650.4 5708.8 936.6 942.2 949.6 951.2 981.4 3747.3 3892.9 3904.4 3915.2 3917.5 11021.3 11448.9 11499.9 11508.3 11547.9 11399.3 11409.6 11410.7 11411.0 11413.0 7908.5 8183.3 8195.8 8205.3 8310.4 5575.0 5810.8 5863.0 5865.9 6074.2 9583.7 9781.8 10050.1 10129.2 10172.4 9933.6 10234.6 10260.5 10323.9 10375.0 7963.8 8128.7 8213.2 8405.9 8433.9 8523.0 8571.8 8586.2 8588.0 8588.5 7361.0 7654.7 7665.7 7674.1 7896.6 7281.4 7510.7 7595.2 7654.4 7656.3 6519.9 6606.1 6627.5 6630.0 6631.0 4903.3 4962.5 5001.9 5005.6 5104.5 4819.5 4908.4 5056.4 5096.9 5184.2 16:40:22 INFO - PROCESS | 13984 | 16:40:22 INFO - PROCESS | 13984 | [#4] prototype.html Cycles:180 Average:4581.85 Median:5247.49 stddev:1340.20 (25.5%) stddev-sans-first:1305.25 16:40:22 INFO - PROCESS | 13984 | Values: 321.2 329.2 330.3 334.9 343.6 5149.7 5179.4 5185.6 5187.8 5194.2 4881.7 4991.5 5009.5 5027.6 5188.7 3896.7 3981.6 4023.2 4105.5 4135.4 4085.1 4091.3 4103.7 4105.6 4142.8 3868.5 4043.3 4057.6 4076.3 4103.3 4016.5 4028.4 4043.8 4051.1 4054.5 3554.9 3562.5 3564.0 3579.5 3598.2 4810.4 5056.6 5073.6 5077.3 5088.0 4604.5 4645.9 4648.1 4650.3 4656.6 5410.5 5425.6 5430.0 5431.7 5450.5 3863.5 3912.6 3913.6 3940.5 3955.0 5557.4 5572.7 5582.0 5590.3 5594.9 3970.0 3972.2 3974.0 3978.0 3983.1 4010.9 4012.9 4022.5 4031.6 4033.8 4390.5 4628.7 4635.3 4649.9 4658.9 5409.4 5426.3 5433.6 5437.2 5444.3 1328.5 1403.3 1406.0 1408.7 1427.5 950.9 968.8 976.7 985.0 986.3 5243.7 5244.8 5250.2 5252.2 5291.9 5524.9 5537.3 5563.7 5583.4 5597.1 5074.8 5244.7 5258.9 5292.5 5314.7 2926.5 2941.3 2948.8 2953.6 2964.9 5510.2 5760.5 5760.9 5785.2 5803.6 5713.6 5715.9 5717.3 5718.3 5722.5 5823.9 5828.6 5830.1 5864.3 5866.6 5491.6 5511.3 5616.7 5616.9 5635.5 5439.3 5520.6 5541.4 5542.3 5559.3 5582.0 5710.7 5802.9 5822.2 5831.2 3964.6 4017.5 4020.4 4049.8 4052.3 5595.2 5602.7 5612.3 5613.7 5635.7 5376.5 5405.6 5461.0 5489.0 5491.0 5408.7 5413.4 5427.6 5432.2 5433.0 5202.2 5322.5 5323.0 5419.5 5424.7 5146.6 5340.9 5378.3 5401.9 5455.2 5327.1 5328.2 5362.4 5363.1 5363.4 16:40:22 INFO - PROCESS | 13984 | 16:40:22 INFO - PROCESS | 13984 | [#5] yui.html Cycles:180 Average:21259.97 Median:26321.78 stddev:30579.34 (116.2%) stddev-sans-first:30626.28 16:40:22 INFO - PROCESS | 13984 | Values: 730.9 736.9 773.9 778.4 859.3 14271.6 15342.5 15349.1 15405.4 15487.8 14133.3 15855.9 16116.4 16136.2 16192.8 792.9 818.5 820.7 820.9 821.3 666.6 797.6 815.8 816.7 818.8 864.4 865.4 866.0 866.0 866.3 857.3 857.4 857.5 857.8 857.9 510.5 611.6 620.3 627.0 628.7 14606.9 14612.9 14618.3 14626.0 14631.8 14664.9 14947.7 15182.4 15361.9 15649.5 12719.2 12925.4 12967.2 13364.0 13432.7 4549.5 4591.8 4594.2 4611.7 4629.1 83345.2 83674.6 84436.6 84472.4 84629.6 11627.9 11681.7 11700.0 11708.4 12038.4 22937.9 22966.7 22999.9 23016.2 23092.4 8183.4 8286.0 8340.8 8479.5 8728.9 4696.7 4913.7 4914.6 4921.9 4928.9 3347.0 3450.3 3451.2 3456.2 3461.9 571.6 580.0 583.9 585.8 596.4 671.3 677.4 715.5 717.3 732.9 9542.4 9793.8 9810.8 9905.7 10188.5 567.1 633.8 647.8 651.7 656.0 4698.3 4868.8 4906.8 4912.1 4976.3 76650.0 87191.6 131871.0 134428.3 135360.0 88569.0 91272.0 92076.0 112272.0 113137.4 76901.0 77650.6 88262.6 89555.0 90105.0 9847.8 9983.4 10388.5 10429.4 10646.2 47945.3 52355.7 53912.0 54283.7 55664.2 71572.6 76173.0 76761.6 78337.6 79618.6 11845.3 12239.3 12314.6 12675.6 12742.9 14913.6 14981.0 15128.0 15568.5 15880.1 13204.6 13355.9 13524.0 14264.1 14286.6 12414.9 12491.8 13982.9 14196.1 14983.6 13963.4 14176.4 14411.4 14634.8 14718.1 10842.0 11770.5 11809.9 11826.3 11899.9 12225.6 12419.9 12440.9 12624.6 12673.8 16:40:22 INFO - PROCESS | 13984 | -------- Summary: end -------- 16:40:22 INFO - PROCESS | 13984 | 16:40:23 INFO - TEST-INFO | 13984: exit 0 16:40:23 INFO - TEST-OK | dromaeo_css | took 1115188ms 16:40:23 INFO - TEST-START | kraken 16:40:23 INFO - operating with platform_type : linux_ 16:40:23 INFO - Initialising browser for kraken test... 16:40:23 INFO - TEST-INFO | started process 14283 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ52Eu8/profile http://localhost:58987/getInfo.html) 16:40:27 INFO - PROCESS | 14283 | __metrics Screen width/height:1600/1200 16:40:27 INFO - PROCESS | 14283 | colorDepth:24 16:40:27 INFO - PROCESS | 14283 | Browser inner width/height: 1024/697 16:40:27 INFO - PROCESS | 14283 | __metrics 16:40:27 INFO - PROCESS | 14283 | 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" 16:40:27 INFO - PROCESS | 14283 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:40:28 INFO - TEST-INFO | 14283: exit 0 16:40:28 INFO - Browser initialized. 16:40:28 INFO - Running cycle 1/1 for kraken test... 16:40:28 INFO - TEST-INFO | started process 14362 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ52Eu8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:40:40 INFO - PROCESS | 14362 | RSS: Main: 156135424 16:40:40 INFO - PROCESS | 14362 | 16:40:43 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58987/tests/kraken/driver.html?testName=audio-beat-detection) 16:40:43 INFO - PROCESS | 14362 | RSS: Main: 204828672 16:40:43 INFO - PROCESS | 14362 | 16:40:46 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58987/tests/kraken/driver.html?testName=audio-dft) 16:40:47 INFO - PROCESS | 14362 | RSS: Main: 195424256 16:40:47 INFO - PROCESS | 14362 | 16:40:50 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58987/tests/kraken/driver.html?testName=audio-fft) 16:40:50 INFO - PROCESS | 14362 | RSS: Main: 275484672 16:40:50 INFO - PROCESS | 14362 | 16:40:52 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58987/tests/kraken/driver.html?testName=audio-oscillator) 16:40:53 INFO - PROCESS | 14362 | RSS: Main: 186126336 16:40:53 INFO - PROCESS | 14362 | 16:40:55 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58987/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:40:56 INFO - PROCESS | 14362 | RSS: Main: 191049728 16:40:56 INFO - PROCESS | 14362 | 16:40:59 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58987/tests/kraken/driver.html?testName=imaging-darkroom) 16:41:00 INFO - PROCESS | 14362 | RSS: Main: 226791424 16:41:00 INFO - PROCESS | 14362 | 16:41:03 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58987/tests/kraken/driver.html?testName=imaging-desaturate) 16:41:03 INFO - PROCESS | 14362 | RSS: Main: 226492416 16:41:03 INFO - PROCESS | 14362 | 16:41:06 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58987/tests/kraken/driver.html?testName=json-parse-financial) 16:41:07 INFO - PROCESS | 14362 | RSS: Main: 226820096 16:41:07 INFO - PROCESS | 14362 | 16:41:08 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58987/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:41:09 INFO - PROCESS | 14362 | RSS: Main: 188309504 16:41:09 INFO - PROCESS | 14362 | 16:41:11 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58987/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:41:11 INFO - PROCESS | 14362 | RSS: Main: 197062656 16:41:11 INFO - PROCESS | 14362 | 16:41:13 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58987/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:41:14 INFO - PROCESS | 14362 | RSS: Main: 193449984 16:41:14 INFO - PROCESS | 14362 | 16:41:17 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58987/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:41:17 INFO - PROCESS | 14362 | RSS: Main: 187310080 16:41:17 INFO - PROCESS | 14362 | 16:41:20 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58987/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:41:21 INFO - PROCESS | 14362 | RSS: Main: 192749568 16:41:21 INFO - PROCESS | 14362 | 16:41:22 INFO - PROCESS | 14362 | Cycle 1(1): loaded http://localhost:58987/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58987/tests/kraken/driver.html?testName=ai-astar) 16:41:23 INFO - PROCESS | 14362 | RSS: Main: 243179520 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | __start_tp_report 16:41:23 INFO - PROCESS | 14362 | _x_x_mozilla_page_load 16:41:23 INFO - PROCESS | 14362 | _x_x_mozilla_page_load_details 16:41:23 INFO - PROCESS | 14362 | |i|pagename|runs| 16:41:23 INFO - PROCESS | 14362 | |0;ai-astar;106;111;113;111;108;112;112;110;112;112 16:41:23 INFO - PROCESS | 14362 | |1;audio-beat-detection;146;119;120;174;119;125;141;119;154;117 16:41:23 INFO - PROCESS | 14362 | |2;audio-dft;160;160;162;161;159;163;160;162;162;164 16:41:23 INFO - PROCESS | 14362 | |3;audio-fft;75;77;77;112;74;76;74;75;108;74 16:41:23 INFO - PROCESS | 14362 | |4;audio-oscillator;86;85;127;85;86;86;124;86;85;127 16:41:23 INFO - PROCESS | 14362 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;116 16:41:23 INFO - PROCESS | 14362 | |6;imaging-darkroom;145;146;146;146;147;145;146;146;146;146 16:41:23 INFO - PROCESS | 14362 | |7;imaging-desaturate;95;95;95;95;95;96;95;95;95;95 16:41:23 INFO - PROCESS | 14362 | |8;json-parse-financial;51;48;51;50;49;52;50;51;52;50 16:41:23 INFO - PROCESS | 14362 | |9;json-stringify-tinderbox;63;63;63;62;63;62;62;63;62;63 16:41:23 INFO - PROCESS | 14362 | |10;stanford-crypto-aes;79;76;78;77;83;77;82;77;77;78 16:41:23 INFO - PROCESS | 14362 | |11;stanford-crypto-ccm;143;129;131;125;132;122;134;122;140;139 16:41:23 INFO - PROCESS | 14362 | |12;stanford-crypto-pbkdf2;162;162;163;162;164;160;160;160;160;163 16:41:23 INFO - PROCESS | 14362 | |13;stanford-crypto-sha256-iterative;66;64;65;64;64;63;64;63;64;63 16:41:23 INFO - PROCESS | 14362 | __end_tp_report 16:41:23 INFO - PROCESS | 14362 | __start_cc_report 16:41:23 INFO - PROCESS | 14362 | _x_x_mozilla_cycle_collect,1686 16:41:23 INFO - PROCESS | 14362 | __end_cc_report 16:41:23 INFO - PROCESS | 14362 | __startTimestamp1471390883062__endTimestamp 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | ------- Summary: start ------- 16:41:23 INFO - PROCESS | 14362 | Number of tests: 14 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:2.16 (1.9%) stddev-sans-first:1.48 16:41:23 INFO - PROCESS | 14362 | Values: 106.0 111.0 113.0 111.0 108.0 112.0 112.0 110.0 112.0 112.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:133.00 stddev:19.52 (14.7%) stddev-sans-first:20.17 16:41:23 INFO - PROCESS | 14362 | Values: 146.0 119.0 120.0 174.0 119.0 125.0 141.0 119.0 154.0 117.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:1.57 (1.0%) stddev-sans-first:1.59 16:41:23 INFO - PROCESS | 14362 | Values: 160.0 160.0 162.0 161.0 159.0 163.0 160.0 162.0 162.0 164.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#3] audio-fft Cycles:10 Average:82.20 Median:75.00 stddev:14.73 (19.6%) stddev-sans-first:15.39 16:41:23 INFO - PROCESS | 14362 | Values: 75.0 77.0 77.0 112.0 74.0 76.0 74.0 75.0 108.0 74.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:85.50 stddev:19.55 (22.9%) stddev-sans-first:20.27 16:41:23 INFO - PROCESS | 14362 | Values: 86.0 85.0 127.0 85.0 86.0 86.0 124.0 86.0 85.0 127.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:41:23 INFO - PROCESS | 14362 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 16:41:23 INFO - PROCESS | 14362 | Values: 145.0 146.0 146.0 146.0 147.0 145.0 146.0 146.0 146.0 146.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:41:23 INFO - PROCESS | 14362 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:51.00 stddev:1.26 (2.5%) stddev-sans-first:1.32 16:41:23 INFO - PROCESS | 14362 | Values: 51.0 48.0 51.0 50.0 49.0 52.0 50.0 51.0 52.0 50.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 16:41:23 INFO - PROCESS | 14362 | Values: 63.0 63.0 63.0 62.0 63.0 62.0 62.0 63.0 62.0 63.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.00 stddev:2.32 (3.0%) stddev-sans-first:2.45 16:41:23 INFO - PROCESS | 14362 | Values: 79.0 76.0 78.0 77.0 83.0 77.0 82.0 77.0 77.0 78.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#11] stanford-crypto-ccm Cycles:10 Average:131.70 Median:133.00 stddev:7.42 (5.6%) stddev-sans-first:6.65 16:41:23 INFO - PROCESS | 14362 | Values: 143.0 129.0 131.0 125.0 132.0 122.0 134.0 122.0 140.0 139.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.60 Median:162.00 stddev:1.51 (0.9%) stddev-sans-first:1.59 16:41:23 INFO - PROCESS | 14362 | Values: 162.0 162.0 163.0 162.0 164.0 160.0 160.0 160.0 160.0 163.0 16:41:23 INFO - PROCESS | 14362 | 16:41:23 INFO - PROCESS | 14362 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.00 stddev:0.94 (1.5%) stddev-sans-first:0.67 16:41:23 INFO - PROCESS | 14362 | Values: 66.0 64.0 65.0 64.0 64.0 63.0 64.0 63.0 64.0 63.0 16:41:23 INFO - PROCESS | 14362 | -------- Summary: end -------- 16:41:23 INFO - PROCESS | 14362 | 16:41:24 INFO - TEST-INFO | 14362: exit 0 16:41:24 INFO - TEST-OK | kraken | took 60896ms 16:41:24 INFO - SUITE-END | took 1176s 16:41:24 INFO - Completed test suite (00:19:36) 16:41:24 INFO - javascript benchmark 16:41:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3048.3446059097296, 3054.7403470705017, 3074.707902749238, 3078.538219267108, 3093.3503964167526, 5240.240165176047, 5315.309009828727, 5429.864253393662, 5464.945350546498, 5550.333959924809, 5396.973015134919, 5447.645903016301, 5471.50756431921, 5480.821623350978, 5527.032769265383, 4868.075065737498, 4910.852674419755, 4944.233643785219, 4956.950430495703, 5102.821401250957, 5000.7249601271915, 5024.995000999815, 5046.419661738911, 5057.291979122926, 5060.721660308682, 4770.833020844271, 4871.732054736988, 4875.926861097087, 4938.555530002317, 5007.173816320321, 4903.534164254425, 4909.3126962225315, 4911.68074075184, 4914.16459201933, 4971.328870602495, 4931.358923339979, 4967.205247160437, 4972.154733695281, 4986.551210391082, 5002.874928126789, 5215.0, 5361.678299302054, 5431.646942948696, 5446.537044351235, 5463.617546771728, 5802.47777700009, 5868.207791948075, 5868.207791948075, 5874.618149820248, 5881.705914704247, 5893.941060589382, 5908.556858235649, 5920.851978700523, 5932.406759324076, 5939.049752039653, 5235.712035838027, 5359.678419294854, 5441.700706461103, 5443.755031023614, 5509.752061157257, 6018.398160183947, 6023.488003519662, 6031.939680603138, 6034.788782392595, 6035.245594300713, 5788.797392091256, 5790.71046447676, 5792.88414231713, 5794.101914203306, 5795.0, 5526.005319042611, 5734.369219385864, 5791.305043394819, 5798.188253644493, 5867.354590994987, 5748.942510574924, 5757.050086735641, 5778.219940308089, 5803.564732645069, 5837.562182836305, 5760.193572899798, 5802.187693722883, 5810.50610698087, 5812.244408226988, 5824.3302020267365, 4270.12462445198, 4351.891202719894, 4415.51429342772, 4416.293393057095, 4418.491873434532, 3949.1706741584017, 3950.9802450987563, 3952.3676211806546, 3977.343738283209, 3987.1228329767405, 5537.861553461115, 5538.723063846872, 5541.33503979517, 5584.329880414376, 5584.497395234367, 5539.639923404966, 5738.8278351649515, 5788.7684492620665, 5803.100519419497, 5872.236609240771, 5493.587980901369, 5495.203195536705, 5498.092814685532, 5498.202760599691, 5509.862253443696, 4887.657863949489, 4899.289603007615, 4899.314096026559, 4900.460949295574, 4942.579880710108, 5846.912296315559, 6030.0, 6032.276126864805, 6057.969710151421, 6099.603525770811, 5846.035404158266, 5846.970765146147, 5847.795327163529, 5853.795117171049, 5887.58786884931, 6042.667653279112, 6044.214252147192, 6044.546659000681, 6045.697715114315, 6054.33402325735, 5525.419830917695, 5683.033884239607, 5685.573581981451, 5730.713464326684, 5732.283464566929, 5803.506701930383, 5844.766209351578, 5859.179714840011, 5863.912041319469, 5909.261342332208, 5723.427657234243, 5734.569907257056, 5736.0822268438515, 5739.540836733135, 5746.597738158289, 5492.478214569769, 5567.832965011056, 5624.0, 5646.2942132233475, 5701.714914254354, 5674.319081710056, 5678.3186017678145, 5682.9147562785765, 5685.772569097356, 5687.744051517774, 5729.570282228766, 5735.225744524602, 5762.942370576408, 5772.422757724362, 5787.681677507778, 5309.920351194658, 5434.619576629598, 5522.723863806874, 5549.334079910436, 5582.804601839079], "value": 5355.01199766651, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.5439321346316, 592.8547505861064, 598.3567664760384, 611.2267981004028, 637.5250438423375, 33292.83353583232, 46972.530274697245, 48771.0, 48777.04891804328, 48851.0, 46165.0, 46633.30050049252, 46665.76667116668, 46719.532804671944, 46933.765331173294, 8960.372773905812, 9087.273018158532, 9104.317176211778, 9105.908940910605, 9115.361924665276, 8902.243309318716, 8902.821943561124, 8914.73255802327, 8937.374383793138, 9003.5048072356, 8946.373753837233, 8988.505632190227, 8991.415557988743, 8997.370184087116, 9012.143846334588, 8756.69351572695, 8781.077986811357, 8787.209151176361, 8795.516246606434, 8853.335999800041, 8676.34927380449, 8749.212570868589, 8753.168448997336, 8755.78110547235, 8759.0, 43356.349654755206, 43474.782626086984, 43611.563884361065, 43718.56281437177, 43784.56215437836, 3445.6037555680914, 3589.0668426209113, 3665.6334366563397, 3808.5048943637426, 4107.753534787892, 32626.510602340983, 32990.50514242288, 33565.16087097818, 33629.327413451596, 33741.6625833741, 4206.305959516707, 4259.254630439661, 4322.546132656057, 4333.2200203963275, 4454.487733910577, 348500.0, 349759.0, 350684.0, 351019.99999999744, 351744.0, 57148.71425642845, 57499.42500575024, 57680.981165659, 57740.711296443245, 57792.0, 60673.69663151656, 60940.695296523234, 60983.34099953928, 61006.0, 61175.38824611786, 16171.272292746855, 16442.83557164413, 16447.424340148038, 16479.093649849132, 16513.83486165147, 12677.492900284069, 12708.872911270953, 12723.745525089447, 12730.872691273154, 12737.681557961125, 13428.597142085753, 16245.51263462098, 16252.674946501003, 16267.0, 16296.592585185464, 5107.668001579886, 5126.615503837227, 5153.45888681683, 5169.896602067937, 5301.602379821529, 838.7399906029151, 901.2609660078673, 932.47315266874, 939.5114540438933, 1048.8793788714395, 26104.8694756525, 26548.0, 26984.730152698223, 26991.730082699316, 27030.459390812073, 1008.5461542305992, 1022.0239671114068, 1031.9122874555746, 1055.5619417941623, 1072.099436473366, 12833.803882905306, 13549.525766598123, 13705.862941370458, 13844.792328115087, 13878.653033674238, 501587.0, 502156.0, 503576.0, 504853.0, 505534.0, 59946.40053599495, 59966.10050849241, 60121.398786012454, 60508.092378614354, 60676.393236067954, 1238.5355491690616, 1325.1651459580596, 1341.3896677011935, 1395.4278745714005, 1396.5391420831102, 25274.0, 26497.735022649525, 26986.0, 27129.0, 27359.86320068387, 42394.0, 42480.36279455749, 42730.78634606807, 43486.34770478508, 44991.77504112459, 423002.0, 423395.0, 424569.0, 425407.0, 425914.0, 35985.82007089948, 40386.0, 40973.728127591865, 41572.0, 44072.338914916945, 38529.22941541098, 45338.0, 47655.76172119117, 48491.5150848487, 48749.268760967905, 40254.59745402508, 40660.66456376495, 41732.58267417287, 42995.78502107469, 43009.784951075046, 40522.0, 40684.38973415342, 42074.36888446737, 42119.36820947627, 43433.565664342954, 37635.24729505449, 37836.81081594574, 38729.0, 39567.2086558273, 39595.0, 43037.56962430335, 44287.778561106985, 44733.77633111813, 45198.32202516899, 45305.546944530135, 30430.543541846448, 30452.238694032825, 30742.0, 30745.231369215948, 30941.845290773403], "value": 19719.889374169314, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.5145219927291, 394.26076107298803, 398.34869987570335, 403.61253196930943, 426.5457287988292, 13170.0, 18537.258509659616, 19295.421137365865, 20317.898410507943, 20368.490787730316, 15369.462068827594, 18194.909025454854, 18726.719099213522, 18819.247230110814, 18953.810461895377, 5636.859078523049, 5816.214811000525, 5819.428754236354, 5820.068788993762, 5821.359650438449, 5591.888162236753, 5743.0, 5749.482546570808, 5812.970935145318, 5815.040518314473, 5756.453136951983, 5763.308402991647, 5764.193012978166, 5764.510016648569, 5784.421557844224, 5575.303087114111, 5640.012997725382, 5712.0, 5785.305763308409, 5787.768489260425, 5145.588352931794, 5147.3051138096625, 5149.433562308143, 5155.61332900034, 5166.250893620441, 22928.77071229283, 23043.53912921749, 23496.765032349627, 23876.761232387627, 23962.880185599133, 2513.8240323177383, 2531.7594828491265, 2596.6364708940764, 2971.7176868197485, 3012.4276387486525, 14246.216458094801, 14329.64175895611, 14890.0, 14955.626109347244, 14960.401583936738, 1886.141805478504, 1901.9429417117508, 1914.8276655101108, 1957.2171131547352, 1981.960360792776, 81246.0, 81834.0, 82770.0, 83658.58170709168, 84886.0, 10325.63860264887, 10419.531121099417, 10763.623273185402, 10783.191260655325, 11201.215914886043, 15224.46714364992, 15241.923790381199, 15250.618734531503, 15270.770938435933, 15283.0, 7998.200179982072, 8191.262786349257, 8249.8350032999, 8453.661853525913, 8518.70184543538, 7877.4091943104495, 7957.3634109271115, 7971.880421793678, 8175.0, 8211.17888211174, 3971.860984865516, 5846.619969702041, 6204.689765511706, 6521.543491955517, 6524.151860258136, 2004.829589484881, 2010.4672261850621, 2025.3215172917237, 2035.450428384328, 2075.450005748478, 782.6556315221285, 808.3613944983515, 880.3309484791605, 912.6942474271061, 939.492673956056, 15993.28030238642, 16192.0, 16276.186190690418, 16818.159092045593, 17681.11594420274, 877.6971944679076, 883.88067610873, 896.9775755606162, 914.1726737302752, 928.2898582584328, 7553.5845528495365, 9609.0, 9744.31789774723, 9771.706848794547, 9798.951005245071, 490648.0, 490705.0, 495435.0, 501817.0, 502602.0, 91249.0, 91581.5420922891, 92610.53694731618, 94041.0, 94270.0, 846.2637505370244, 855.170484629908, 856.2678909532385, 861.4701958295603, 864.6627815152222, 14858.0, 15128.470503532324, 15188.696226075195, 15335.76996345078, 15770.811947374807, 43044.78477607592, 43291.0, 44633.55366446294, 44634.0, 45358.0, 56287.43712562822, 57083.429165707814, 58895.41104588899, 60138.699306503186, 60402.69798650979, 8070.87893681607, 8305.252527272574, 8356.749297521084, 8683.480390931689, 8833.734987950373, 7194.892076618959, 12032.157748957488, 12070.45682944252, 12484.188527745668, 12885.549005784753, 10518.894811051792, 10755.89244107549, 10886.40124793144, 10953.288036277616, 11367.090632749525, 11122.61070862516, 11260.380679062402, 11560.531840448, 11927.0458363329, 12047.397630118283, 10806.0, 10826.079783218514, 10877.728056798644, 11105.833412498658, 11217.2147949646, 8400.243978042006, 8422.0, 8445.619947102272, 8623.353248506262, 8652.610632521677, 10008.799824003334, 10742.462876855969, 10954.671359859218, 11052.171087168334, 11356.886431135583], "value": 9096.318855626861, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.167095590991, 1480.6890552983873, 1648.8021437427503, 1751.5095773183514, 1799.1723807048766, 8010.91989080109, 8647.308215342773, 8734.825303493926, 9277.165055145044, 9297.953510232448, 8756.605952732127, 9122.407043542165, 9126.49804260767, 9128.224100951427, 9135.0, 2432.6959130108735, 2455.1161581830524, 2459.434330104077, 2463.7289898111285, 2467.173496878539, 2097.3393381084984, 2320.6170981787986, 2364.6689463475127, 2382.5949588569983, 2386.904523819045, 2415.903363865443, 2428.781409673129, 2429.6962879640046, 2443.6945381827272, 2444.083468699238, 2371.8932648030795, 2398.6042303019894, 2428.514297140579, 2430.4409985703387, 2431.3070774829266, 2367.2661474942824, 2378.3578433822945, 2388.0, 2388.581998150317, 2390.470099136554, 8968.730938071869, 9183.265338772882, 9190.678326258549, 9195.356325057248, 9206.17144457002, 5230.63385563007, 5323.467653234684, 5329.866753331198, 5363.597730170214, 5367.597430192712, 3307.832634120612, 3376.4766461198556, 3416.9145771355916, 3434.9484757728655, 3452.223249768807, 1185.407296351824, 1237.9442925068392, 1246.731952630189, 1255.0900597067146, 1276.2916581297377, 12281.201721888048, 12348.073894457952, 12367.690807729789, 12429.502819887193, 12433.254004759743, 4691.437027556715, 4797.7601119943865, 4802.577615803636, 4880.365552478155, 4901.877453063702, 10366.118879895294, 10397.896021039844, 10430.947845260725, 10439.686809395731, 10449.425281609585, 5304.0, 5330.840074797762, 5332.733363331896, 5333.0, 5333.866653333697, 4852.1508735971765, 4871.464138944713, 4909.828156014522, 4909.901801963941, 4920.803167873317, 4000.6599439047427, 4070.1452694933805, 4200.684948628804, 4201.33215408218, 4262.87211383659, 1220.6643173127284, 1248.0888951065592, 1251.9812202816966, 1286.9935650321875, 1304.9608511744664, 890.3500444675424, 907.5643691028212, 910.1581037540249, 916.6287653500343, 921.1525396635112, 5490.972545137249, 5576.379101822137, 5612.214289999403, 5650.434956504317, 5708.828735137952, 936.6300311376968, 942.1661829280998, 949.6154057606681, 951.2152474208806, 981.3572110267786, 3747.269282489888, 3892.9416058759143, 3904.3557812960544, 3915.2169566086895, 3917.4515567820526, 11021.283616564897, 11448.885511144781, 11499.942500287445, 11508.309501429942, 11547.942260288644, 11399.316041037564, 11409.60066397672, 11410.657680269605, 11411.0, 11413.029892459233, 7908.536920669694, 8183.3453323732765, 8195.83608327842, 8205.302549283379, 8310.376721745894, 5574.9721251393485, 5810.82567522975, 5863.0, 5865.882682346414, 6074.240719910012, 9583.664571739955, 9781.755456113655, 10050.09549140581, 10129.189664826648, 10172.440515771703, 9933.602655893972, 10234.590616375262, 10260.538275777757, 10323.896761032294, 10375.014373634493, 7963.840723185388, 8128.715494957648, 8213.178682131738, 8405.915940840514, 8433.915660843313, 8523.0, 8571.828563428571, 8586.184312490303, 8588.0, 8588.527630980356, 7361.0, 7654.693812247448, 7665.6933722650465, 7674.117476490275, 7896.644650990834, 7281.381082608038, 7510.662020208994, 7595.164531901597, 7654.387648988179, 7656.310932016146, 6519.934800652122, 6606.108175396452, 6627.469802415699, 6630.0, 6631.0, 4903.289023091703, 4962.454130045764, 5001.924971125509, 5005.599552035901, 5104.515071068243, 4819.469858315652, 4908.435529914105, 5056.393232812086, 5096.872578185575, 5184.248283998874], "value": 4688.089381577933, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.1681744282308, 329.1606403670641, 330.301412512536, 334.94138525757995, 343.63231342463575, 5149.742512874355, 5179.430262671112, 5185.637005409628, 5187.766550505232, 5194.168932970725, 4881.682690625107, 4991.500849915006, 5009.524095210958, 5027.622928280384, 5188.72876145344, 3896.688264938798, 3981.5819338969354, 4023.2355852387955, 4105.466289382391, 4135.441715368432, 4085.12169883475, 4091.3249313863175, 4103.733257338278, 4105.568915263899, 4142.7514349139, 3868.4719535783347, 4043.332850079748, 4057.5536690963972, 4076.266272071041, 4103.343465045593, 4016.518017837849, 4028.3756017817286, 4043.7978101095123, 4051.129007263423, 4054.4931883514414, 3554.862444017915, 3562.5012498250267, 3563.982180089083, 3579.5167652367118, 3598.2443686825536, 4810.446798618169, 5056.570191533724, 5073.644844860862, 5077.2637967494875, 5088.033273677989, 4604.539546045402, 4645.907081858378, 4648.070385922795, 4650.302454631798, 4656.557627025427, 5410.458954104597, 5425.647332923348, 5430.0, 5431.728413579344, 5450.454954504517, 3863.459115723801, 3912.6087391260644, 3913.62820532049, 3940.5074365704286, 3955.011247188203, 5557.444255574451, 5572.693501857396, 5581.972090139564, 5590.301212348497, 5594.86012849682, 3969.960300396959, 3972.1658451724884, 3973.980130099389, 3978.0, 3983.132144855699, 4010.8997275068355, 4012.9333393302472, 4022.4971878515184, 4031.637152656275, 4033.8184781684895, 4390.473143222834, 4628.652851036186, 4635.281531362644, 4649.907271791127, 4658.930116048262, 5409.43200963901, 5426.34883813937, 5433.619646624698, 5437.184422336682, 5444.265024221759, 1328.4752522753458, 1403.3404299978993, 1405.9648508787157, 1408.7393832141086, 1427.4647007372234, 950.8811398575178, 968.8207681578931, 976.7204961500457, 985.0, 986.3440811860032, 5243.737813109329, 5244.7902083916215, 5250.188684903702, 5252.185911183773, 5291.920621190685, 5524.917126243109, 5537.252470916455, 5563.666180029211, 5583.4137415571595, 5597.132444471114, 5074.847754567369, 5244.737763111829, 5258.868528286747, 5292.497212764782, 5314.661548918463, 2926.5024945758846, 2941.279386550309, 2948.837813920255, 2953.61602991614, 2964.925876853096, 5510.228568000483, 5760.5022694099835, 5760.942390576124, 5785.161151633074, 5803.622764520293, 5713.628614140068, 5715.914261286084, 5717.313922329264, 5718.285214348207, 5722.513586345124, 5823.941760582425, 5828.591998560145, 5830.125481177773, 5864.325602555676, 5866.560007999332, 5491.588130890279, 5511.338639363302, 5616.747246373841, 5616.943830561642, 5635.520980716685, 5439.347278326468, 5520.564653190119, 5541.420695101877, 5542.307211598551, 5559.33288005442, 5581.972090139686, 5710.743016564347, 5802.94197058024, 5822.214001109796, 5831.212786273799, 3964.6035396460125, 4017.5379831318605, 4020.4170395293104, 4049.7772622506045, 4052.3313653248065, 5595.244641973444, 5602.663840169603, 5612.306093075428, 5613.719314034363, 5635.718214089197, 5376.4623537647485, 5405.621606487508, 5460.972695136499, 5488.9725551371985, 5490.972545137249, 5408.702521361363, 5413.404525502113, 5427.647202931955, 5432.158015507603, 5433.0, 5202.167653175474, 5322.54758345545, 5322.973385133049, 5419.539339156216, 5424.728763561727, 5146.588272938081, 5340.866478338073, 5378.327709036371, 5401.8939110011115, 5455.18172274162, 5327.121025030827, 5328.227407026037, 5362.436944120811, 5363.061464243664, 5363.41002489718], "value": 4159.7634331583795, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.9305615966481, 736.8636802191592, 773.912090757876, 778.353966208047, 859.2996707683237, 14271.571852844405, 15342.463013794506, 15349.079055256692, 15405.3837846486, 15487.84512154878, 14133.293335333243, 15855.920720396382, 16116.355345786184, 16136.193190340495, 16192.75710864338, 792.9088154862206, 818.4925346285312, 820.6553247636007, 820.9384296177781, 821.3347188777079, 666.5567397680561, 797.6211299632657, 815.8368326334727, 816.677412422093, 818.7789296889836, 864.3776480933718, 865.3596338709341, 865.9523726195057, 866.030046348091, 866.2896424931558, 857.2584714222215, 857.4083882121379, 857.5197889182047, 857.7726902370877, 857.9099194584603, 510.4793111026769, 611.5627326461602, 620.2866703291206, 627.0406278394064, 628.6825153297556, 14606.926965365212, 14612.853871461255, 14618.342174602169, 14626.04930679513, 14631.780523292158, 14664.926675366662, 14947.70104597902, 15182.446911652805, 15361.923190383977, 15649.530514084596, 12719.236845789283, 12925.418356173996, 12967.193776224869, 13364.0, 13432.731345373135, 4549.522300158502, 4591.793369298356, 4594.2419500782335, 4611.723296602181, 4629.1436084324505, 83345.16654833435, 83674.58162709148, 84436.57781711113, 84472.35402052884, 84629.57685211596, 11627.941860290644, 11681.707957300965, 11700.0, 11708.356040417859, 12038.39808009596, 22937.88531057334, 22966.65550016751, 22999.885000575225, 23016.194433194756, 23092.4226894329, 8183.386246031571, 8285.95857020711, 8340.791480213044, 8479.533625650525, 8728.91271087281, 4696.694714843593, 4913.65604407688, 4914.5822605078965, 4921.87695307613, 4928.876778080505, 3347.0126312737316, 3450.258194488197, 3451.2062225688233, 3456.1532424556144, 3461.8961431157104, 571.6255852416674, 579.958973642313, 583.9051777916407, 585.7627660797299, 596.385722705614, 671.3378520172448, 677.3700458573476, 715.47770127807, 717.3042149115349, 732.8644200822865, 9542.42745435276, 9793.804123917482, 9810.751535721036, 9905.658041351988, 10188.545851941475, 567.0926517571852, 633.8288662061218, 647.7829926974422, 651.680676468527, 656.0487293424553, 4698.271767875985, 4868.756562171877, 4906.803727850846, 4912.140375434285, 4976.328195693607, 76650.0, 87191.56404218065, 131871.0, 134428.32785836008, 135360.0, 88569.0, 91272.0, 92076.0, 112272.0, 113137.43431282791, 76901.0, 77650.61174694203, 88262.55868720615, 89555.0, 90105.0, 9847.753806154617, 9983.351082179635, 10388.48057597102, 10429.426381549089, 10646.20153488476, 47945.28082078702, 52355.73822130865, 53912.0, 54283.72858135684, 55664.16503752366, 71572.64213679107, 76173.0, 76761.61619191869, 78337.60831195807, 79618.6019069901, 11845.289282643069, 12239.32683702405, 12314.630561083182, 12675.55635552788, 12742.936285318514, 14913.552593422215, 14981.0, 15128.0, 15568.455104071303, 15880.126593037494, 13204.60386188416, 13355.866441335851, 13524.0, 14264.144151350953, 14286.571402857931, 12414.937925310316, 12491.750164996467, 13982.930085349508, 14196.148231106166, 14983.550493485212, 13963.441462341392, 14176.432942682177, 14411.351489182802, 14634.780478293047, 14718.116912985255, 10842.0, 11770.470328835052, 11809.940950295193, 11826.34955077479, 11899.940500297444, 12225.633231003085, 12419.93790031044, 12440.883988855763, 12624.55814046504, 12673.80989285143], "value": 7698.778142250381, "unit": "score"}], "name": "dromaeo_css", "value": 7241.750647582317, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 111.0, 113.0, 111.0, 108.0, 112.0, 112.0, 110.0, 112.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 119.0, 120.0, 174.0, 119.0, 125.0, 141.0, 119.0, 154.0, 117.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 161.0, 159.0, 163.0, 160.0, 162.0, 162.0, 164.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 112.0, 74.0, 76.0, 74.0, 75.0, 108.0, 74.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 127.0, 85.0, 86.0, 86.0, 124.0, 86.0, 85.0, 127.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 51.0, 50.0, 49.0, 52.0, 50.0, 51.0, 52.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 62.0, 63.0, 62.0, 62.0, 63.0, 62.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 78.0, 77.0, 83.0, 77.0, 82.0, 77.0, 77.0, 78.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 129.0, 131.0, 125.0, 132.0, 122.0, 134.0, 122.0, 140.0, 139.0], "value": 131.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 162.0, 164.0, 160.0, 160.0, 160.0, 160.0, 163.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 64.0, 63.0, 64.0, 63.0, 64.0, 63.0], "value": 64.0, "unit": "score"}], "name": "kraken", "value": 1491.9, "alertThreshold": 2.0}]} 16:41:24 INFO - Return code: 0 16:41:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:41:24 INFO - # TBPL SUCCESS # 16:41:24 INFO - Running post-action listener: _resource_record_post_action 16:41:24 INFO - [mozharness: 2016-08-16 23:41:24.393136Z] Finished run-tests step (success) 16:41:24 INFO - Running post-run listener: _resource_record_post_run 16:41:24 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 425914368; Read time: 16; Write time: 230460 16:41:24 INFO - TinderboxPrint: CPU usage
13.0% 16:41:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:41:24 INFO - TinderboxPrint: I/O write bytes / time
425,914,368 / 230,460 16:41:24 INFO - TinderboxPrint: CPU idle
8,293.5 (87.0%) 16:41:24 INFO - TinderboxPrint: CPU user
1,192.7 (12.5%) 16:41:24 INFO - TinderboxPrint: Swap in / out
0 / 0 16:41:24 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 134258688; Read time: 0; Write time: 58852 16:41:24 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291041280; Read time: 16; Write time: 171608 16:41:25 INFO - Running post-run listener: _upload_blobber_files 16:41:25 INFO - Blob upload gear active. 16:41:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:41:25 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 16:41:25 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'] 16:41:25 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 16:41:25 INFO - (blobuploader) - INFO - Open directory for files ... 16:41:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:41:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:41:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:26 INFO - (blobuploader) - INFO - Done attempting. 16:41:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:41:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:41:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:26 INFO - (blobuploader) - INFO - Done attempting. 16:41:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:41:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:41:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:31 INFO - (blobuploader) - INFO - Done attempting. 16:41:31 INFO - (blobuploader) - INFO - Iteration through files over. 16:41:31 INFO - Return code: 0 16:41:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:41:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:41:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/bde4b1da0380e5e85f649d6a2fc7648ca1558dc9d6c819074a380c4173f9c36fa129beb57b55d10950936734ea48021429c7840c8a4107ef2d724b0fce5eec6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7e06d355db02dc5d43c8f7d62f5d1235fb8cd4fbad5f0e7dfb008a01b35dd9d4cfe0d9ffdf8cd6c15ebf3f9ed1524c48127852b687e50e30d11e82634b5a75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3b5cf35c121174c546902da876124ee809b3fefe85b264ae736063f93f286e3bcdabe13bd25fa4048019853d909a4f38f6fdbe59c717c9a3f5e2ad244e94f773"} 16:41:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:41:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:41:31 INFO - Contents: 16:41:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/bde4b1da0380e5e85f649d6a2fc7648ca1558dc9d6c819074a380c4173f9c36fa129beb57b55d10950936734ea48021429c7840c8a4107ef2d724b0fce5eec6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7e06d355db02dc5d43c8f7d62f5d1235fb8cd4fbad5f0e7dfb008a01b35dd9d4cfe0d9ffdf8cd6c15ebf3f9ed1524c48127852b687e50e30d11e82634b5a75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3b5cf35c121174c546902da876124ee809b3fefe85b264ae736063f93f286e3bcdabe13bd25fa4048019853d909a4f38f6fdbe59c717c9a3f5e2ad244e94f773"} 16:41:31 INFO - Running post-run listener: copy_logs_to_upload_dir 16:41:31 INFO - Copying logs to upload dir... 16:41:31 INFO - mkdir: /builds/slave/test/build/upload/logs 16:41:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.737037 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-16 16:41:31.255380) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 16:41:31.258541) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083 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/bde4b1da0380e5e85f649d6a2fc7648ca1558dc9d6c819074a380c4173f9c36fa129beb57b55d10950936734ea48021429c7840c8a4107ef2d724b0fce5eec6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7e06d355db02dc5d43c8f7d62f5d1235fb8cd4fbad5f0e7dfb008a01b35dd9d4cfe0d9ffdf8cd6c15ebf3f9ed1524c48127852b687e50e30d11e82634b5a75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3b5cf35c121174c546902da876124ee809b3fefe85b264ae736063f93f286e3bcdabe13bd25fa4048019853d909a4f38f6fdbe59c717c9a3f5e2ad244e94f773"} build_url:https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011331 build_url: 'https://queue.taskcluster.net/v1/task/MN49L8IwSBeDjdlPDnJ4wQ/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/bde4b1da0380e5e85f649d6a2fc7648ca1558dc9d6c819074a380c4173f9c36fa129beb57b55d10950936734ea48021429c7840c8a4107ef2d724b0fce5eec6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c7e06d355db02dc5d43c8f7d62f5d1235fb8cd4fbad5f0e7dfb008a01b35dd9d4cfe0d9ffdf8cd6c15ebf3f9ed1524c48127852b687e50e30d11e82634b5a75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3b5cf35c121174c546902da876124ee809b3fefe85b264ae736063f93f286e3bcdabe13bd25fa4048019853d909a4f38f6fdbe59c717c9a3f5e2ad244e94f773"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 16:41:31.286431) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:41:31.286762) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1471388086.361962-1139177083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:41:31.368183) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 16:41:31.368508) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 16:41:31.368802) ========= ========= Total master_lag: 0.20 =========