builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1478171830.54 results: success (0) buildid: 20161103012423 builduid: f2815fbacd9442fd90fd5db52af5d4ab revision: 64a63ba0f3ef0a36968df13fbadb87dfcf167dd8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:10.537800) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:10.538466) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:10.538853) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:10.560563) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:10.560968) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-03 04:17:10-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.7M=0s 2016-11-03 04:17:11 (42.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119022 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:10.694962) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:10.695492) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017771 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:10.746097) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:10.746479) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 64a63ba0f3ef0a36968df13fbadb87dfcf167dd8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 64a63ba0f3ef0a36968df13fbadb87dfcf167dd8 --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=7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-03 04:17:11,174 truncating revision to first 12 chars 2016-11-03 04:17:11,174 Setting DEBUG logging. 2016-11-03 04:17:11,174 attempt 1/10 2016-11-03 04:17:11,174 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/64a63ba0f3ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-03 04:17:11,477 unpacking tar archive at: mozilla-aurora-64a63ba0f3ef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.606334 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:11.366170) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:11.366488) ========= script_repo_revision: 64a63ba0f3ef0a36968df13fbadb87dfcf167dd8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:11.366888) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:11.367161) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 04:17:11.376163) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-11-03 04:17:11.376453) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:17:11 INFO - MultiFileLogger online at 20161103 04:17:11 in /builds/slave/test 04:17:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:17:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:17:11 INFO - {'append_to_log': False, 04:17:11 INFO - 'base_work_dir': '/builds/slave/test', 04:17:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:17:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:17:11 INFO - 'branch': 'Mozilla-Aurora', 04:17:11 INFO - 'buildbot_json_path': 'buildprops.json', 04:17:11 INFO - 'config_files': ('talos/linux_config.py',), 04:17:11 INFO - 'default_actions': ('clobber', 04:17:11 INFO - 'read-buildbot-config', 04:17:11 INFO - 'download-and-extract', 04:17:11 INFO - 'populate-webroot', 04:17:11 INFO - 'create-virtualenv', 04:17:11 INFO - 'install', 04:17:11 INFO - 'run-tests'), 04:17:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:17:11 INFO - 'download_minidump_stackwalk': True, 04:17:11 INFO - 'download_symbols': 'ondemand', 04:17:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:17:11 INFO - 'tooltool.py': '/tools/tooltool.py', 04:17:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:17:11 INFO - '/tools/misc-python/virtualenv.py')}, 04:17:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:17:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:17:11 INFO - 'installer_path': 'installer.exe', 04:17:11 INFO - 'log_level': 'info', 04:17:11 INFO - 'log_name': 'talos', 04:17:11 INFO - 'log_to_console': True, 04:17:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:17:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:17:11 INFO - 'opt_config_files': (), 04:17:11 INFO - 'pip_index': False, 04:17:11 INFO - 'sps_profile': False, 04:17:11 INFO - 'sps_profile_interval': 0, 04:17:11 INFO - 'suite': 'dromaeojs', 04:17:11 INFO - 'system_bits': '32', 04:17:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:17:11 INFO - 'title': 'talos-linux64-ix-005', 04:17:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:17:11 INFO - 'use_talos_json': True, 04:17:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:17:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:17:11 INFO - 'work_dir': 'build'} 04:17:11 INFO - [mozharness: 2016-11-03 11:17:11.901606Z] Running clobber step. 04:17:11 INFO - Running pre-action listener: _resource_record_pre_action 04:17:11 INFO - Running main action method: clobber 04:17:11 INFO - rmtree: /builds/slave/test/build 04:17:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:17:12 INFO - Running post-action listener: _resource_record_post_action 04:17:12 INFO - [mozharness: 2016-11-03 11:17:12.367755Z] Finished clobber step (success) 04:17:12 INFO - [mozharness: 2016-11-03 11:17:12.367856Z] Running read-buildbot-config step. 04:17:12 INFO - Running pre-action listener: _resource_record_pre_action 04:17:12 INFO - Running main action method: read_buildbot_config 04:17:12 INFO - Using buildbot properties: 04:17:12 INFO - { 04:17:12 INFO - "project": "", 04:17:12 INFO - "product": "firefox", 04:17:12 INFO - "script_repo_revision": "production", 04:17:12 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:17:12 INFO - "repository": "", 04:17:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:17:12 INFO - "buildid": "20161103012423", 04:17:12 INFO - "pgo_build": "True", 04:17:12 INFO - "basedir": "/builds/slave/test", 04:17:12 INFO - "buildnumber": 850, 04:17:12 INFO - "slavename": "talos-linux64-ix-005", 04:17:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:17:12 INFO - "platform": "ubuntu64_hw", 04:17:12 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:17:12 INFO - "branch": "mozilla-aurora", 04:17:12 INFO - "repo_path": "releases/mozilla-aurora", 04:17:12 INFO - "revision": "64a63ba0f3ef0a36968df13fbadb87dfcf167dd8", 04:17:12 INFO - "stage_platform": "linux64-pgo", 04:17:12 INFO - "builduid": "f2815fbacd9442fd90fd5db52af5d4ab", 04:17:12 INFO - "slavebuilddir": "test" 04:17:12 INFO - } 04:17:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 04:17:12 INFO - Running post-action listener: _resource_record_post_action 04:17:12 INFO - [mozharness: 2016-11-03 11:17:12.370321Z] Finished read-buildbot-config step (success) 04:17:12 INFO - [mozharness: 2016-11-03 11:17:12.370411Z] Running download-and-extract step. 04:17:12 INFO - Running pre-action listener: _resource_record_pre_action 04:17:12 INFO - Running main action method: download_and_extract 04:17:12 INFO - mkdir: /builds/slave/test/build/tests 04:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:12 INFO - trying https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 04:17:12 INFO - Downloading https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 04:17:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:17:13 INFO - Downloaded 1361 bytes. 04:17:13 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 04:17:13 INFO - Using the following test package requirements: 04:17:13 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 04:17:13 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:17:13 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:17:13 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:13 INFO - u'jsshell-linux-x86_64.zip'], 04:17:13 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:17:13 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 04:17:13 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:17:13 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:17:13 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:17:13 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:13 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:17:13 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:17:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 04:17:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:17:13 INFO - Fetch https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 04:17:13 INFO - Expected file size: 21895274 04:17:17 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:17:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 04:17:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:17:17 INFO - Fetch https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 04:17:18 INFO - Expected file size: 21895274 04:17:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 04:17:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:17:23 INFO - Fetch https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 04:17:23 INFO - Expected file size: 14414089 04:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:24 INFO - trying https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:17:24 INFO - Downloading https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:17:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:17:28 INFO - Downloaded 63985425 bytes. 04:17:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:17:28 INFO - mkdir: /builds/slave/test/properties 04:17:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:17:28 INFO - Writing to file /builds/slave/test/properties/build_url 04:17:28 INFO - Contents: 04:17:28 INFO - build_url:https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:17:28 INFO - Running post-action listener: _resource_record_post_action 04:17:28 INFO - Running post-action listener: set_extra_try_arguments 04:17:28 INFO - [mozharness: 2016-11-03 11:17:28.923284Z] Finished download-and-extract step (success) 04:17:28 INFO - [mozharness: 2016-11-03 11:17:28.923513Z] Running populate-webroot step. 04:17:28 INFO - Running pre-action listener: _resource_record_pre_action 04:17:28 INFO - Running main action method: populate_webroot 04:17:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:17:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:17:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:17:28 INFO - '--tppagecycles', 04:17:28 INFO - '7'], 04:17:28 INFO - 'tests': ['tsvgm']}}, 04:17:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:17:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:17:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:17:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:17:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:28 INFO - 'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:28 INFO - 'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['damp', 'tps']}, 04:17:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:28 INFO - 'tests': ['damp', 'tps']}, 04:17:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['dromaeo_dom']}, 04:17:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:17:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['basic_compositor_video']}, 04:17:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:17:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['a11yr', 04:17:28 INFO - 'ts_paint', 04:17:28 INFO - 'tpaint', 04:17:28 INFO - 'sessionrestore', 04:17:28 INFO - 'sessionrestore_no_auto_restore', 04:17:28 INFO - 'tabpaint']}, 04:17:28 INFO - 'other-e10s': {'tests': ['a11yr', 04:17:28 INFO - 'ts_paint', 04:17:28 INFO - 'tpaint', 04:17:28 INFO - 'sessionrestore', 04:17:28 INFO - 'sessionrestore_no_auto_restore', 04:17:28 INFO - 'tabpaint']}, 04:17:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:17:28 INFO - 'ts_paint', 04:17:28 INFO - 'tpaint', 04:17:28 INFO - 'sessionrestore', 04:17:28 INFO - 'sessionrestore_no_auto_restore', 04:17:28 INFO - 'tabpaint']}, 04:17:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:17:28 INFO - 'ts_paint', 04:17:28 INFO - 'tpaint', 04:17:28 INFO - 'sessionrestore', 04:17:28 INFO - 'sessionrestore_no_auto_restore', 04:17:28 INFO - 'tabpaint']}, 04:17:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['a11yr', 04:17:28 INFO - 'ts_paint', 04:17:28 INFO - 'tpaint', 04:17:28 INFO - 'sessionrestore', 04:17:28 INFO - 'sessionrestore_no_auto_restore', 04:17:28 INFO - 'tabpaint']}, 04:17:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['a11yr', 04:17:28 INFO - 'ts_paint', 04:17:28 INFO - 'tpaint', 04:17:28 INFO - 'sessionrestore', 04:17:28 INFO - 'sessionrestore_no_auto_restore', 04:17:28 INFO - 'tabpaint']}, 04:17:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['tsvgx', 04:17:28 INFO - 'tsvgr_opacity', 04:17:28 INFO - 'tart', 04:17:28 INFO - 'tscrollx', 04:17:28 INFO - 'cart']}, 04:17:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:17:28 INFO - 'tsvgr_opacity', 04:17:28 INFO - 'tart', 04:17:28 INFO - 'tscrollx', 04:17:28 INFO - 'cart']}, 04:17:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:28 INFO - 'talos_options': ['--disable-e10s'], 04:17:28 INFO - 'tests': ['tp5o']}, 04:17:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:28 INFO - 'tests': ['tp5o']}, 04:17:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:28 INFO - 'talos_options': ['--disable-e10s', 04:17:28 INFO - '--xperf_path', 04:17:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:28 INFO - 'tests': ['tp5n']}, 04:17:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:28 INFO - 'talos_options': ['--xperf_path', 04:17:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:28 INFO - 'tests': ['tp5n']}}, 04:17:28 INFO - 'talos.zip': {'path': '', 04:17:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:17:28 INFO - Running post-action listener: _resource_record_post_action 04:17:28 INFO - [mozharness: 2016-11-03 11:17:28.939784Z] Finished populate-webroot step (success) 04:17:28 INFO - [mozharness: 2016-11-03 11:17:28.939872Z] Running create-virtualenv step. 04:17:28 INFO - Running pre-action listener: _resource_record_pre_action 04:17:28 INFO - Running main action method: create_virtualenv 04:17:28 INFO - Creating virtualenv /builds/slave/test/build/venv 04:17:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:17:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:17:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:17:29 INFO - Using real prefix '/usr' 04:17:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:17:29 INFO - Installing distribute.............................................................................................................................................................................................done. 04:17:31 INFO - Installing pip.................done. 04:17:31 INFO - Return code: 0 04:17:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:31 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 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:17:31 INFO - Using env: {'DISPLAY': ':0', 04:17:31 INFO - 'HOME': '/home/cltbld', 04:17:31 INFO - 'LANG': 'en_US.UTF-8', 04:17:31 INFO - 'LANGUAGE': 'en_US:en', 04:17:31 INFO - 'LOGNAME': 'cltbld', 04:17:31 INFO - 'MAIL': '/var/mail/cltbld', 04:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:31 INFO - 'MOZ_NO_REMOTE': '1', 04:17:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:31 INFO - 'NO_EM_RESTART': '1', 04:17:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:31 INFO - 'PWD': '/builds/slave/test', 04:17:31 INFO - 'SHELL': '/bin/bash', 04:17:31 INFO - 'SHLVL': '1', 04:17:31 INFO - 'TERM': 'linux', 04:17:31 INFO - 'TMOUT': '86400', 04:17:31 INFO - 'USER': 'cltbld', 04:17:31 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:31 INFO - '_': '/tools/buildbot/bin/python'} 04:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:17:31 INFO - Cleaning up... 04:17:31 INFO - Return code: 0 04:17:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:31 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 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:17:31 INFO - Using env: {'DISPLAY': ':0', 04:17:31 INFO - 'HOME': '/home/cltbld', 04:17:31 INFO - 'LANG': 'en_US.UTF-8', 04:17:31 INFO - 'LANGUAGE': 'en_US:en', 04:17:31 INFO - 'LOGNAME': 'cltbld', 04:17:31 INFO - 'MAIL': '/var/mail/cltbld', 04:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:31 INFO - 'MOZ_NO_REMOTE': '1', 04:17:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:31 INFO - 'NO_EM_RESTART': '1', 04:17:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:31 INFO - 'PWD': '/builds/slave/test', 04:17:31 INFO - 'SHELL': '/bin/bash', 04:17:31 INFO - 'SHLVL': '1', 04:17:31 INFO - 'TERM': 'linux', 04:17:31 INFO - 'TMOUT': '86400', 04:17:31 INFO - 'USER': 'cltbld', 04:17:31 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:31 INFO - '_': '/tools/buildbot/bin/python'} 04:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:31 INFO - Downloading/unpacking psutil>=3.1.1 04:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:17:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:33 INFO - Installing collected packages: psutil 04:17:33 INFO - Running setup.py install for psutil 04:17:34 INFO - building 'psutil._psutil_linux' extension 04:17:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:17:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:17:34 INFO - building 'psutil._psutil_posix' extension 04:17:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:17:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:17:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:34 INFO - Successfully installed psutil 04:17:34 INFO - Cleaning up... 04:17:34 INFO - Return code: 0 04:17:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:17:34 INFO - Using env: {'DISPLAY': ':0', 04:17:34 INFO - 'HOME': '/home/cltbld', 04:17:34 INFO - 'LANG': 'en_US.UTF-8', 04:17:34 INFO - 'LANGUAGE': 'en_US:en', 04:17:34 INFO - 'LOGNAME': 'cltbld', 04:17:34 INFO - 'MAIL': '/var/mail/cltbld', 04:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:34 INFO - 'MOZ_NO_REMOTE': '1', 04:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:34 INFO - 'NO_EM_RESTART': '1', 04:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:34 INFO - 'PWD': '/builds/slave/test', 04:17:34 INFO - 'SHELL': '/bin/bash', 04:17:34 INFO - 'SHLVL': '1', 04:17:34 INFO - 'TERM': 'linux', 04:17:34 INFO - 'TMOUT': '86400', 04:17:34 INFO - 'USER': 'cltbld', 04:17:34 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:34 INFO - '_': '/tools/buildbot/bin/python'} 04:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:17:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:17:36 INFO - Installing collected packages: mozsystemmonitor 04:17:36 INFO - Running setup.py install for mozsystemmonitor 04:17:36 INFO - Successfully installed mozsystemmonitor 04:17:36 INFO - Cleaning up... 04:17:37 INFO - Return code: 0 04:17:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:17:37 INFO - Using env: {'DISPLAY': ':0', 04:17:37 INFO - 'HOME': '/home/cltbld', 04:17:37 INFO - 'LANG': 'en_US.UTF-8', 04:17:37 INFO - 'LANGUAGE': 'en_US:en', 04:17:37 INFO - 'LOGNAME': 'cltbld', 04:17:37 INFO - 'MAIL': '/var/mail/cltbld', 04:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:37 INFO - 'MOZ_NO_REMOTE': '1', 04:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:37 INFO - 'NO_EM_RESTART': '1', 04:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:37 INFO - 'PWD': '/builds/slave/test', 04:17:37 INFO - 'SHELL': '/bin/bash', 04:17:37 INFO - 'SHLVL': '1', 04:17:37 INFO - 'TERM': 'linux', 04:17:37 INFO - 'TMOUT': '86400', 04:17:37 INFO - 'USER': 'cltbld', 04:17:37 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:37 INFO - '_': '/tools/buildbot/bin/python'} 04:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:37 INFO - Downloading/unpacking jsonschema==2.5.1 04:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:17:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:39 INFO - Downloading functools32-3.2.3-2.tar.gz 04:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:17:39 INFO - warning: no files found matching '*.txt' 04:17:39 INFO - no previously-included directories found matching 'build' 04:17:39 INFO - no previously-included directories found matching 'dist' 04:17:39 INFO - no previously-included directories found matching '.git*' 04:17:39 INFO - Installing collected packages: jsonschema, functools32 04:17:39 INFO - Running setup.py install for functools32 04:17:39 INFO - warning: no files found matching '*.txt' 04:17:39 INFO - no previously-included directories found matching 'build' 04:17:39 INFO - no previously-included directories found matching 'dist' 04:17:39 INFO - no previously-included directories found matching '.git*' 04:17:39 INFO - Successfully installed jsonschema functools32 04:17:39 INFO - Cleaning up... 04:17:39 INFO - Return code: 0 04:17:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:39 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 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:17:39 INFO - Using env: {'DISPLAY': ':0', 04:17:39 INFO - 'HOME': '/home/cltbld', 04:17:39 INFO - 'LANG': 'en_US.UTF-8', 04:17:39 INFO - 'LANGUAGE': 'en_US:en', 04:17:39 INFO - 'LOGNAME': 'cltbld', 04:17:39 INFO - 'MAIL': '/var/mail/cltbld', 04:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:39 INFO - 'MOZ_NO_REMOTE': '1', 04:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:39 INFO - 'NO_EM_RESTART': '1', 04:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:39 INFO - 'PWD': '/builds/slave/test', 04:17:39 INFO - 'SHELL': '/bin/bash', 04:17:39 INFO - 'SHLVL': '1', 04:17:39 INFO - 'TERM': 'linux', 04:17:39 INFO - 'TMOUT': '86400', 04:17:39 INFO - 'USER': 'cltbld', 04:17:39 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:39 INFO - '_': '/tools/buildbot/bin/python'} 04:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:17:39 INFO - Cleaning up... 04:17:39 INFO - Return code: 0 04:17:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:39 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 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:17:39 INFO - Using env: {'DISPLAY': ':0', 04:17:39 INFO - 'HOME': '/home/cltbld', 04:17:39 INFO - 'LANG': 'en_US.UTF-8', 04:17:39 INFO - 'LANGUAGE': 'en_US:en', 04:17:39 INFO - 'LOGNAME': 'cltbld', 04:17:39 INFO - 'MAIL': '/var/mail/cltbld', 04:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:39 INFO - 'MOZ_NO_REMOTE': '1', 04:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:39 INFO - 'NO_EM_RESTART': '1', 04:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:39 INFO - 'PWD': '/builds/slave/test', 04:17:39 INFO - 'SHELL': '/bin/bash', 04:17:39 INFO - 'SHLVL': '1', 04:17:39 INFO - 'TERM': 'linux', 04:17:39 INFO - 'TMOUT': '86400', 04:17:39 INFO - 'USER': 'cltbld', 04:17:39 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:39 INFO - '_': '/tools/buildbot/bin/python'} 04:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:40 INFO - Downloading/unpacking blobuploader==1.2.4 04:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:41 INFO - Downloading blobuploader-1.2.4.tar.gz 04:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:17:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:17:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:42 INFO - Downloading docopt-0.6.1.tar.gz 04:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:17:42 INFO - Installing collected packages: blobuploader, requests, docopt 04:17:42 INFO - Running setup.py install for blobuploader 04:17:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:17:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:17:42 INFO - Running setup.py install for requests 04:17:43 INFO - Running setup.py install for docopt 04:17:43 INFO - Successfully installed blobuploader requests docopt 04:17:43 INFO - Cleaning up... 04:17:43 INFO - Return code: 0 04:17:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:43 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 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:17:43 INFO - Using env: {'DISPLAY': ':0', 04:17:43 INFO - 'HOME': '/home/cltbld', 04:17:43 INFO - 'LANG': 'en_US.UTF-8', 04:17:43 INFO - 'LANGUAGE': 'en_US:en', 04:17:43 INFO - 'LOGNAME': 'cltbld', 04:17:43 INFO - 'MAIL': '/var/mail/cltbld', 04:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:43 INFO - 'MOZ_NO_REMOTE': '1', 04:17:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:43 INFO - 'NO_EM_RESTART': '1', 04:17:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:43 INFO - 'PWD': '/builds/slave/test', 04:17:43 INFO - 'SHELL': '/bin/bash', 04:17:43 INFO - 'SHLVL': '1', 04:17:43 INFO - 'TERM': 'linux', 04:17:43 INFO - 'TMOUT': '86400', 04:17:43 INFO - 'USER': 'cltbld', 04:17:43 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:43 INFO - '_': '/tools/buildbot/bin/python'} 04:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:43 INFO - Running setup.py (path:/tmp/pip-VPXEYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:43 INFO - Running setup.py (path:/tmp/pip-Cxzhkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:43 INFO - Running setup.py (path:/tmp/pip-4kXgzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:43 INFO - Running setup.py (path:/tmp/pip-FAfQQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:43 INFO - Running setup.py (path:/tmp/pip-LVpzp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:43 INFO - Running setup.py (path:/tmp/pip-EnvxFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:44 INFO - Running setup.py (path:/tmp/pip-cCRhii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:44 INFO - Running setup.py (path:/tmp/pip-gviyK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:44 INFO - Running setup.py (path:/tmp/pip-HhOufV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:44 INFO - Running setup.py (path:/tmp/pip-sgqwdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:44 INFO - Running setup.py (path:/tmp/pip-e5NohH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:44 INFO - Running setup.py (path:/tmp/pip-os_kFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:44 INFO - Running setup.py (path:/tmp/pip-ZB8Hic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:44 INFO - Running setup.py (path:/tmp/pip-goPgRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:44 INFO - Running setup.py (path:/tmp/pip-4H8sBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:44 INFO - Running setup.py (path:/tmp/pip-V6G7Bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:44 INFO - Running setup.py (path:/tmp/pip-spZeJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:17:44 INFO - Running setup.py install for manifestparser 04:17:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:17:44 INFO - Running setup.py install for mozcrash 04:17:45 INFO - Running setup.py install for mozdebug 04:17:45 INFO - Running setup.py install for mozdevice 04:17:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Running setup.py install for mozfile 04:17:45 INFO - Running setup.py install for mozhttpd 04:17:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Running setup.py install for mozinfo 04:17:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Running setup.py install for mozInstall 04:17:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Running setup.py install for mozleak 04:17:45 INFO - Running setup.py install for mozlog 04:17:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Running setup.py install for moznetwork 04:17:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Running setup.py install for mozprocess 04:17:46 INFO - Running setup.py install for mozprofile 04:17:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Running setup.py install for mozrunner 04:17:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Running setup.py install for mozscreenshot 04:17:46 INFO - Running setup.py install for moztest 04:17:46 INFO - Running setup.py install for mozversion 04:17:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:17:46 INFO - Cleaning up... 04:17:46 INFO - Return code: 0 04:17:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:46 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 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:17:46 INFO - Using env: {'DISPLAY': ':0', 04:17:46 INFO - 'HOME': '/home/cltbld', 04:17:46 INFO - 'LANG': 'en_US.UTF-8', 04:17:46 INFO - 'LANGUAGE': 'en_US:en', 04:17:46 INFO - 'LOGNAME': 'cltbld', 04:17:46 INFO - 'MAIL': '/var/mail/cltbld', 04:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:46 INFO - 'MOZ_NO_REMOTE': '1', 04:17:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:46 INFO - 'NO_EM_RESTART': '1', 04:17:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:46 INFO - 'PWD': '/builds/slave/test', 04:17:46 INFO - 'SHELL': '/bin/bash', 04:17:46 INFO - 'SHLVL': '1', 04:17:46 INFO - 'TERM': 'linux', 04:17:46 INFO - 'TMOUT': '86400', 04:17:46 INFO - 'USER': 'cltbld', 04:17:46 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:46 INFO - '_': '/tools/buildbot/bin/python'} 04:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:46 INFO - Running setup.py (path:/tmp/pip-lu09aU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:46 INFO - Running setup.py (path:/tmp/pip-uX_kJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:46 INFO - Running setup.py (path:/tmp/pip-D80rHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:47 INFO - Running setup.py (path:/tmp/pip-cIurgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:47 INFO - Running setup.py (path:/tmp/pip-_rK0pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:47 INFO - Running setup.py (path:/tmp/pip-fATSUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:47 INFO - Running setup.py (path:/tmp/pip-vfdOqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:47 INFO - Running setup.py (path:/tmp/pip-1j2vJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:47 INFO - Running setup.py (path:/tmp/pip-ZSgmyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:47 INFO - Running setup.py (path:/tmp/pip-CdYKOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:47 INFO - Running setup.py (path:/tmp/pip-SV64jV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:47 INFO - Running setup.py (path:/tmp/pip-6fiRX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:47 INFO - Running setup.py (path:/tmp/pip-aQ6zZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:47 INFO - Running setup.py (path:/tmp/pip-jNeteu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:47 INFO - Running setup.py (path:/tmp/pip-M0C7pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:47 INFO - Running setup.py (path:/tmp/pip-77DsfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:48 INFO - Running setup.py (path:/tmp/pip-vM4A1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:17:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:50 INFO - Downloading blessings-1.6.tar.gz 04:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:17:50 INFO - Installing collected packages: blessings 04:17:50 INFO - Running setup.py install for blessings 04:17:50 INFO - Successfully installed blessings 04:17:50 INFO - Cleaning up... 04:17:50 INFO - Return code: 0 04:17:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:17:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:50 INFO - Reading from file tmpfile_stdout 04:17:50 INFO - Current package versions: 04:17:50 INFO - argparse == 1.2.1 04:17:50 INFO - blessings == 1.6 04:17:50 INFO - blobuploader == 1.2.4 04:17:50 INFO - docopt == 0.6.1 04:17:50 INFO - functools32 == 3.2.3-2 04:17:50 INFO - jsonschema == 2.5.1 04:17:50 INFO - manifestparser == 1.1 04:17:50 INFO - mozInstall == 1.12 04:17:50 INFO - mozcrash == 0.17 04:17:50 INFO - mozdebug == 0.1 04:17:50 INFO - mozdevice == 0.48 04:17:50 INFO - mozfile == 1.2 04:17:50 INFO - mozhttpd == 0.7 04:17:50 INFO - mozinfo == 0.9 04:17:50 INFO - mozleak == 0.1 04:17:50 INFO - mozlog == 3.4 04:17:50 INFO - moznetwork == 0.27 04:17:50 INFO - mozprocess == 0.23 04:17:50 INFO - mozprofile == 0.28 04:17:50 INFO - mozrunner == 6.12 04:17:50 INFO - mozscreenshot == 0.1 04:17:50 INFO - mozsystemmonitor == 0.3 04:17:50 INFO - moztest == 0.7 04:17:50 INFO - mozversion == 1.4 04:17:50 INFO - psutil == 3.1.1 04:17:50 INFO - requests == 1.2.3 04:17:50 INFO - wsgiref == 0.1.2 04:17:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:50 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 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:17:50 INFO - Using env: {'DISPLAY': ':0', 04:17:50 INFO - 'HOME': '/home/cltbld', 04:17:50 INFO - 'LANG': 'en_US.UTF-8', 04:17:50 INFO - 'LANGUAGE': 'en_US:en', 04:17:50 INFO - 'LOGNAME': 'cltbld', 04:17:50 INFO - 'MAIL': '/var/mail/cltbld', 04:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:50 INFO - 'MOZ_NO_REMOTE': '1', 04:17:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:50 INFO - 'NO_EM_RESTART': '1', 04:17:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:50 INFO - 'PWD': '/builds/slave/test', 04:17:50 INFO - 'SHELL': '/bin/bash', 04:17:50 INFO - 'SHLVL': '1', 04:17:50 INFO - 'TERM': 'linux', 04:17:50 INFO - 'TMOUT': '86400', 04:17:50 INFO - 'USER': 'cltbld', 04:17:50 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:50 INFO - '_': '/tools/buildbot/bin/python'} 04:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:17:50 INFO - Cleaning up... 04:17:50 INFO - Return code: 0 04:17:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:50 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 0x7f8cff3f8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:17:50 INFO - Using env: {'DISPLAY': ':0', 04:17:50 INFO - 'HOME': '/home/cltbld', 04:17:50 INFO - 'LANG': 'en_US.UTF-8', 04:17:50 INFO - 'LANGUAGE': 'en_US:en', 04:17:50 INFO - 'LOGNAME': 'cltbld', 04:17:50 INFO - 'MAIL': '/var/mail/cltbld', 04:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:50 INFO - 'MOZ_NO_REMOTE': '1', 04:17:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:50 INFO - 'NO_EM_RESTART': '1', 04:17:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:50 INFO - 'PWD': '/builds/slave/test', 04:17:50 INFO - 'SHELL': '/bin/bash', 04:17:50 INFO - 'SHLVL': '1', 04:17:50 INFO - 'TERM': 'linux', 04:17:50 INFO - 'TMOUT': '86400', 04:17:50 INFO - 'USER': 'cltbld', 04:17:50 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:50 INFO - '_': '/tools/buildbot/bin/python'} 04:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:17:50 INFO - Cleaning up... 04:17:50 INFO - Return code: 0 04:17:50 INFO - Running post-action listener: _resource_record_post_action 04:17:50 INFO - Running post-action listener: _start_resource_monitoring 04:17:50 INFO - Starting resource monitoring. 04:17:50 INFO - [mozharness: 2016-11-03 11:17:50.954074Z] Finished create-virtualenv step (success) 04:17:50 INFO - [mozharness: 2016-11-03 11:17:50.954584Z] Running install step. 04:17:50 INFO - Running pre-action listener: _resource_record_pre_action 04:17:50 INFO - Running main action method: install 04:17:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:51 INFO - Reading from file tmpfile_stdout 04:17:51 INFO - Detecting whether we're running mozinstall >=1.0... 04:17:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:17:51 INFO - Reading from file tmpfile_stdout 04:17:51 INFO - Output received: 04:17:51 INFO - Usage: mozinstall [options] installer 04:17:51 INFO - Options: 04:17:51 INFO - -h, --help show this help message and exit 04:17:51 INFO - -d DEST, --destination=DEST 04:17:51 INFO - Directory to install application into. [default: 04:17:51 INFO - "/builds/slave/test"] 04:17:51 INFO - --app=APP Application being installed. [default: firefox] 04:17:51 INFO - mkdir: /builds/slave/test/build/application 04:17:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:18:07 INFO - Reading from file tmpfile_stdout 04:18:07 INFO - Output received: 04:18:07 INFO - /builds/slave/test/build/application/firefox/firefox 04:18:07 INFO - Running post-action listener: _resource_record_post_action 04:18:07 INFO - [mozharness: 2016-11-03 11:18:07.212275Z] Finished install step (success) 04:18:07 INFO - [mozharness: 2016-11-03 11:18:07.212525Z] Running run-tests step. 04:18:07 INFO - Running pre-action listener: _resource_record_pre_action 04:18:07 INFO - Running main action method: run_tests 04:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:18:07 INFO - Python 2.7.3 04:18:07 INFO - Return code: 0 04:18:07 INFO - grabbing minidump binary from tooltool 04:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:07 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 0x24eaf60>, '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 0x24ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ec1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:18:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:18:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:18:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:18:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:18:07 INFO - Return code: 0 04:18:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:18:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:18:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:18:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:18:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:18:07 INFO - Using env: {'DISPLAY': ':0', 04:18:07 INFO - 'HOME': '/home/cltbld', 04:18:07 INFO - 'LANG': 'en_US.UTF-8', 04:18:07 INFO - 'LANGUAGE': 'en_US:en', 04:18:07 INFO - 'LOGNAME': 'cltbld', 04:18:07 INFO - 'MAIL': '/var/mail/cltbld', 04:18:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:18:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:07 INFO - 'MOZ_NO_REMOTE': '1', 04:18:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:18:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:07 INFO - 'NO_EM_RESTART': '1', 04:18:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:07 INFO - 'PWD': '/builds/slave/test', 04:18:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:18:07 INFO - 'SHELL': '/bin/bash', 04:18:07 INFO - 'SHLVL': '1', 04:18:07 INFO - 'TERM': 'linux', 04:18:07 INFO - 'TMOUT': '86400', 04:18:07 INFO - 'USER': 'cltbld', 04:18:07 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578', 04:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:07 INFO - '_': '/tools/buildbot/bin/python'} 04:18:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:18:07 INFO - mozversion application_buildid: 20161103012423 04:18:07 INFO - mozversion application_changeset: 64a63ba0f3ef0a36968df13fbadb87dfcf167dd8 04:18:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:18:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:18:07 INFO - mozversion application_name: Firefox 04:18:07 INFO - mozversion application_remotingname: firefox-dev 04:18:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:18:07 INFO - mozversion application_vendor: Mozilla 04:18:07 INFO - mozversion application_version: 51.0a2 04:18:07 INFO - mozversion platform_buildid: 20161103012423 04:18:07 INFO - mozversion platform_changeset: 64a63ba0f3ef0a36968df13fbadb87dfcf167dd8 04:18:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:18:07 INFO - mozversion platform_version: 51.0a2 04:18:07 INFO - using testdate: 1478171887 04:18:07 INFO - actual date: 1478171887 04:18:07 INFO - starting webserver on 'localhost:38340' 04:18:07 INFO - SUITE-START | Running 2 tests 04:18:07 INFO - TEST-START | dromaeo_css 04:18:07 INFO - operating with platform_type : linux_ 04:18:07 INFO - Initialising browser for dromaeo_css test... 04:18:07 INFO - TEST-INFO | started process 1892 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxZg92q/profile http://localhost:38340/getInfo.html) 04:18:11 INFO - PROCESS | 1892 | __metrics Screen width/height:1600/1200 04:18:11 INFO - PROCESS | 1892 | colorDepth:24 04:18:11 INFO - PROCESS | 1892 | Browser inner width/height: 1024/697 04:18:11 INFO - PROCESS | 1892 | __metrics 04:18:11 INFO - PROCESS | 1892 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:18:11 INFO - PROCESS | 1892 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:18:12 INFO - PROCESS | 1892 | console.error: PushService: 04:18:12 INFO - PROCESS | 1892 | stateChangeProcessEnqueue: Error transitioning state 04:18:12 INFO - PROCESS | 1892 | UnknownError 04:18:12 INFO - TEST-INFO | 1892: exit 0 04:18:12 INFO - Browser initialized. 04:18:12 INFO - Running cycle 1/1 for dromaeo_css test... 04:18:12 INFO - TEST-INFO | started process 1982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxZg92q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:24 INFO - PROCESS | 1982 | RSS: Main: 145518592 04:18:24 INFO - PROCESS | 1982 | 04:19:15 INFO - PROCESS | 1982 | 1478171955922 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:21:25 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:38340/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38340/tests/dromaeo/cssquery-ext.html) 04:21:26 INFO - PROCESS | 1982 | RSS: Main: 245956608 04:21:26 INFO - PROCESS | 1982 | 04:24:28 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:38340/tests/dromaeo/cssquery-ext.html (next: http://localhost:38340/tests/dromaeo/cssquery-jquery.html) 04:24:28 INFO - PROCESS | 1982 | RSS: Main: 227160064 04:24:28 INFO - PROCESS | 1982 | 04:27:30 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:38340/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38340/tests/dromaeo/cssquery-mootools.html) 04:27:31 INFO - PROCESS | 1982 | RSS: Main: 174264320 04:27:31 INFO - PROCESS | 1982 | 04:30:33 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:38340/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38340/tests/dromaeo/cssquery-prototype.html) 04:30:34 INFO - PROCESS | 1982 | RSS: Main: 175656960 04:30:34 INFO - PROCESS | 1982 | 04:33:36 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:38340/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38340/tests/dromaeo/cssquery-yui.html) 04:33:37 INFO - PROCESS | 1982 | RSS: Main: 173543424 04:33:37 INFO - PROCESS | 1982 | 04:36:39 INFO - PROCESS | 1982 | Cycle 1(1): loaded http://localhost:38340/tests/dromaeo/cssquery-yui.html (next: http://localhost:38340/tests/dromaeo/cssquery-dojo.html) 04:36:40 INFO - PROCESS | 1982 | RSS: Main: 268328960 04:36:40 INFO - PROCESS | 1982 | 04:36:40 INFO - PROCESS | 1982 | __start_tp_report 04:36:40 INFO - PROCESS | 1982 | _x_x_mozilla_page_load 04:36:40 INFO - PROCESS | 1982 | _x_x_mozilla_page_load_details 04:36:40 INFO - PROCESS | 1982 | |i|pagename|runs| 04:36:40 INFO - PROCESS | 1982 | |0;dojo.html;3111.9688803111967;3144.7326977206935;3167.857446414911;3189.968100318996;3199.808011519309;5499.752511136999;5540.390557038722;5544.1960915667205;5565.332160140783;5580.135079062741;5611.7474713637885;5684.175794509793;5687.402822703608;5693.77224911003;5735.713214339276;5103.106956282654;5128.461511541291;5160.096983027974;5204.141316682742;5208.0885844977165;4978.029284289565;4991.151504244287;5168.715720635363;5215.113430716769;5217.686938783685;4998.750062496861;4998.825041123561;5011.649184557083;5053.6715113517685;5088.694678319294;5098.745062746866;5114.335136432277;5201.5838732901275;5201.713905735183;5233.058049551079;4859.052484765472;5065.949340506585;5081.136206844845;5122.846314610568;5131.3329267195395;5453.127499600045;5455.399906010336;5507.531859791922;5525.806596769134;5528.585356098269;5937.554683398763;5944.197533332988;6057.545684073668;6059.697015149269;6071.635701857903;5806.332271788756;6052.727627256784;6072.696365181768;6079.726412311456;6080.695965201767;5432.6468779529205;5545.417731138253;5587.888242235133;5588.91616625751;5609.859753506195;6175.413335733098;6178.258608966953;6191.164192834;6191.690415479208;6193.535484838565;5657.264555607745;5927.3776253493625;5932.317783454958;5937.851553711192;5938.49522790559;5798.652080875161;5862.384449632727;5915.1718759373725;5933.050711886078;6008.429199226067;5940.108983652401;5942.3166335871065;5949.791757288474;5949.940500595025;5951.315598706117;5907.675077870673;5962.880742385215;5963.045912654041;5971.701414929236;5989.700514974234;4537.228671125917;4542.613877820356;4563.178627846953;4574.199515084847;4614.861554153381;4048.838046478108;4063.6342729153926;4070.023194433374;4089.8977525561504;4104.753714777123;5581.274434323594;5582.748776304995;5586.273784408001;5588.720563971785;5617.157426386075;5750.4249575042995;5791.768329266866;5932.406759324033;6075.33171351151;6123.130109042722;5805.303363596396;5806.303243610793;5811.593188476766;5818.8254352369495;5842.152887831241;5270.631055826132;5279.709615971082;5281.049411105961;5284.233786100994;5284.339457567804;5964.910526342108;6087.208662873798;6095.2685677718955;6310.6213627182515;6320.115183874262;5859.472647461749;6014.977453832771;6025.969870150621;6050.42520960508;6056.727447264796;6154.969225153845;6159.29168145669;6164.229471316085;6169.043798211284;6202.906956395567;5522.06124958766;5687.488126068673;5882.911756323573;5897.587168898136;5899.115132729953;5970.97014514942;5991.281046274301;6027.065804800263;6032.366601506778;6038.486728628116;5836.095405212199;5884.382139875423;5890.175375447355;5893.764249429974;5895.086261629454;5628.437156284339;5849.532037436909;5862.648241105548;5869.178315035813;5870.5010074144175;5690.687012214368;5750.827475175752;5802.187693722798;5806.2451881255165;5900;5877.382874798255;5892.322382925847;5895.086261629454;5898.7935422261735;5900.64596124234;5522.337319521683;5567.526760225426;5753.741081651252;5754.9136762947755;5756.856078597901 04:36:40 INFO - PROCESS | 1982 | |1;ext.html;591.9822405327839;623.8814625221207;630.464105510316;646.5571083807589;650.0282078292952;36579.63420365796;46415.303770443425;47491.28763068548;47566.52433475664;51258;47913.52086479134;48246;48315.27527087097;48436.51563484364;48511;8503.1921967413;8676.739697809076;9366.297527685418;9541.666041688542;9543.713688589354;11025.558977640885;11293.717657058558;11314.603988860392;11737.413129343542;11754.29474231549;11398.715032124222;11496.597619083333;11500.884991150066;11504.827427588592;11521.251118677259;8849.601767920394;9097.13577210164;11141.442927853575;11316.547338106466;11397.202195846294;10714.250002499828;11108.16688748347;11127.72180695481;11127.72180695481;11132.833007504894;45728.314075288894;46168.53831461676;46219.306710399374;46246.53753462456;46301;3704.5554533456157;3716.349638813197;3740.3454395480953;3751.7936513491745;3919.510061242345;34129.31741365159;34139.4879076814;34230.48654270188;34313.627454901776;34317;4652.255639097762;4719.575238228542;4740.644451666139;4748.264019077049;4750.334953106568;350481;353135;353605;353701;354602;62105.37894621086;62477;62543.6872815633;62686.68656656779;62688.37311626917;64659;64663.353366466676;64683;64710;64747;17980;18639.720404193948;18645.533861653297;18647.160877760532;18650.34723784661;13141;13418.731625367438;14083.647908802363;14487.275636218146;14762.557123286319;17301.56746081358;17347.566310842078;17358.826411735972;17366.826331736775;17474.65050698979;6258.468030217391;6349.873002539924;6368.490520758421;6382.84042898922;6398.2641996170105;1011.9949400252951;1073.5598404654054;1115.0117684605336;1154.6247469572254;1180.5218886351042;33405.33189336248;33437.16407089842;33631.15922101918;33685.494717579255;34018.136226089766;1105.7788442311569;1131.422974283105;1163.8661553921238;1170.2861254634665;1220.3593113615245;17501.649967000587;17656.20547075385;17684.557886052695;17775.73336399955;17786.46640600784;157991;158257;158346.2082689579;158706;160005;61664.69167654223;61866.38133618606;61969;62144;62173.37826621766;1314.4019471140607;1364.6315494816345;1415.2640626874174;1416.8937329700314;1466.9633259168393;30190.09429717112;33949;34641.30717385588;34641.48037779385;35006.233020606705;51935;52458.73770631275;52538.737306313225;52583.47416525786;52590;436671;438518;438754;440713;440965;29723.554146687384;29955;30397.240068998453;31852.362952741278;43924.780376097915;50431.747841260556;50592.74703626605;50728.492715072374;51329;51953.740231300115;42843.14313713646;43008.139837202456;43076.78461607672;43153.784231078644;44951.775241123585;42009.15981680288;42330.78834605807;42831.1433771329;42944.35583466313;44275.778621106685;42033.78983105065;42469;43353;43671.34492982544;43684.34473482964;35250.29499409946;37993;38181.23637527179;38534;38977.2204555913;29077.7092229075;29144.125676229745;29160.854195728887;29168.416631666823;29210 04:36:40 INFO - PROCESS | 1982 | |2;jquery.html;386.4917633312196;429.24553726743073;431.4520558890209;439.8174757475648;444.29800914555005;16945.322187112517;19968.5007874803;20432.693509597368;21063;21214.787852121473;19585.216591336364;19835.305764298253;20157.697634535492;20205;20300;5889.793857214999;5891.852703682421;6132.50939924805;6137.17148184996;6146.078088286748;6022.096685497188;6051.75792968283;6107.267127944653;6107.603005804608;6110.786122485714;5836.153757705151;6013.278406591194;6059.818205453844;6061.969690151543;6063.696815159246;6021.157038014681;6021.789237376693;6050.3647117052515;6061.090836374535;6062.939370606304;5294.57643388528;5402.162664786964;5449.809256676037;5454.263674403984;5459.044667183227;24349.715664139876;24665.506689866103;24685.50628987428;24741.87629061843;25183.244502664766;2526.027479420418;2611.3341098020073;2670.1588999465303;2671.305460580263;2708.404151086758;8124.984376952881;14927.850721492754;15909;15929.123898185711;16614.916925415415;1978.99010504948;2003.6493613617704;2012.466696325475;2035.4707775978352;2085.1971990783504;88358.11641883563;89759.99999999935;90016;91219;91686.5415672924;10104.595816167272;11048.337099774039;11263.66209013731;11345.886541134649;11585.131115166228;14366.13803171813;15072.472463463726;15116.244187790566;15117.16855572954;15148.469803556822;8365.790855228668;8767.868481972775;8768.25469835071;8814.427062240933;8884.244839188612;8460;8498.575071246412;8532.146785321418;8668.956655216809;8763.167499087576;4951.554360107608;5036.269740887551;5047.116754567936;5240.030594340059;5287.894242115213;2073.5956488484903;2077.740282464692;2123.6283650361434;2138.134055707441;2145.3027765975808;928.8560273157672;941.2281928818303;997.9920280516731;1047.2878442659064;1080.5191689698165;19839.611565478725;19901.60196796056;20011;20178.394648160578;20276.594468110554;974.4202199691173;986.6941248213077;987.4667679453015;996.7358649957768;1004.5730564510054;11092.279001864854;11285.266457680225;11567.363794991355;11573.189876708719;11581.594644187413;487007;500158;502454;505309;508159;89612;90180;91193.54403228074;91385.54307228421;91482.08517914735;1036.8496567997597;1088.8638920134983;1109.4840898981852;1120.4845770945456;1124.9043831274269;17563.473095807145;17719.468415947544;17901.10494475297;18171.364002259856;18321.267149313877;29342.70657293485;32378.352432950738;42199;43554.56445435632;43936.560634393245;59017.40982590291;61598.692006541474;67178;67324;68626.313736862;8807.735767926972;9153.267738580764;9200.907990920274;9288.674896378594;9320.347575669639;13087.476500939856;13131.01517386219;13406.731865362834;13543.32283385807;13635.454581816617;11465.942670286595;11588.594399195988;11665.300081995107;11705.122115841175;11957.880421195676;11281.379524126252;11576.710582235512;11604.709882253012;11630.360330181582;11892.10809189297;11649;11661.59184428575;11698.532058717896;11821.290722556674;12536.623901282976;8036.116027237116;8287.046989596032;8955.462672239686;9173.40372875761;9445.149936505748;10931.398773067558;11491.827622585835;11623.709407264885;12279.508819647472;12281.385930703607 04:36:40 INFO - PROCESS | 1982 | |3;mootools.html;1406.1633328169735;1510.8791296696263;1523.3830298729013;1700.9829901700987;1864.5711486358136;8374.665013399457;9402.341836071479;9629.759256018602;9641.180499657537;9977.600895964171;9667.56495957682;9738.561764720587;9753.707388778348;9756.365836220659;9764.414135151896;2556.3736884463287;2576.8196226264167;2577.7680008799202;2580.135654555726;2581.6772903387077;2523.129520315488;2579.8968041278326;2580.3936075022357;2582.7029891562524;2583.147561304765;2531.8480891146496;2547.4777670577487;2604;2608.074133682544;2611.6082587611913;2540;2553.668023156987;2568.0883286433327;2570.5116027954627;2572.909948151824;2435.890384932682;2462.9261122166363;2468.7531246875346;2519.6220566914926;2524.886380112899;9461.716148515556;9489.193418559431;9677.806443871154;9701.126898579161;9712.077352651488;5690;5900.675462849542;5940.584159108864;5955.761769529214;5961.225040744719;3643.1802844360072;3669.2478042001326;3719.423489359154;3726.8695595654294;3731.8693845715543;1207.088648070701;1219.548766956232;1239.2874097394035;1239.5289789879787;1275.3623188405797;13453.125546839521;13539.864601353958;13556.457741690321;13580.796288055688;13629.59111226655;4839.104765618372;4849.393825771778;4940.777665005084;5017.7491125443585;5067.391912970394;10816.459177041117;10822.458877056115;10822.729431764268;10836.295640783324;10837;5816.47651711348;5818.301803783573;5828.388019257917;5838.241028666246;5839.211706419664;4343.891402714894;4541.772911354419;4595.333676616939;4650.558196971282;5435.701036442954;4196.580341965779;4252.383404406344;4327.978360108179;4390.319500477431;4393.890152746207;1265.101777737798;1288.4652869059234;1296.0862591872708;1299.064673435125;1312.3700623700677;936.5223735894607;979.72567681048;981.1316984468797;984.975375615601;992.7617371830709;6311.779087731908;6466.612003279724;6573.769918052845;6694.430973367221;6715.664216789141;467.91577516046755;468.4519112638221;476.8974670445871;482.1081000149695;483.07009007660815;4389;4478.619317358061;4503.774811259424;4516.322551617218;4682.906341873144;11932.403379830974;12530.561430349893;12546.121771476091;12556.246625202517;12567.748645027048;12115.091368147248;12141;12200.75598488043;12279;12363.196392234657;8370.288525475282;9126.13301736334;9131.68039118641;9210.677626283183;9223.216026637676;6499.6750162492635;6568.047633093271;6615.4376877965915;6617.8014659560295;6901.171859376706;10632.25574209798;10787.892121078688;11047.72380690457;11083.113350932068;11090.168237382068;10094.141997929964;10424.635137770141;11051.944740276247;11152.498137583665;11190.384528850991;7503.587302698185;8324.209200125979;8571.928508936382;8644.91355086441;8816.823663526822;9423.811523769624;9750.609975600897;9811.656592019244;9832.705018849447;9842.163416109712;8312.168783121639;8367.49385110686;8592.226699597066;8957.507337096522;8976.281897448058;8475.915240847513;8477.194666506672;8539.957300213458;8553.315734741082;8729.127087291221;7822.804429889071;7833.960830195812;7956.6419511120985;8042.919570804217;8045.477043992122;5613.943860561505;5628.634138780966;5633.324001119728;5678.574106941914;5768.567357448124;5709.115087161496;5797.59416840834;5870.236869206976;5923.733431995484;5972.910406343822 04:36:40 INFO - PROCESS | 1982 | |4;prototype.html;344.5572439415352;351.78716876289843;353.0643793946044;357.119699939649;360.75108175359014;5532.1425179097205;5559.527440167585;5561.5272701820395;5562.888742225153;5570.275864137657;5445.809396671118;5466.289382380295;5536.833894983147;5543.695096769676;5555.138953462209;3792.7534710243744;4083.9795801020955;4233.534311225762;4322.222000039991;4322.610965013148;4301.612854843063;4314.3097104463295;4318.222719910431;4318.784060796947;4323.329883867989;4283.443152390185;4290.356446533014;4313.741175529462;4328.134373125372;4352.869413917588;4043.716939814214;4108.67130629549;4226.957730422702;4260.297050986584;4282.250606143913;3755.586885442599;3779.99660180105;3783.5459744830796;3790.4882840816413;3822.2737679840743;5306.779440728654;5308.46915308466;5338.466153384669;5364.758585863646;5398.136298192309;4789;4889.533140058004;4899.8775030624165;4924.729139897304;4941.407031156248;5675.659460432387;5676.403977582336;5683.971580142114;5686.459786320293;5699.401562835884;4179.707420480568;4183.832646694129;4187.65491324856;4191.371294305848;4218.177455396199;5607.383187849333;5749.1663708762;5764.481196692276;5917.733701983421;5933.673647949361;4173;4301.827926882889;4305.418768466279;4307.633851122627;4332.393464914914;4377.956220437819;4378.146261479044;4385.399329244867;4405.7797110145;4413.757243351646;4848.321235027099;4913.754312284372;4952.603791696655;4970.627202959792;4976.552110310017;5682.7442765075675;5687.175359572839;5688.601797874192;5727.5131613812455;5738.942610573924;1494.5815171751926;1508.8381945901594;1516.8483151684743;1517.2262146305463;1518.9012714173543;946.4936259101348;984.158544444504;988.3032462114193;991.9900800991898;1014.2747935226201;4862.708237505761;4912.091263116335;4922.901541969212;4929.433115191727;4963.230699241623;5153.793848246103;5157.896842063138;5158.484151584811;5163.225516172604;5168.715720635326;4591.380163677927;4856.95143048565;4870.634702397264;4947.851564453073;4953.777080031407;2733;2734.1681073680684;2734.931626709348;2743.7530622244094;2745;5201.307071978862;5258;5277.947220527745;5282.392524859613;5323.680579165262;5276.577873770166;5278.419373868871;5616.185653080281;6099.817005489754;6113.388661133851;6101.328853826076;6118.143459915705;6131.202943617302;6134.969325153345;6146.385361463907;5647.350554686263;5930.703464826655;5933.436323549256;5940.762369505172;5949.762009519571;5821.592488525763;5823.796167134299;5848.619839710405;5875.735591898289;5889.440503152194;5794.681292528951;5897.616654917417;5918.7928422505;6088.543359247986;6098.969505152446;4191.895202619959;4210.32634778434;4211.53673095953;4232;4238.6397156242065;5770.711464426678;5908.675022873611;5962.509372656836;5962.672053036951;5971.044632858721;5719.656820590778;5775.653460792366;5777.364489906023;5794.62334948227;5804.01331773591;5753.367129615823;5756.42435756421;5762.884742305214;5769.76892939955;5793.710314484175;5533;5648.661080335193;5747.597668163188;5769.96140694679;5787.623804452697;5742.827715168552;5788.913166302593;5790.913136302875;5794.232759008542;5802.854928626649;5653.717314134359;5674.404187560412;5680.204771331934;5682.659040457585;5689.34572524165 04:36:40 INFO - PROCESS | 1982 | |5;yui.html;822.9711960081395;852.6845067325089;853.6798700487317;883.6818745251712;920.958556864941;15523.301451434685;16670.416535421264;16734.163291835404;16738.83261167388;16747.665046699058;16076.356945722186;16087.436939707113;16205.189740512986;16243.51269461918;16248.75626865598;709.7196607340097;731.7987553422824;850.6002178975872;855.2815634866711;887.7514295997099;877.4779006491127;879.6921077622812;881.1496905486205;881.7707396077034;882.0738224863867;907.6732376344511;908.5956749246554;909.0545832334398;910.1990248581272;911.0752586125088;751.6730222353294;772.3010675338827;886.2422628652538;902.1970446302796;916.4867674102524;671.1912145864222;671.9865602687968;672.6838385958591;673.3905815237218;673.4511373230802;15820.841791582052;15847;15894.046357218604;15922.124283164421;15940.920295398564;14896.702065958729;14944.40222391103;15131.394744210218;15219.923900380427;15472.767908481383;14355.497557585435;14396.496122635657;14456;14638.121712697166;14702.26488675562;4622.930656040162;4678.672492925497;4706.364640773486;4748.620110391161;4796.472388037313;90035.09964900398;91351;91391.54304228436;91564.54217728869;91667.54166229126;12165.452554635063;12175.695607609881;12200.633980980407;12201.877981220252;12202.633920982385;22445.663315050286;22550.548989020455;22655.433614159778;22695.659565106536;23295.76704232936;8899.599518021705;9048.683296084737;9126.680566180152;9168;9310.487923164292;4944.184209605447;4993.900121997613;5121.334226550599;5122.385313762298;5211.322528071327;3399.6940275375337;3497.2306092659574;3542.079059444563;3548.6273941236313;3597.94603080954;509.2132655047886;597.7160848596968;601.6720887116531;615.4983688294026;620.2928661326092;737.340080627835;791.600241877848;802.9237222463856;809.0574480730002;817.284875733733;9736.36713613627;9988.450635215133;10021.640586344964;10267;10353.74115647115;728.7850084225166;763.4999075605488;781.1368437876065;782.1748055801185;785.6229010075195;5006.3992320921725;5121.206213036985;5126.92309615356;5176.1459359206065;5216.66091704038;82827.58586207031;124092.37953810173;129656;131815;136933.31533342268;94318;96152;116639;118899;120951;79443.20556794474;82100;87889.99999999872;90441.54779226062;93404;9513.381630194015;10026.197904167795;10233.385996840214;11436.656900293005;11472.483738231633;56565.71717141388;57348;57501.84996300134;57543.712281438326;59013;55638.16542751781;85067.57466212877;86127.13872861191;86755;90681.54659226662;13873.375698093812;14514.34685439179;15520.844791552392;16165.353385864904;16211.270492828087;14377.712445751236;14860.62848428798;14894.776578351117;17767.46697599074;17831.73252401241;14051;14336.21150836714;14959.25203739787;15457.922710386376;15472.14903180336;15606;15855.52433426999;16180.190990450667;16823.66352672964;17406.564835879206;14016.509422170175;14604.561863143697;14687.853121468648;14870.553883383516;15466.690666186838;12125.636230912734;12490;12843.229406235656;13051.412686429323;13770.93114534421;12195.512179513074;12669.619911402673;12674.619761407173;12678.302693351954;13002.479900804241 04:36:40 INFO - PROCESS | 1982 | __end_tp_report 04:36:40 INFO - PROCESS | 1982 | __start_cc_report 04:36:40 INFO - PROCESS | 1982 | _x_x_mozilla_cycle_collect,912 04:36:40 INFO - PROCESS | 1982 | __end_cc_report 04:36:40 INFO - PROCESS | 1982 | __startTimestamp1478173000193__endTimestamp 04:36:40 INFO - PROCESS | 1982 | 04:36:40 INFO - PROCESS | 1982 | ------- Summary: start ------- 04:36:40 INFO - PROCESS | 1982 | Number of tests: 6 04:36:40 INFO - PROCESS | 1982 | 04:36:40 INFO - PROCESS | 1982 | [#0] dojo.html Cycles:165 Average:5554.65 Median:5791.77 stddev:637.02 (11.0%) stddev-sans-first:609.48 04:36:40 INFO - PROCESS | 1982 | Values: 3112.0 3144.7 3167.9 3190.0 3199.8 5499.8 5540.4 5544.2 5565.3 5580.1 5611.7 5684.2 5687.4 5693.8 5735.7 5103.1 5128.5 5160.1 5204.1 5208.1 4978.0 4991.2 5168.7 5215.1 5217.7 4998.8 4998.8 5011.6 5053.7 5088.7 5098.7 5114.3 5201.6 5201.7 5233.1 4859.1 5065.9 5081.1 5122.8 5131.3 5453.1 5455.4 5507.5 5525.8 5528.6 5937.6 5944.2 6057.5 6059.7 6071.6 5806.3 6052.7 6072.7 6079.7 6080.7 5432.6 5545.4 5587.9 5588.9 5609.9 6175.4 6178.3 6191.2 6191.7 6193.5 5657.3 5927.4 5932.3 5937.9 5938.5 5798.7 5862.4 5915.2 5933.1 6008.4 5940.1 5942.3 5949.8 5949.9 5951.3 5907.7 5962.9 5963.0 5971.7 5989.7 4537.2 4542.6 4563.2 4574.2 4614.9 4048.8 4063.6 4070.0 4089.9 4104.8 5581.3 5582.7 5586.3 5588.7 5617.2 5750.4 5791.8 5932.4 6075.3 6123.1 5805.3 5806.3 5811.6 5818.8 5842.2 5270.6 5279.7 5281.0 5284.2 5284.3 5964.9 6087.2 6095.3 6310.6 6320.1 5859.5 6015.0 6026.0 6050.4 6056.7 6155.0 6159.3 6164.2 6169.0 6202.9 5522.1 5687.5 5882.9 5897.6 5899.1 5971.0 5991.3 6027.1 6032.4 6038.5 5836.1 5884.4 5890.2 5893.8 5895.1 5628.4 5849.5 5862.6 5869.2 5870.5 5690.7 5750.8 5802.2 5806.2 5900.0 5877.4 5892.3 5895.1 5898.8 5900.6 5522.3 5567.5 5753.7 5754.9 5756.9 04:36:40 INFO - PROCESS | 1982 | 04:36:40 INFO - PROCESS | 1982 | [#1] ext.html Cycles:180 Average:51523.57 Median:3728.35 stddev:89259.73 (2394.1%) stddev-sans-first:89428.22 04:36:40 INFO - PROCESS | 1982 | Values: 592.0 623.9 630.5 646.6 650.0 36579.6 46415.3 47491.3 47566.5 51258.0 47913.5 48246.0 48315.3 48436.5 48511.0 8503.2 8676.7 9366.3 9541.7 9543.7 11025.6 11293.7 11314.6 11737.4 11754.3 11398.7 11496.6 11500.9 11504.8 11521.3 8849.6 9097.1 11141.4 11316.5 11397.2 10714.3 11108.2 11127.7 11127.7 11132.8 45728.3 46168.5 46219.3 46246.5 46301.0 3704.6 3716.3 3740.3 3751.8 3919.5 34129.3 34139.5 34230.5 34313.6 34317.0 4652.3 4719.6 4740.6 4748.3 4750.3 350481.0 353135.0 353605.0 353701.0 354602.0 62105.4 62477.0 62543.7 62686.7 62688.4 64659.0 64663.4 64683.0 64710.0 64747.0 17980.0 18639.7 18645.5 18647.2 18650.3 13141.0 13418.7 14083.6 14487.3 14762.6 17301.6 17347.6 17358.8 17366.8 17474.7 6258.5 6349.9 6368.5 6382.8 6398.3 1012.0 1073.6 1115.0 1154.6 1180.5 33405.3 33437.2 33631.2 33685.5 34018.1 1105.8 1131.4 1163.9 1170.3 1220.4 17501.6 17656.2 17684.6 17775.7 17786.5 157991.0 158257.0 158346.2 158706.0 160005.0 61664.7 61866.4 61969.0 62144.0 62173.4 1314.4 1364.6 1415.3 1416.9 1467.0 30190.1 33949.0 34641.3 34641.5 35006.2 51935.0 52458.7 52538.7 52583.5 52590.0 436671.0 438518.0 438754.0 440713.0 440965.0 29723.6 29955.0 30397.2 31852.4 43924.8 50431.7 50592.7 50728.5 51329.0 51953.7 42843.1 43008.1 43076.8 43153.8 44951.8 42009.2 42330.8 42831.1 42944.4 44275.8 42033.8 42469.0 43353.0 43671.3 43684.3 35250.3 37993.0 38181.2 38534.0 38977.2 29077.7 29144.1 29160.9 29168.4 29210.0 04:36:40 INFO - PROCESS | 1982 | 04:36:40 INFO - PROCESS | 1982 | [#2] jquery.html Cycles:180 Average:29985.31 Median:2689.85 stddev:82637.72 (3072.2%) stddev-sans-first:82839.66 04:36:40 INFO - PROCESS | 1982 | Values: 386.5 429.2 431.5 439.8 444.3 16945.3 19968.5 20432.7 21063.0 21214.8 19585.2 19835.3 20157.7 20205.0 20300.0 5889.8 5891.9 6132.5 6137.2 6146.1 6022.1 6051.8 6107.3 6107.6 6110.8 5836.2 6013.3 6059.8 6062.0 6063.7 6021.2 6021.8 6050.4 6061.1 6062.9 5294.6 5402.2 5449.8 5454.3 5459.0 24349.7 24665.5 24685.5 24741.9 25183.2 2526.0 2611.3 2670.2 2671.3 2708.4 8125.0 14927.9 15909.0 15929.1 16614.9 1979.0 2003.6 2012.5 2035.5 2085.2 88358.1 89760.0 90016.0 91219.0 91686.5 10104.6 11048.3 11263.7 11345.9 11585.1 14366.1 15072.5 15116.2 15117.2 15148.5 8365.8 8767.9 8768.3 8814.4 8884.2 8460.0 8498.6 8532.1 8669.0 8763.2 4951.6 5036.3 5047.1 5240.0 5287.9 2073.6 2077.7 2123.6 2138.1 2145.3 928.9 941.2 998.0 1047.3 1080.5 19839.6 19901.6 20011.0 20178.4 20276.6 974.4 986.7 987.5 996.7 1004.6 11092.3 11285.3 11567.4 11573.2 11581.6 487007.0 500158.0 502454.0 505309.0 508159.0 89612.0 90180.0 91193.5 91385.5 91482.1 1036.8 1088.9 1109.5 1120.5 1124.9 17563.5 17719.5 17901.1 18171.4 18321.3 29342.7 32378.4 42199.0 43554.6 43936.6 59017.4 61598.7 67178.0 67324.0 68626.3 8807.7 9153.3 9200.9 9288.7 9320.3 13087.5 13131.0 13406.7 13543.3 13635.5 11465.9 11588.6 11665.3 11705.1 11957.9 11281.4 11576.7 11604.7 11630.4 11892.1 11649.0 11661.6 11698.5 11821.3 12536.6 8036.1 8287.0 8955.5 9173.4 9445.1 10931.4 11491.8 11623.7 12279.5 12281.4 04:36:40 INFO - PROCESS | 1982 | 04:36:40 INFO - PROCESS | 1982 | [#3] mootools.html Cycles:180 Average:6352.75 Median:2579.90 stddev:3669.97 (142.3%) stddev-sans-first:3661.43 04:36:40 INFO - PROCESS | 1982 | Values: 1406.2 1510.9 1523.4 1701.0 1864.6 8374.7 9402.3 9629.8 9641.2 9977.6 9667.6 9738.6 9753.7 9756.4 9764.4 2556.4 2576.8 2577.8 2580.1 2581.7 2523.1 2579.9 2580.4 2582.7 2583.1 2531.8 2547.5 2604.0 2608.1 2611.6 2540.0 2553.7 2568.1 2570.5 2572.9 2435.9 2462.9 2468.8 2519.6 2524.9 9461.7 9489.2 9677.8 9701.1 9712.1 5690.0 5900.7 5940.6 5955.8 5961.2 3643.2 3669.2 3719.4 3726.9 3731.9 1207.1 1219.5 1239.3 1239.5 1275.4 13453.1 13539.9 13556.5 13580.8 13629.6 4839.1 4849.4 4940.8 5017.7 5067.4 10816.5 10822.5 10822.7 10836.3 10837.0 5816.5 5818.3 5828.4 5838.2 5839.2 4343.9 4541.8 4595.3 4650.6 5435.7 4196.6 4252.4 4328.0 4390.3 4393.9 1265.1 1288.5 1296.1 1299.1 1312.4 936.5 979.7 981.1 985.0 992.8 6311.8 6466.6 6573.8 6694.4 6715.7 467.9 468.5 476.9 482.1 483.1 4389.0 4478.6 4503.8 4516.3 4682.9 11932.4 12530.6 12546.1 12556.2 12567.7 12115.1 12141.0 12200.8 12279.0 12363.2 8370.3 9126.1 9131.7 9210.7 9223.2 6499.7 6568.0 6615.4 6617.8 6901.2 10632.3 10787.9 11047.7 11083.1 11090.2 10094.1 10424.6 11051.9 11152.5 11190.4 7503.6 8324.2 8571.9 8644.9 8816.8 9423.8 9750.6 9811.7 9832.7 9842.2 8312.2 8367.5 8592.2 8957.5 8976.3 8475.9 8477.2 8540.0 8553.3 8729.1 7822.8 7834.0 7956.6 8042.9 8045.5 5613.9 5628.6 5633.3 5678.6 5768.6 5709.1 5797.6 5870.2 5923.7 5972.9 04:36:40 INFO - PROCESS | 1982 | 04:36:40 INFO - PROCESS | 1982 | [#4] prototype.html Cycles:180 Average:4753.88 Median:5280.41 stddev:1390.38 (26.3%) stddev-sans-first:1354.32 04:36:40 INFO - PROCESS | 1982 | Values: 344.6 351.8 353.1 357.1 360.8 5532.1 5559.5 5561.5 5562.9 5570.3 5445.8 5466.3 5536.8 5543.7 5555.1 3792.8 4084.0 4233.5 4322.2 4322.6 4301.6 4314.3 4318.2 4318.8 4323.3 4283.4 4290.4 4313.7 4328.1 4352.9 4043.7 4108.7 4227.0 4260.3 4282.3 3755.6 3780.0 3783.5 3790.5 3822.3 5306.8 5308.5 5338.5 5364.8 5398.1 4789.0 4889.5 4899.9 4924.7 4941.4 5675.7 5676.4 5684.0 5686.5 5699.4 4179.7 4183.8 4187.7 4191.4 4218.2 5607.4 5749.2 5764.5 5917.7 5933.7 4173.0 4301.8 4305.4 4307.6 4332.4 4378.0 4378.1 4385.4 4405.8 4413.8 4848.3 4913.8 4952.6 4970.6 4976.6 5682.7 5687.2 5688.6 5727.5 5738.9 1494.6 1508.8 1516.8 1517.2 1518.9 946.5 984.2 988.3 992.0 1014.3 4862.7 4912.1 4922.9 4929.4 4963.2 5153.8 5157.9 5158.5 5163.2 5168.7 4591.4 4857.0 4870.6 4947.9 4953.8 2733.0 2734.2 2734.9 2743.8 2745.0 5201.3 5258.0 5277.9 5282.4 5323.7 5276.6 5278.4 5616.2 6099.8 6113.4 6101.3 6118.1 6131.2 6135.0 6146.4 5647.4 5930.7 5933.4 5940.8 5949.8 5821.6 5823.8 5848.6 5875.7 5889.4 5794.7 5897.6 5918.8 6088.5 6099.0 4191.9 4210.3 4211.5 4232.0 4238.6 5770.7 5908.7 5962.5 5962.7 5971.0 5719.7 5775.7 5777.4 5794.6 5804.0 5753.4 5756.4 5762.9 5769.8 5793.7 5533.0 5648.7 5747.6 5770.0 5787.6 5742.8 5788.9 5790.9 5794.2 5802.9 5653.7 5674.4 5680.2 5682.7 5689.3 04:36:40 INFO - PROCESS | 1982 | 04:36:40 INFO - PROCESS | 1982 | [#5] yui.html Cycles:180 Average:22717.03 Median:4692.52 stddev:32681.34 (696.5%) stddev-sans-first:32731.68 04:36:40 INFO - PROCESS | 1982 | Values: 823.0 852.7 853.7 883.7 921.0 15523.3 16670.4 16734.2 16738.8 16747.7 16076.4 16087.4 16205.2 16243.5 16248.8 709.7 731.8 850.6 855.3 887.8 877.5 879.7 881.1 881.8 882.1 907.7 908.6 909.1 910.2 911.1 751.7 772.3 886.2 902.2 916.5 671.2 672.0 672.7 673.4 673.5 15820.8 15847.0 15894.0 15922.1 15940.9 14896.7 14944.4 15131.4 15219.9 15472.8 14355.5 14396.5 14456.0 14638.1 14702.3 4622.9 4678.7 4706.4 4748.6 4796.5 90035.1 91351.0 91391.5 91564.5 91667.5 12165.5 12175.7 12200.6 12201.9 12202.6 22445.7 22550.5 22655.4 22695.7 23295.8 8899.6 9048.7 9126.7 9168.0 9310.5 4944.2 4993.9 5121.3 5122.4 5211.3 3399.7 3497.2 3542.1 3548.6 3597.9 509.2 597.7 601.7 615.5 620.3 737.3 791.6 802.9 809.1 817.3 9736.4 9988.5 10021.6 10267.0 10353.7 728.8 763.5 781.1 782.2 785.6 5006.4 5121.2 5126.9 5176.1 5216.7 82827.6 124092.4 129656.0 131815.0 136933.3 94318.0 96152.0 116639.0 118899.0 120951.0 79443.2 82100.0 87890.0 90441.5 93404.0 9513.4 10026.2 10233.4 11436.7 11472.5 56565.7 57348.0 57501.8 57543.7 59013.0 55638.2 85067.6 86127.1 86755.0 90681.5 13873.4 14514.3 15520.8 16165.4 16211.3 14377.7 14860.6 14894.8 17767.5 17831.7 14051.0 14336.2 14959.3 15457.9 15472.1 15606.0 15855.5 16180.2 16823.7 17406.6 14016.5 14604.6 14687.9 14870.6 15466.7 12125.6 12490.0 12843.2 13051.4 13770.9 12195.5 12669.6 12674.6 12678.3 13002.5 04:36:40 INFO - PROCESS | 1982 | -------- Summary: end -------- 04:36:40 INFO - PROCESS | 1982 | 04:36:41 INFO - TEST-INFO | 1982: exit 0 04:36:41 INFO - TEST-OK | dromaeo_css | took 1113490ms 04:36:41 INFO - TEST-START | kraken 04:36:41 INFO - operating with platform_type : linux_ 04:36:41 INFO - Initialising browser for kraken test... 04:36:41 INFO - TEST-INFO | started process 2303 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpokJ3VR/profile http://localhost:38340/getInfo.html) 04:36:44 INFO - PROCESS | 2303 | __metrics Screen width/height:1600/1200 04:36:44 INFO - PROCESS | 2303 | colorDepth:24 04:36:44 INFO - PROCESS | 2303 | Browser inner width/height: 1024/697 04:36:44 INFO - PROCESS | 2303 | __metrics 04:36:45 INFO - PROCESS | 2303 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:36:45 INFO - PROCESS | 2303 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:36:46 INFO - PROCESS | 2303 | console.error: PushService: 04:36:46 INFO - PROCESS | 2303 | stateChangeProcessEnqueue: Error transitioning state 04:36:46 INFO - PROCESS | 2303 | UnknownError 04:36:46 INFO - TEST-INFO | 2303: exit 0 04:36:46 INFO - Browser initialized. 04:36:46 INFO - Running cycle 1/1 for kraken test... 04:36:46 INFO - TEST-INFO | started process 2393 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpokJ3VR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:36:58 INFO - PROCESS | 2393 | RSS: Main: 144678912 04:36:58 INFO - PROCESS | 2393 | 04:37:00 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38340/tests/kraken/driver.html?testName=audio-beat-detection) 04:37:01 INFO - PROCESS | 2393 | RSS: Main: 186040320 04:37:01 INFO - PROCESS | 2393 | 04:37:04 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38340/tests/kraken/driver.html?testName=audio-dft) 04:37:04 INFO - PROCESS | 2393 | RSS: Main: 176427008 04:37:04 INFO - PROCESS | 2393 | 04:37:07 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38340/tests/kraken/driver.html?testName=audio-fft) 04:37:08 INFO - PROCESS | 2393 | RSS: Main: 267567104 04:37:08 INFO - PROCESS | 2393 | 04:37:10 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38340/tests/kraken/driver.html?testName=audio-oscillator) 04:37:10 INFO - PROCESS | 2393 | RSS: Main: 174555136 04:37:10 INFO - PROCESS | 2393 | 04:37:12 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38340/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:37:13 INFO - PROCESS | 2393 | RSS: Main: 175706112 04:37:13 INFO - PROCESS | 2393 | 04:37:16 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38340/tests/kraken/driver.html?testName=imaging-darkroom) 04:37:17 INFO - PROCESS | 2393 | RSS: Main: 211308544 04:37:17 INFO - PROCESS | 2393 | 04:37:20 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38340/tests/kraken/driver.html?testName=imaging-desaturate) 04:37:20 INFO - PROCESS | 2393 | RSS: Main: 210382848 04:37:20 INFO - PROCESS | 2393 | 04:37:23 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38340/tests/kraken/driver.html?testName=json-parse-financial) 04:37:24 INFO - PROCESS | 2393 | RSS: Main: 209936384 04:37:24 INFO - PROCESS | 2393 | 04:37:25 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38340/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:37:26 INFO - PROCESS | 2393 | RSS: Main: 172085248 04:37:26 INFO - PROCESS | 2393 | 04:37:28 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38340/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:37:28 INFO - PROCESS | 2393 | RSS: Main: 178896896 04:37:28 INFO - PROCESS | 2393 | 04:37:30 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38340/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:37:31 INFO - PROCESS | 2393 | RSS: Main: 188858368 04:37:31 INFO - PROCESS | 2393 | 04:37:33 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38340/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:37:34 INFO - PROCESS | 2393 | RSS: Main: 197447680 04:37:34 INFO - PROCESS | 2393 | 04:37:37 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38340/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:37:37 INFO - PROCESS | 2393 | RSS: Main: 194334720 04:37:37 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | Cycle 1(1): loaded http://localhost:38340/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38340/tests/kraken/driver.html?testName=ai-astar) 04:37:39 INFO - PROCESS | 2393 | RSS: Main: 245121024 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | __start_tp_report 04:37:39 INFO - PROCESS | 2393 | _x_x_mozilla_page_load 04:37:39 INFO - PROCESS | 2393 | _x_x_mozilla_page_load_details 04:37:39 INFO - PROCESS | 2393 | |i|pagename|runs| 04:37:39 INFO - PROCESS | 2393 | |0;ai-astar;109;113;111;111;111;112;112;112;111;112 04:37:39 INFO - PROCESS | 2393 | |1;audio-beat-detection;145;119;117;158;126;116;120;121;143;117 04:37:39 INFO - PROCESS | 2393 | |2;audio-dft;161;161;162;161;162;161;164;161;161;161 04:37:39 INFO - PROCESS | 2393 | |3;audio-fft;75;75;74;109;76;76;76;74;99;76 04:37:39 INFO - PROCESS | 2393 | |4;audio-oscillator;82;82;113;82;82;81;101;80;81;119 04:37:39 INFO - PROCESS | 2393 | |5;imaging-gaussian-blur;117;116;117;117;116;117;117;117;117;116 04:37:39 INFO - PROCESS | 2393 | |6;imaging-darkroom;145;146;146;145;146;146;145;146;146;146 04:37:39 INFO - PROCESS | 2393 | |7;imaging-desaturate;95;97;95;94;94;94;95;95;94;94 04:37:39 INFO - PROCESS | 2393 | |8;json-parse-financial;50;55;48;48;49;49;49;50;49;50 04:37:39 INFO - PROCESS | 2393 | |9;json-stringify-tinderbox;59;59;59;61;60;60;60;60;61;59 04:37:39 INFO - PROCESS | 2393 | |10;stanford-crypto-aes;74;74;74;75;77;74;74;75;73;76 04:37:39 INFO - PROCESS | 2393 | |11;stanford-crypto-ccm;122;125;124;119;121;123;129;120;158;127 04:37:39 INFO - PROCESS | 2393 | |12;stanford-crypto-pbkdf2;152;156;154;158;154;152;153;153;153;154 04:37:39 INFO - PROCESS | 2393 | |13;stanford-crypto-sha256-iterative;64;62;65;63;63;63;63;62;61;61 04:37:39 INFO - PROCESS | 2393 | __end_tp_report 04:37:39 INFO - PROCESS | 2393 | __start_cc_report 04:37:39 INFO - PROCESS | 2393 | _x_x_mozilla_cycle_collect,1610 04:37:39 INFO - PROCESS | 2393 | __end_cc_report 04:37:39 INFO - PROCESS | 2393 | __startTimestamp1478173059597__endTimestamp 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | ------- Summary: start ------- 04:37:39 INFO - PROCESS | 2393 | Number of tests: 14 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:1.07 (1.0%) stddev-sans-first:0.71 04:37:39 INFO - PROCESS | 2393 | Values: 109.0 113.0 111.0 111.0 111.0 112.0 112.0 112.0 111.0 112.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:123.50 stddev:14.90 (12.1%) stddev-sans-first:14.51 04:37:39 INFO - PROCESS | 2393 | Values: 145.0 119.0 117.0 158.0 126.0 116.0 120.0 121.0 143.0 117.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 04:37:39 INFO - PROCESS | 2393 | Values: 161.0 161.0 162.0 161.0 162.0 161.0 164.0 161.0 161.0 161.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:12.37 (16.3%) stddev-sans-first:12.93 04:37:39 INFO - PROCESS | 2393 | Values: 75.0 75.0 74.0 109.0 76.0 76.0 76.0 74.0 99.0 76.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:81.50 stddev:14.94 (18.3%) stddev-sans-first:15.54 04:37:39 INFO - PROCESS | 2393 | Values: 82.0 82.0 113.0 82.0 82.0 81.0 101.0 80.0 81.0 119.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 04:37:39 INFO - PROCESS | 2393 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 04:37:39 INFO - PROCESS | 2393 | Values: 145.0 146.0 146.0 145.0 146.0 146.0 145.0 146.0 146.0 146.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.95 (1.0%) stddev-sans-first:1.00 04:37:39 INFO - PROCESS | 2393 | Values: 95.0 97.0 95.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:49.50 stddev:2.00 (4.0%) stddev-sans-first:2.12 04:37:39 INFO - PROCESS | 2393 | Values: 50.0 55.0 48.0 48.0 49.0 49.0 49.0 50.0 49.0 50.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.79 (1.3%) stddev-sans-first:0.78 04:37:39 INFO - PROCESS | 2393 | Values: 59.0 59.0 59.0 61.0 60.0 60.0 60.0 60.0 61.0 59.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.50 stddev:1.17 (1.6%) stddev-sans-first:1.22 04:37:39 INFO - PROCESS | 2393 | Values: 74.0 74.0 74.0 75.0 77.0 74.0 74.0 75.0 73.0 76.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:124.50 stddev:11.39 (9.1%) stddev-sans-first:11.95 04:37:39 INFO - PROCESS | 2393 | Values: 122.0 125.0 124.0 119.0 121.0 123.0 129.0 120.0 158.0 127.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.90 Median:154.00 stddev:1.85 (1.2%) stddev-sans-first:1.83 04:37:39 INFO - PROCESS | 2393 | Values: 152.0 156.0 154.0 158.0 154.0 152.0 153.0 153.0 153.0 154.0 04:37:39 INFO - PROCESS | 2393 | 04:37:39 INFO - PROCESS | 2393 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.25 (2.0%) stddev-sans-first:1.24 04:37:39 INFO - PROCESS | 2393 | Values: 64.0 62.0 65.0 63.0 63.0 63.0 63.0 62.0 61.0 61.0 04:37:39 INFO - PROCESS | 2393 | -------- Summary: end -------- 04:37:39 INFO - PROCESS | 2393 | 04:37:40 INFO - TEST-INFO | 2393: exit 0 04:37:40 INFO - TEST-OK | kraken | took 59733ms 04:37:40 INFO - SUITE-END | took 1173s 04:37:41 INFO - Completed test suite (00:19:33) 04:37:41 INFO - javascript benchmark 04:37:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3111.9688803111967, 3144.7326977206935, 3167.857446414911, 3189.968100318996, 3199.808011519309, 5499.752511136999, 5540.390557038722, 5544.1960915667205, 5565.332160140783, 5580.135079062741, 5611.7474713637885, 5684.175794509793, 5687.402822703608, 5693.77224911003, 5735.713214339276, 5103.106956282654, 5128.461511541291, 5160.096983027974, 5204.141316682742, 5208.0885844977165, 4978.029284289565, 4991.151504244287, 5168.715720635363, 5215.113430716769, 5217.686938783685, 4998.750062496861, 4998.825041123561, 5011.649184557083, 5053.6715113517685, 5088.694678319294, 5098.745062746866, 5114.335136432277, 5201.5838732901275, 5201.713905735183, 5233.058049551079, 4859.052484765472, 5065.949340506585, 5081.136206844845, 5122.846314610568, 5131.3329267195395, 5453.127499600045, 5455.399906010336, 5507.531859791922, 5525.806596769134, 5528.585356098269, 5937.554683398763, 5944.197533332988, 6057.545684073668, 6059.697015149269, 6071.635701857903, 5806.332271788756, 6052.727627256784, 6072.696365181768, 6079.726412311456, 6080.695965201767, 5432.6468779529205, 5545.417731138253, 5587.888242235133, 5588.91616625751, 5609.859753506195, 6175.413335733098, 6178.258608966953, 6191.164192834, 6191.690415479208, 6193.535484838565, 5657.264555607745, 5927.3776253493625, 5932.317783454958, 5937.851553711192, 5938.49522790559, 5798.652080875161, 5862.384449632727, 5915.1718759373725, 5933.050711886078, 6008.429199226067, 5940.108983652401, 5942.3166335871065, 5949.791757288474, 5949.940500595025, 5951.315598706117, 5907.675077870673, 5962.880742385215, 5963.045912654041, 5971.701414929236, 5989.700514974234, 4537.228671125917, 4542.613877820356, 4563.178627846953, 4574.199515084847, 4614.861554153381, 4048.838046478108, 4063.6342729153926, 4070.023194433374, 4089.8977525561504, 4104.753714777123, 5581.274434323594, 5582.748776304995, 5586.273784408001, 5588.720563971785, 5617.157426386075, 5750.4249575042995, 5791.768329266866, 5932.406759324033, 6075.33171351151, 6123.130109042722, 5805.303363596396, 5806.303243610793, 5811.593188476766, 5818.8254352369495, 5842.152887831241, 5270.631055826132, 5279.709615971082, 5281.049411105961, 5284.233786100994, 5284.339457567804, 5964.910526342108, 6087.208662873798, 6095.2685677718955, 6310.6213627182515, 6320.115183874262, 5859.472647461749, 6014.977453832771, 6025.969870150621, 6050.42520960508, 6056.727447264796, 6154.969225153845, 6159.29168145669, 6164.229471316085, 6169.043798211284, 6202.906956395567, 5522.06124958766, 5687.488126068673, 5882.911756323573, 5897.587168898136, 5899.115132729953, 5970.97014514942, 5991.281046274301, 6027.065804800263, 6032.366601506778, 6038.486728628116, 5836.095405212199, 5884.382139875423, 5890.175375447355, 5893.764249429974, 5895.086261629454, 5628.437156284339, 5849.532037436909, 5862.648241105548, 5869.178315035813, 5870.5010074144175, 5690.687012214368, 5750.827475175752, 5802.187693722798, 5806.2451881255165, 5900.0, 5877.382874798255, 5892.322382925847, 5895.086261629454, 5898.7935422261735, 5900.64596124234, 5522.337319521683, 5567.526760225426, 5753.741081651252, 5754.9136762947755, 5756.856078597901], "value": 5510.857909460288, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.9822405327839, 623.8814625221207, 630.464105510316, 646.5571083807589, 650.0282078292952, 36579.63420365796, 46415.303770443425, 47491.28763068548, 47566.52433475664, 51258.0, 47913.52086479134, 48246.0, 48315.27527087097, 48436.51563484364, 48511.0, 8503.1921967413, 8676.739697809076, 9366.297527685418, 9541.666041688542, 9543.713688589354, 11025.558977640885, 11293.717657058558, 11314.603988860392, 11737.413129343542, 11754.29474231549, 11398.715032124222, 11496.597619083333, 11500.884991150066, 11504.827427588592, 11521.251118677259, 8849.601767920394, 9097.13577210164, 11141.442927853575, 11316.547338106466, 11397.202195846294, 10714.250002499828, 11108.16688748347, 11127.72180695481, 11127.72180695481, 11132.833007504894, 45728.314075288894, 46168.53831461676, 46219.306710399374, 46246.53753462456, 46301.0, 3704.5554533456157, 3716.349638813197, 3740.3454395480953, 3751.7936513491745, 3919.510061242345, 34129.31741365159, 34139.4879076814, 34230.48654270188, 34313.627454901776, 34317.0, 4652.255639097762, 4719.575238228542, 4740.644451666139, 4748.264019077049, 4750.334953106568, 350481.0, 353135.0, 353605.0, 353701.0, 354602.0, 62105.37894621086, 62477.0, 62543.6872815633, 62686.68656656779, 62688.37311626917, 64659.0, 64663.353366466676, 64683.0, 64710.0, 64747.0, 17980.0, 18639.720404193948, 18645.533861653297, 18647.160877760532, 18650.34723784661, 13141.0, 13418.731625367438, 14083.647908802363, 14487.275636218146, 14762.557123286319, 17301.56746081358, 17347.566310842078, 17358.826411735972, 17366.826331736775, 17474.65050698979, 6258.468030217391, 6349.873002539924, 6368.490520758421, 6382.84042898922, 6398.2641996170105, 1011.9949400252951, 1073.5598404654054, 1115.0117684605336, 1154.6247469572254, 1180.5218886351042, 33405.33189336248, 33437.16407089842, 33631.15922101918, 33685.494717579255, 34018.136226089766, 1105.7788442311569, 1131.422974283105, 1163.8661553921238, 1170.2861254634665, 1220.3593113615245, 17501.649967000587, 17656.20547075385, 17684.557886052695, 17775.73336399955, 17786.46640600784, 157991.0, 158257.0, 158346.2082689579, 158706.0, 160005.0, 61664.69167654223, 61866.38133618606, 61969.0, 62144.0, 62173.37826621766, 1314.4019471140607, 1364.6315494816345, 1415.2640626874174, 1416.8937329700314, 1466.9633259168393, 30190.09429717112, 33949.0, 34641.30717385588, 34641.48037779385, 35006.233020606705, 51935.0, 52458.73770631275, 52538.737306313225, 52583.47416525786, 52590.0, 436671.0, 438518.0, 438754.0, 440713.0, 440965.0, 29723.554146687384, 29955.0, 30397.240068998453, 31852.362952741278, 43924.780376097915, 50431.747841260556, 50592.74703626605, 50728.492715072374, 51329.0, 51953.740231300115, 42843.14313713646, 43008.139837202456, 43076.78461607672, 43153.784231078644, 44951.775241123585, 42009.15981680288, 42330.78834605807, 42831.1433771329, 42944.35583466313, 44275.778621106685, 42033.78983105065, 42469.0, 43353.0, 43671.34492982544, 43684.34473482964, 35250.29499409946, 37993.0, 38181.23637527179, 38534.0, 38977.2204555913, 29077.7092229075, 29144.125676229745, 29160.854195728887, 29168.416631666823, 29210.0], "value": 20765.260752301205, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.4917633312196, 429.24553726743073, 431.4520558890209, 439.8174757475648, 444.29800914555005, 16945.322187112517, 19968.5007874803, 20432.693509597368, 21063.0, 21214.787852121473, 19585.216591336364, 19835.305764298253, 20157.697634535492, 20205.0, 20300.0, 5889.793857214999, 5891.852703682421, 6132.50939924805, 6137.17148184996, 6146.078088286748, 6022.096685497188, 6051.75792968283, 6107.267127944653, 6107.603005804608, 6110.786122485714, 5836.153757705151, 6013.278406591194, 6059.818205453844, 6061.969690151543, 6063.696815159246, 6021.157038014681, 6021.789237376693, 6050.3647117052515, 6061.090836374535, 6062.939370606304, 5294.57643388528, 5402.162664786964, 5449.809256676037, 5454.263674403984, 5459.044667183227, 24349.715664139876, 24665.506689866103, 24685.50628987428, 24741.87629061843, 25183.244502664766, 2526.027479420418, 2611.3341098020073, 2670.1588999465303, 2671.305460580263, 2708.404151086758, 8124.984376952881, 14927.850721492754, 15909.0, 15929.123898185711, 16614.916925415415, 1978.99010504948, 2003.6493613617704, 2012.466696325475, 2035.4707775978352, 2085.1971990783504, 88358.11641883563, 89759.99999999935, 90016.0, 91219.0, 91686.5415672924, 10104.595816167272, 11048.337099774039, 11263.66209013731, 11345.886541134649, 11585.131115166228, 14366.13803171813, 15072.472463463726, 15116.244187790566, 15117.16855572954, 15148.469803556822, 8365.790855228668, 8767.868481972775, 8768.25469835071, 8814.427062240933, 8884.244839188612, 8460.0, 8498.575071246412, 8532.146785321418, 8668.956655216809, 8763.167499087576, 4951.554360107608, 5036.269740887551, 5047.116754567936, 5240.030594340059, 5287.894242115213, 2073.5956488484903, 2077.740282464692, 2123.6283650361434, 2138.134055707441, 2145.3027765975808, 928.8560273157672, 941.2281928818303, 997.9920280516731, 1047.2878442659064, 1080.5191689698165, 19839.611565478725, 19901.60196796056, 20011.0, 20178.394648160578, 20276.594468110554, 974.4202199691173, 986.6941248213077, 987.4667679453015, 996.7358649957768, 1004.5730564510054, 11092.279001864854, 11285.266457680225, 11567.363794991355, 11573.189876708719, 11581.594644187413, 487007.0, 500158.0, 502454.0, 505309.0, 508159.0, 89612.0, 90180.0, 91193.54403228074, 91385.54307228421, 91482.08517914735, 1036.8496567997597, 1088.8638920134983, 1109.4840898981852, 1120.4845770945456, 1124.9043831274269, 17563.473095807145, 17719.468415947544, 17901.10494475297, 18171.364002259856, 18321.267149313877, 29342.70657293485, 32378.352432950738, 42199.0, 43554.56445435632, 43936.560634393245, 59017.40982590291, 61598.692006541474, 67178.0, 67324.0, 68626.313736862, 8807.735767926972, 9153.267738580764, 9200.907990920274, 9288.674896378594, 9320.347575669639, 13087.476500939856, 13131.01517386219, 13406.731865362834, 13543.32283385807, 13635.454581816617, 11465.942670286595, 11588.594399195988, 11665.300081995107, 11705.122115841175, 11957.880421195676, 11281.379524126252, 11576.710582235512, 11604.709882253012, 11630.360330181582, 11892.10809189297, 11649.0, 11661.59184428575, 11698.532058717896, 11821.290722556674, 12536.623901282976, 8036.116027237116, 8287.046989596032, 8955.462672239686, 9173.40372875761, 9445.149936505748, 10931.398773067558, 11491.827622585835, 11623.709407264885, 12279.508819647472, 12281.385930703607], "value": 9657.369821521208, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.1633328169735, 1510.8791296696263, 1523.3830298729013, 1700.9829901700987, 1864.5711486358136, 8374.665013399457, 9402.341836071479, 9629.759256018602, 9641.180499657537, 9977.600895964171, 9667.56495957682, 9738.561764720587, 9753.707388778348, 9756.365836220659, 9764.414135151896, 2556.3736884463287, 2576.8196226264167, 2577.7680008799202, 2580.135654555726, 2581.6772903387077, 2523.129520315488, 2579.8968041278326, 2580.3936075022357, 2582.7029891562524, 2583.147561304765, 2531.8480891146496, 2547.4777670577487, 2604.0, 2608.074133682544, 2611.6082587611913, 2540.0, 2553.668023156987, 2568.0883286433327, 2570.5116027954627, 2572.909948151824, 2435.890384932682, 2462.9261122166363, 2468.7531246875346, 2519.6220566914926, 2524.886380112899, 9461.716148515556, 9489.193418559431, 9677.806443871154, 9701.126898579161, 9712.077352651488, 5690.0, 5900.675462849542, 5940.584159108864, 5955.761769529214, 5961.225040744719, 3643.1802844360072, 3669.2478042001326, 3719.423489359154, 3726.8695595654294, 3731.8693845715543, 1207.088648070701, 1219.548766956232, 1239.2874097394035, 1239.5289789879787, 1275.3623188405797, 13453.125546839521, 13539.864601353958, 13556.457741690321, 13580.796288055688, 13629.59111226655, 4839.104765618372, 4849.393825771778, 4940.777665005084, 5017.7491125443585, 5067.391912970394, 10816.459177041117, 10822.458877056115, 10822.729431764268, 10836.295640783324, 10837.0, 5816.47651711348, 5818.301803783573, 5828.388019257917, 5838.241028666246, 5839.211706419664, 4343.891402714894, 4541.772911354419, 4595.333676616939, 4650.558196971282, 5435.701036442954, 4196.580341965779, 4252.383404406344, 4327.978360108179, 4390.319500477431, 4393.890152746207, 1265.101777737798, 1288.4652869059234, 1296.0862591872708, 1299.064673435125, 1312.3700623700677, 936.5223735894607, 979.72567681048, 981.1316984468797, 984.975375615601, 992.7617371830709, 6311.779087731908, 6466.612003279724, 6573.769918052845, 6694.430973367221, 6715.664216789141, 467.91577516046755, 468.4519112638221, 476.8974670445871, 482.1081000149695, 483.07009007660815, 4389.0, 4478.619317358061, 4503.774811259424, 4516.322551617218, 4682.906341873144, 11932.403379830974, 12530.561430349893, 12546.121771476091, 12556.246625202517, 12567.748645027048, 12115.091368147248, 12141.0, 12200.75598488043, 12279.0, 12363.196392234657, 8370.288525475282, 9126.13301736334, 9131.68039118641, 9210.677626283183, 9223.216026637676, 6499.6750162492635, 6568.047633093271, 6615.4376877965915, 6617.8014659560295, 6901.171859376706, 10632.25574209798, 10787.892121078688, 11047.72380690457, 11083.113350932068, 11090.168237382068, 10094.141997929964, 10424.635137770141, 11051.944740276247, 11152.498137583665, 11190.384528850991, 7503.587302698185, 8324.209200125979, 8571.928508936382, 8644.91355086441, 8816.823663526822, 9423.811523769624, 9750.609975600897, 9811.656592019244, 9832.705018849447, 9842.163416109712, 8312.168783121639, 8367.49385110686, 8592.226699597066, 8957.507337096522, 8976.281897448058, 8475.915240847513, 8477.194666506672, 8539.957300213458, 8553.315734741082, 8729.127087291221, 7822.804429889071, 7833.960830195812, 7956.6419511120985, 8042.919570804217, 8045.477043992122, 5613.943860561505, 5628.634138780966, 5633.324001119728, 5678.574106941914, 5768.567357448124, 5709.115087161496, 5797.59416840834, 5870.236869206976, 5923.733431995484, 5972.910406343822], "value": 4957.360743572893, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.5572439415352, 351.78716876289843, 353.0643793946044, 357.119699939649, 360.75108175359014, 5532.1425179097205, 5559.527440167585, 5561.5272701820395, 5562.888742225153, 5570.275864137657, 5445.809396671118, 5466.289382380295, 5536.833894983147, 5543.695096769676, 5555.138953462209, 3792.7534710243744, 4083.9795801020955, 4233.534311225762, 4322.222000039991, 4322.610965013148, 4301.612854843063, 4314.3097104463295, 4318.222719910431, 4318.784060796947, 4323.329883867989, 4283.443152390185, 4290.356446533014, 4313.741175529462, 4328.134373125372, 4352.869413917588, 4043.716939814214, 4108.67130629549, 4226.957730422702, 4260.297050986584, 4282.250606143913, 3755.586885442599, 3779.99660180105, 3783.5459744830796, 3790.4882840816413, 3822.2737679840743, 5306.779440728654, 5308.46915308466, 5338.466153384669, 5364.758585863646, 5398.136298192309, 4789.0, 4889.533140058004, 4899.8775030624165, 4924.729139897304, 4941.407031156248, 5675.659460432387, 5676.403977582336, 5683.971580142114, 5686.459786320293, 5699.401562835884, 4179.707420480568, 4183.832646694129, 4187.65491324856, 4191.371294305848, 4218.177455396199, 5607.383187849333, 5749.1663708762, 5764.481196692276, 5917.733701983421, 5933.673647949361, 4173.0, 4301.827926882889, 4305.418768466279, 4307.633851122627, 4332.393464914914, 4377.956220437819, 4378.146261479044, 4385.399329244867, 4405.7797110145, 4413.757243351646, 4848.321235027099, 4913.754312284372, 4952.603791696655, 4970.627202959792, 4976.552110310017, 5682.7442765075675, 5687.175359572839, 5688.601797874192, 5727.5131613812455, 5738.942610573924, 1494.5815171751926, 1508.8381945901594, 1516.8483151684743, 1517.2262146305463, 1518.9012714173543, 946.4936259101348, 984.158544444504, 988.3032462114193, 991.9900800991898, 1014.2747935226201, 4862.708237505761, 4912.091263116335, 4922.901541969212, 4929.433115191727, 4963.230699241623, 5153.793848246103, 5157.896842063138, 5158.484151584811, 5163.225516172604, 5168.715720635326, 4591.380163677927, 4856.95143048565, 4870.634702397264, 4947.851564453073, 4953.777080031407, 2733.0, 2734.1681073680684, 2734.931626709348, 2743.7530622244094, 2745.0, 5201.307071978862, 5258.0, 5277.947220527745, 5282.392524859613, 5323.680579165262, 5276.577873770166, 5278.419373868871, 5616.185653080281, 6099.817005489754, 6113.388661133851, 6101.328853826076, 6118.143459915705, 6131.202943617302, 6134.969325153345, 6146.385361463907, 5647.350554686263, 5930.703464826655, 5933.436323549256, 5940.762369505172, 5949.762009519571, 5821.592488525763, 5823.796167134299, 5848.619839710405, 5875.735591898289, 5889.440503152194, 5794.681292528951, 5897.616654917417, 5918.7928422505, 6088.543359247986, 6098.969505152446, 4191.895202619959, 4210.32634778434, 4211.53673095953, 4232.0, 4238.6397156242065, 5770.711464426678, 5908.675022873611, 5962.509372656836, 5962.672053036951, 5971.044632858721, 5719.656820590778, 5775.653460792366, 5777.364489906023, 5794.62334948227, 5804.01331773591, 5753.367129615823, 5756.42435756421, 5762.884742305214, 5769.76892939955, 5793.710314484175, 5533.0, 5648.661080335193, 5747.597668163188, 5769.96140694679, 5787.623804452697, 5742.827715168552, 5788.913166302593, 5790.913136302875, 5794.232759008542, 5802.854928626649, 5653.717314134359, 5674.404187560412, 5680.204771331934, 5682.659040457585, 5689.34572524165], "value": 4318.1396957717425, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [822.9711960081395, 852.6845067325089, 853.6798700487317, 883.6818745251712, 920.958556864941, 15523.301451434685, 16670.416535421264, 16734.163291835404, 16738.83261167388, 16747.665046699058, 16076.356945722186, 16087.436939707113, 16205.189740512986, 16243.51269461918, 16248.75626865598, 709.7196607340097, 731.7987553422824, 850.6002178975872, 855.2815634866711, 887.7514295997099, 877.4779006491127, 879.6921077622812, 881.1496905486205, 881.7707396077034, 882.0738224863867, 907.6732376344511, 908.5956749246554, 909.0545832334398, 910.1990248581272, 911.0752586125088, 751.6730222353294, 772.3010675338827, 886.2422628652538, 902.1970446302796, 916.4867674102524, 671.1912145864222, 671.9865602687968, 672.6838385958591, 673.3905815237218, 673.4511373230802, 15820.841791582052, 15847.0, 15894.046357218604, 15922.124283164421, 15940.920295398564, 14896.702065958729, 14944.40222391103, 15131.394744210218, 15219.923900380427, 15472.767908481383, 14355.497557585435, 14396.496122635657, 14456.0, 14638.121712697166, 14702.26488675562, 4622.930656040162, 4678.672492925497, 4706.364640773486, 4748.620110391161, 4796.472388037313, 90035.09964900398, 91351.0, 91391.54304228436, 91564.54217728869, 91667.54166229126, 12165.452554635063, 12175.695607609881, 12200.633980980407, 12201.877981220252, 12202.633920982385, 22445.663315050286, 22550.548989020455, 22655.433614159778, 22695.659565106536, 23295.76704232936, 8899.599518021705, 9048.683296084737, 9126.680566180152, 9168.0, 9310.487923164292, 4944.184209605447, 4993.900121997613, 5121.334226550599, 5122.385313762298, 5211.322528071327, 3399.6940275375337, 3497.2306092659574, 3542.079059444563, 3548.6273941236313, 3597.94603080954, 509.2132655047886, 597.7160848596968, 601.6720887116531, 615.4983688294026, 620.2928661326092, 737.340080627835, 791.600241877848, 802.9237222463856, 809.0574480730002, 817.284875733733, 9736.36713613627, 9988.450635215133, 10021.640586344964, 10267.0, 10353.74115647115, 728.7850084225166, 763.4999075605488, 781.1368437876065, 782.1748055801185, 785.6229010075195, 5006.3992320921725, 5121.206213036985, 5126.92309615356, 5176.1459359206065, 5216.66091704038, 82827.58586207031, 124092.37953810173, 129656.0, 131815.0, 136933.31533342268, 94318.0, 96152.0, 116639.0, 118899.0, 120951.0, 79443.20556794474, 82100.0, 87889.99999999872, 90441.54779226062, 93404.0, 9513.381630194015, 10026.197904167795, 10233.385996840214, 11436.656900293005, 11472.483738231633, 56565.71717141388, 57348.0, 57501.84996300134, 57543.712281438326, 59013.0, 55638.16542751781, 85067.57466212877, 86127.13872861191, 86755.0, 90681.54659226662, 13873.375698093812, 14514.34685439179, 15520.844791552392, 16165.353385864904, 16211.270492828087, 14377.712445751236, 14860.62848428798, 14894.776578351117, 17767.46697599074, 17831.73252401241, 14051.0, 14336.21150836714, 14959.25203739787, 15457.922710386376, 15472.14903180336, 15606.0, 15855.52433426999, 16180.190990450667, 16823.66352672964, 17406.564835879206, 14016.509422170175, 14604.561863143697, 14687.853121468648, 14870.553883383516, 15466.690666186838, 12125.636230912734, 12490.0, 12843.229406235656, 13051.412686429323, 13770.93114534421, 12195.512179513074, 12669.619911402673, 12674.619761407173, 12678.302693351954, 13002.479900804241], "value": 8218.216638571757, "unit": "score"}], "name": "dromaeo_css", "value": 7611.389314522428, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 111.0, 111.0, 111.0, 112.0, 112.0, 112.0, 111.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 117.0, 158.0, 126.0, 116.0, 120.0, 121.0, 143.0, 117.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 162.0, 161.0, 164.0, 161.0, 161.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 109.0, 76.0, 76.0, 76.0, 74.0, 99.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 113.0, 82.0, 82.0, 81.0, 101.0, 80.0, 81.0, 119.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 55.0, 48.0, 48.0, 49.0, 49.0, 49.0, 50.0, 49.0, 50.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0, 59.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 75.0, 77.0, 74.0, 74.0, 75.0, 73.0, 76.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 125.0, 124.0, 119.0, 121.0, 123.0, 129.0, 120.0, 158.0, 127.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 156.0, 154.0, 158.0, 154.0, 152.0, 153.0, 153.0, 153.0, 154.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 65.0, 63.0, 63.0, 63.0, 63.0, 62.0, 61.0, 61.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1457.0, "alertThreshold": 2.0}]} 04:37:41 INFO - Return code: 0 04:37:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:37:41 INFO - # TBPL SUCCESS # 04:37:41 INFO - Running post-action listener: _resource_record_post_action 04:37:41 INFO - [mozharness: 2016-11-03 11:37:41.391939Z] Finished run-tests step (success) 04:37:41 INFO - Running post-run listener: _resource_record_post_run 04:37:41 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 2134016; Write bytes: 358305792; Read time: 724; Write time: 200276 04:37:41 INFO - TinderboxPrint: CPU usage
13.0% 04:37:41 INFO - TinderboxPrint: I/O read bytes / time
2,134,016 / 724 04:37:41 INFO - TinderboxPrint: I/O write bytes / time
358,305,792 / 200,276 04:37:41 INFO - TinderboxPrint: CPU idle
8,265.9 (87.0%) 04:37:41 INFO - TinderboxPrint: CPU user
1,187.8 (12.5%) 04:37:41 INFO - TinderboxPrint: Swap in / out
0 / 0 04:37:41 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 108183552; Read time: 0; Write time: 67268 04:37:41 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 270336; Write bytes: 250122240; Read time: 200; Write time: 133008 04:37:42 INFO - Running post-run listener: _upload_blobber_files 04:37:42 INFO - Blob upload gear active. 04:37:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:37:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:37:42 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:37:42 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:37:42 INFO - (blobuploader) - INFO - Open directory for files ... 04:37:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:37:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:37:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:43 INFO - (blobuploader) - INFO - Done attempting. 04:37:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:37:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:37:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:44 INFO - (blobuploader) - INFO - Done attempting. 04:37:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:37:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:37:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:48 INFO - (blobuploader) - INFO - Done attempting. 04:37:48 INFO - (blobuploader) - INFO - Iteration through files over. 04:37:48 INFO - Return code: 0 04:37:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:37:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:37:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02e81725cbe88e2c471277284925793aeda3d39145a79570e8c2d651633fd7bf2c9eebd3d397a349d6e387998137ee6e53763ae02296e6a26c708524ccdb3620", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13d058a7b948faaf37aa056b6c53a656bc5ccf23255d2efe66b408b4e3d06be4646a35937a7a00599c3c8a2ad3b2995c991f235109597e1cbd3d36113a438607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84f6fe92717b6fb804c9bf1e0a7231db9786f88a5ef7f6778afde2be66362e542a223b8a7e93a79d9b1c26f62afcdaee097e4aada7ead4cd1a1ab19a5ff8747d"} 04:37:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:37:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:37:48 INFO - Contents: 04:37:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02e81725cbe88e2c471277284925793aeda3d39145a79570e8c2d651633fd7bf2c9eebd3d397a349d6e387998137ee6e53763ae02296e6a26c708524ccdb3620", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13d058a7b948faaf37aa056b6c53a656bc5ccf23255d2efe66b408b4e3d06be4646a35937a7a00599c3c8a2ad3b2995c991f235109597e1cbd3d36113a438607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84f6fe92717b6fb804c9bf1e0a7231db9786f88a5ef7f6778afde2be66362e542a223b8a7e93a79d9b1c26f62afcdaee097e4aada7ead4cd1a1ab19a5ff8747d"} 04:37:48 INFO - Running post-run listener: copy_logs_to_upload_dir 04:37:48 INFO - Copying logs to upload dir... 04:37:48 INFO - mkdir: /builds/slave/test/build/upload/logs 04:37:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.125654 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-11-03 04:37:48.513648) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 04:37:48.517604) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578 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-Aurora/sha512/02e81725cbe88e2c471277284925793aeda3d39145a79570e8c2d651633fd7bf2c9eebd3d397a349d6e387998137ee6e53763ae02296e6a26c708524ccdb3620", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13d058a7b948faaf37aa056b6c53a656bc5ccf23255d2efe66b408b4e3d06be4646a35937a7a00599c3c8a2ad3b2995c991f235109597e1cbd3d36113a438607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84f6fe92717b6fb804c9bf1e0a7231db9786f88a5ef7f6778afde2be66362e542a223b8a7e93a79d9b1c26f62afcdaee097e4aada7ead4cd1a1ab19a5ff8747d"} build_url:https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011533 build_url: 'https://queue.taskcluster.net/v1/task/e_IpDU6-TtCuDe3HBbYRsw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02e81725cbe88e2c471277284925793aeda3d39145a79570e8c2d651633fd7bf2c9eebd3d397a349d6e387998137ee6e53763ae02296e6a26c708524ccdb3620", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13d058a7b948faaf37aa056b6c53a656bc5ccf23255d2efe66b408b4e3d06be4646a35937a7a00599c3c8a2ad3b2995c991f235109597e1cbd3d36113a438607", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/84f6fe92717b6fb804c9bf1e0a7231db9786f88a5ef7f6778afde2be66362e542a223b8a7e93a79d9b1c26f62afcdaee097e4aada7ead4cd1a1ab19a5ff8747d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 04:37:48.544934) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 04:37:48.545482) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1478170711.768318-1076854578 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 04:37:48.627289) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 04:37:48.627619) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 04:37:48.627904) ========= ========= Total master_lag: 0.17 =========