builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1478950041.32 results: success (0) buildid: 20161112004002 builduid: 53b893d91b5d4ddba756de63dc7836a4 revision: 3d380055aaedfda2da14f5aee37eda8079105387 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:21.318921) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:21.319410) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:21.319719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:21.334634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:21.334931) ========= 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=ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-12 03:27:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-11-12 03:27:22 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095483 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:21.444323) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:21.444647) ========= 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=ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017643 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:21.496896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:21.497263) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3d380055aaedfda2da14f5aee37eda8079105387 --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 3d380055aaedfda2da14f5aee37eda8079105387 --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=ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-12 03:27:22,214 truncating revision to first 12 chars 2016-11-12 03:27:22,214 Setting DEBUG logging. 2016-11-12 03:27:22,214 attempt 1/10 2016-11-12 03:27:22,214 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3d380055aaed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-12 03:27:22,531 unpacking tar archive at: mozilla-aurora-3d380055aaed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664002 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:22.192497) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:22.192781) ========= script_repo_revision: 3d380055aaedfda2da14f5aee37eda8079105387 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:22.193153) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:22.193409) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-12 03:27:22.204445) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-11-12 03:27:22.204717) ========= /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=ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:27:23 INFO - MultiFileLogger online at 20161112 03:27:23 in /builds/slave/test 03:27:23 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 03:27:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:27:23 INFO - {'append_to_log': False, 03:27:23 INFO - 'base_work_dir': '/builds/slave/test', 03:27:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:27:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:27:23 INFO - 'branch': 'Mozilla-Aurora', 03:27:23 INFO - 'buildbot_json_path': 'buildprops.json', 03:27:23 INFO - 'config_files': ('talos/linux_config.py',), 03:27:23 INFO - 'default_actions': ('clobber', 03:27:23 INFO - 'read-buildbot-config', 03:27:23 INFO - 'download-and-extract', 03:27:23 INFO - 'populate-webroot', 03:27:23 INFO - 'create-virtualenv', 03:27:23 INFO - 'install', 03:27:23 INFO - 'run-tests'), 03:27:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:27:23 INFO - 'download_minidump_stackwalk': True, 03:27:23 INFO - 'download_symbols': 'ondemand', 03:27:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:27:23 INFO - 'tooltool.py': '/tools/tooltool.py', 03:27:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:27:23 INFO - '/tools/misc-python/virtualenv.py')}, 03:27:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:27:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:27:23 INFO - 'installer_path': 'installer.exe', 03:27:23 INFO - 'log_level': 'info', 03:27:23 INFO - 'log_name': 'talos', 03:27:23 INFO - 'log_to_console': True, 03:27:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:27:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:27:23 INFO - 'opt_config_files': (), 03:27:23 INFO - 'pip_index': False, 03:27:23 INFO - 'sps_profile': False, 03:27:23 INFO - 'sps_profile_interval': 0, 03:27:23 INFO - 'suite': 'dromaeojs', 03:27:23 INFO - 'system_bits': '32', 03:27:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:27:23 INFO - 'title': 'talos-linux64-ix-035', 03:27:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:27:23 INFO - 'use_talos_json': True, 03:27:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:27:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:27:23 INFO - 'work_dir': 'build'} 03:27:23 INFO - [mozharness: 2016-11-12 11:27:23.014281Z] Running clobber step. 03:27:23 INFO - Running pre-action listener: _resource_record_pre_action 03:27:23 INFO - Running main action method: clobber 03:27:23 INFO - rmtree: /builds/slave/test/build 03:27:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:27:23 INFO - Running post-action listener: _resource_record_post_action 03:27:23 INFO - [mozharness: 2016-11-12 11:27:23.481621Z] Finished clobber step (success) 03:27:23 INFO - [mozharness: 2016-11-12 11:27:23.481725Z] Running read-buildbot-config step. 03:27:23 INFO - Running pre-action listener: _resource_record_pre_action 03:27:23 INFO - Running main action method: read_buildbot_config 03:27:23 INFO - Using buildbot properties: 03:27:23 INFO - { 03:27:23 INFO - "project": "", 03:27:23 INFO - "product": "firefox", 03:27:23 INFO - "script_repo_revision": "production", 03:27:23 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:27:23 INFO - "repository": "", 03:27:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:27:23 INFO - "buildid": "20161112004002", 03:27:23 INFO - "stage_platform": "linux64-pgo", 03:27:23 INFO - "basedir": "/builds/slave/test", 03:27:23 INFO - "nightly_build": "True", 03:27:23 INFO - "buildnumber": 617, 03:27:23 INFO - "slavename": "talos-linux64-ix-035", 03:27:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:27:23 INFO - "platform": "ubuntu64_hw", 03:27:23 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:27:23 INFO - "branch": "mozilla-aurora", 03:27:23 INFO - "repo_path": "releases/mozilla-aurora", 03:27:23 INFO - "revision": "3d380055aaedfda2da14f5aee37eda8079105387", 03:27:23 INFO - "pgo_build": "True", 03:27:23 INFO - "builduid": "53b893d91b5d4ddba756de63dc7836a4", 03:27:23 INFO - "slavebuilddir": "test" 03:27:23 INFO - } 03:27:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 03:27:23 INFO - Running post-action listener: _resource_record_post_action 03:27:23 INFO - [mozharness: 2016-11-12 11:27:23.484226Z] Finished read-buildbot-config step (success) 03:27:23 INFO - [mozharness: 2016-11-12 11:27:23.484315Z] Running download-and-extract step. 03:27:23 INFO - Running pre-action listener: _resource_record_pre_action 03:27:23 INFO - Running main action method: download_and_extract 03:27:23 INFO - mkdir: /builds/slave/test/build/tests 03:27:23 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')]} 03:27:23 INFO - trying https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:27:23 INFO - Downloading https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/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 03:27:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/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 03:27:24 INFO - Downloaded 1361 bytes. 03:27:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:27:24 INFO - Using the following test package requirements: 03:27:24 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:27:24 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:27:24 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:27:24 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:24 INFO - u'jsshell-linux-x86_64.zip'], 03:27:24 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:27:24 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:27:24 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:27:24 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:27:24 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:27:24 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:24 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:27:24 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:27:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:27:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:27:24 INFO - Fetch https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:27:24 INFO - Expected file size: 21961920 03:27:28 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 03:27:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:27:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:27:28 INFO - Fetch https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:27:29 INFO - Expected file size: 21961920 03:27:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 03:27:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:27:34 INFO - Fetch https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 03:27:34 INFO - Expected file size: 14414089 03:27:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:35 INFO - trying https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:27:35 INFO - Downloading https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/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 03:27:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/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 03:27:37 INFO - Downloaded 64018187 bytes. 03:27:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:27:37 INFO - mkdir: /builds/slave/test/properties 03:27:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:27:37 INFO - Writing to file /builds/slave/test/properties/build_url 03:27:37 INFO - Contents: 03:27:37 INFO - build_url:https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:27:37 INFO - Running post-action listener: _resource_record_post_action 03:27:37 INFO - Running post-action listener: set_extra_try_arguments 03:27:37 INFO - [mozharness: 2016-11-12 11:27:37.778494Z] Finished download-and-extract step (success) 03:27:37 INFO - [mozharness: 2016-11-12 11:27:37.778721Z] Running populate-webroot step. 03:27:37 INFO - Running pre-action listener: _resource_record_pre_action 03:27:37 INFO - Running main action method: populate_webroot 03:27:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:27:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:27:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:27:37 INFO - '--tppagecycles', 03:27:37 INFO - '7'], 03:27:37 INFO - 'tests': ['tsvgm']}}, 03:27:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:27:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:27:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:27:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:27:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:37 INFO - 'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:27:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:27:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:37 INFO - 'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['damp', 'tps']}, 03:27:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:37 INFO - 'tests': ['damp', 'tps']}, 03:27:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['dromaeo_dom']}, 03:27:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:27:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['basic_compositor_video']}, 03:27:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:27:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['a11yr', 03:27:37 INFO - 'ts_paint', 03:27:37 INFO - 'tpaint', 03:27:37 INFO - 'sessionrestore', 03:27:37 INFO - 'sessionrestore_no_auto_restore', 03:27:37 INFO - 'tabpaint']}, 03:27:37 INFO - 'other-e10s': {'tests': ['a11yr', 03:27:37 INFO - 'ts_paint', 03:27:37 INFO - 'tpaint', 03:27:37 INFO - 'sessionrestore', 03:27:37 INFO - 'sessionrestore_no_auto_restore', 03:27:37 INFO - 'tabpaint']}, 03:27:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:27:37 INFO - 'ts_paint', 03:27:37 INFO - 'tpaint', 03:27:37 INFO - 'sessionrestore', 03:27:37 INFO - 'sessionrestore_no_auto_restore', 03:27:37 INFO - 'tabpaint']}, 03:27:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:27:37 INFO - 'ts_paint', 03:27:37 INFO - 'tpaint', 03:27:37 INFO - 'sessionrestore', 03:27:37 INFO - 'sessionrestore_no_auto_restore', 03:27:37 INFO - 'tabpaint']}, 03:27:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['a11yr', 03:27:37 INFO - 'ts_paint', 03:27:37 INFO - 'tpaint', 03:27:37 INFO - 'sessionrestore', 03:27:37 INFO - 'sessionrestore_no_auto_restore', 03:27:37 INFO - 'tabpaint']}, 03:27:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['a11yr', 03:27:37 INFO - 'ts_paint', 03:27:37 INFO - 'tpaint', 03:27:37 INFO - 'sessionrestore', 03:27:37 INFO - 'sessionrestore_no_auto_restore', 03:27:37 INFO - 'tabpaint']}, 03:27:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['tsvgx', 03:27:37 INFO - 'tsvgr_opacity', 03:27:37 INFO - 'tart', 03:27:37 INFO - 'tscrollx', 03:27:37 INFO - 'cart']}, 03:27:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:27:37 INFO - 'tsvgr_opacity', 03:27:37 INFO - 'tart', 03:27:37 INFO - 'tscrollx', 03:27:37 INFO - 'cart']}, 03:27:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:37 INFO - 'talos_options': ['--disable-e10s'], 03:27:37 INFO - 'tests': ['tp5o']}, 03:27:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:37 INFO - 'tests': ['tp5o']}, 03:27:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:37 INFO - 'talos_options': ['--disable-e10s', 03:27:37 INFO - '--xperf_path', 03:27:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:27:37 INFO - 'tests': ['tp5n']}, 03:27:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:37 INFO - 'talos_options': ['--xperf_path', 03:27:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:27:37 INFO - 'tests': ['tp5n']}}, 03:27:37 INFO - 'talos.zip': {'path': '', 03:27:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:27:37 INFO - Running post-action listener: _resource_record_post_action 03:27:37 INFO - [mozharness: 2016-11-12 11:27:37.793851Z] Finished populate-webroot step (success) 03:27:37 INFO - [mozharness: 2016-11-12 11:27:37.793939Z] Running create-virtualenv step. 03:27:37 INFO - Running pre-action listener: _resource_record_pre_action 03:27:37 INFO - Running main action method: create_virtualenv 03:27:37 INFO - Creating virtualenv /builds/slave/test/build/venv 03:27:37 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 03:27:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:27:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:27:37 INFO - Using real prefix '/usr' 03:27:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:27:38 INFO - Installing distribute.............................................................................................................................................................................................done. 03:27:40 INFO - Installing pip.................done. 03:27:40 INFO - Return code: 0 03:27:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:27:40 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')]} 03:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:40 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')]} 03:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:40 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 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:40 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 03:27:40 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 03:27:40 INFO - Using env: {'DISPLAY': ':0', 03:27:40 INFO - 'HOME': '/home/cltbld', 03:27:40 INFO - 'LANG': 'en_US.UTF-8', 03:27:40 INFO - 'LANGUAGE': 'en_US:en', 03:27:40 INFO - 'LOGNAME': 'cltbld', 03:27:40 INFO - 'MAIL': '/var/mail/cltbld', 03:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:40 INFO - 'MOZ_NO_REMOTE': '1', 03:27:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:40 INFO - 'NO_EM_RESTART': '1', 03:27:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:40 INFO - 'PWD': '/builds/slave/test', 03:27:40 INFO - 'SHELL': '/bin/bash', 03:27:40 INFO - 'SHLVL': '1', 03:27:40 INFO - 'TERM': 'linux', 03:27:40 INFO - 'TMOUT': '86400', 03:27:40 INFO - 'USER': 'cltbld', 03:27:40 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:40 INFO - '_': '/tools/buildbot/bin/python'} 03:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:40 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 03:27:40 INFO - Cleaning up... 03:27:40 INFO - Return code: 0 03:27:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:27:40 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')]} 03:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:40 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')]} 03:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:40 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 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:40 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 03:27:40 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 03:27:40 INFO - Using env: {'DISPLAY': ':0', 03:27:40 INFO - 'HOME': '/home/cltbld', 03:27:40 INFO - 'LANG': 'en_US.UTF-8', 03:27:40 INFO - 'LANGUAGE': 'en_US:en', 03:27:40 INFO - 'LOGNAME': 'cltbld', 03:27:40 INFO - 'MAIL': '/var/mail/cltbld', 03:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:40 INFO - 'MOZ_NO_REMOTE': '1', 03:27:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:40 INFO - 'NO_EM_RESTART': '1', 03:27:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:40 INFO - 'PWD': '/builds/slave/test', 03:27:40 INFO - 'SHELL': '/bin/bash', 03:27:40 INFO - 'SHLVL': '1', 03:27:40 INFO - 'TERM': 'linux', 03:27:40 INFO - 'TMOUT': '86400', 03:27:40 INFO - 'USER': 'cltbld', 03:27:40 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:40 INFO - '_': '/tools/buildbot/bin/python'} 03:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:40 INFO - Downloading/unpacking psutil>=3.1.1 03:27: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 03:27: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 03:27: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 03:27: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 03:27: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 03:27: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 03:27:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:27:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:27:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:27:42 INFO - Installing collected packages: psutil 03:27:42 INFO - Running setup.py install for psutil 03:27:42 INFO - building 'psutil._psutil_linux' extension 03:27:42 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 03:27:43 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 03:27:43 INFO - building 'psutil._psutil_posix' extension 03:27:43 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 03:27:43 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 03:27:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:27:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:27:43 INFO - Successfully installed psutil 03:27:43 INFO - Cleaning up... 03:27:43 INFO - Return code: 0 03:27:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:27: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')]} 03:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27: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')]} 03:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:43 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 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:43 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 03:27:43 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 03:27:43 INFO - Using env: {'DISPLAY': ':0', 03:27:43 INFO - 'HOME': '/home/cltbld', 03:27:43 INFO - 'LANG': 'en_US.UTF-8', 03:27:43 INFO - 'LANGUAGE': 'en_US:en', 03:27:43 INFO - 'LOGNAME': 'cltbld', 03:27:43 INFO - 'MAIL': '/var/mail/cltbld', 03:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:43 INFO - 'MOZ_NO_REMOTE': '1', 03:27:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:43 INFO - 'NO_EM_RESTART': '1', 03:27:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:43 INFO - 'PWD': '/builds/slave/test', 03:27:43 INFO - 'SHELL': '/bin/bash', 03:27:43 INFO - 'SHLVL': '1', 03:27:43 INFO - 'TERM': 'linux', 03:27:43 INFO - 'TMOUT': '86400', 03:27:43 INFO - 'USER': 'cltbld', 03:27:43 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:43 INFO - '_': '/tools/buildbot/bin/python'} 03:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:27:43 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 03:27:43 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 03:27:43 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 03:27:43 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 03:27:43 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 03:27:43 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 03:27:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:27:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:27:45 INFO - Installing collected packages: mozsystemmonitor 03:27:45 INFO - Running setup.py install for mozsystemmonitor 03:27:45 INFO - Successfully installed mozsystemmonitor 03:27:45 INFO - Cleaning up... 03:27:45 INFO - Return code: 0 03:27:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:27:45 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')]} 03:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:45 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')]} 03:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:45 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 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:45 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 03:27:45 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 03:27:45 INFO - Using env: {'DISPLAY': ':0', 03:27:45 INFO - 'HOME': '/home/cltbld', 03:27:45 INFO - 'LANG': 'en_US.UTF-8', 03:27:45 INFO - 'LANGUAGE': 'en_US:en', 03:27:45 INFO - 'LOGNAME': 'cltbld', 03:27:45 INFO - 'MAIL': '/var/mail/cltbld', 03:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:45 INFO - 'MOZ_NO_REMOTE': '1', 03:27:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:45 INFO - 'NO_EM_RESTART': '1', 03:27:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:45 INFO - 'PWD': '/builds/slave/test', 03:27:45 INFO - 'SHELL': '/bin/bash', 03:27:45 INFO - 'SHLVL': '1', 03:27:45 INFO - 'TERM': 'linux', 03:27:45 INFO - 'TMOUT': '86400', 03:27:45 INFO - 'USER': 'cltbld', 03:27:45 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:45 INFO - '_': '/tools/buildbot/bin/python'} 03:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:45 INFO - Downloading/unpacking jsonschema==2.5.1 03:27:45 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 03:27:45 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 03:27:45 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 03:27:45 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 03:27:45 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 03:27:45 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 03:27:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:27:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:27:47 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 03:27:47 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 03:27:47 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 03:27:47 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 03:27:47 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 03:27:47 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 03:27:48 INFO - Downloading functools32-3.2.3-2.tar.gz 03:27:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:27:48 INFO - warning: no files found matching '*.txt' 03:27:48 INFO - no previously-included directories found matching 'build' 03:27:48 INFO - no previously-included directories found matching 'dist' 03:27:48 INFO - no previously-included directories found matching '.git*' 03:27:48 INFO - Installing collected packages: jsonschema, functools32 03:27:48 INFO - Running setup.py install for functools32 03:27:48 INFO - warning: no files found matching '*.txt' 03:27:48 INFO - no previously-included directories found matching 'build' 03:27:48 INFO - no previously-included directories found matching 'dist' 03:27:48 INFO - no previously-included directories found matching '.git*' 03:27:48 INFO - Successfully installed jsonschema functools32 03:27:48 INFO - Cleaning up... 03:27:48 INFO - Return code: 0 03:27:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:27:48 INFO - Using env: {'DISPLAY': ':0', 03:27:48 INFO - 'HOME': '/home/cltbld', 03:27:48 INFO - 'LANG': 'en_US.UTF-8', 03:27:48 INFO - 'LANGUAGE': 'en_US:en', 03:27:48 INFO - 'LOGNAME': 'cltbld', 03:27:48 INFO - 'MAIL': '/var/mail/cltbld', 03:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:48 INFO - 'MOZ_NO_REMOTE': '1', 03:27:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:48 INFO - 'NO_EM_RESTART': '1', 03:27:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:48 INFO - 'PWD': '/builds/slave/test', 03:27:48 INFO - 'SHELL': '/bin/bash', 03:27:48 INFO - 'SHLVL': '1', 03:27:48 INFO - 'TERM': 'linux', 03:27:48 INFO - 'TMOUT': '86400', 03:27:48 INFO - 'USER': 'cltbld', 03:27:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:48 INFO - '_': '/tools/buildbot/bin/python'} 03:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:27:48 INFO - Cleaning up... 03:27:48 INFO - Return code: 0 03:27:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:27:48 INFO - Using env: {'DISPLAY': ':0', 03:27:48 INFO - 'HOME': '/home/cltbld', 03:27:48 INFO - 'LANG': 'en_US.UTF-8', 03:27:48 INFO - 'LANGUAGE': 'en_US:en', 03:27:48 INFO - 'LOGNAME': 'cltbld', 03:27:48 INFO - 'MAIL': '/var/mail/cltbld', 03:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:48 INFO - 'MOZ_NO_REMOTE': '1', 03:27:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:48 INFO - 'NO_EM_RESTART': '1', 03:27:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:48 INFO - 'PWD': '/builds/slave/test', 03:27:48 INFO - 'SHELL': '/bin/bash', 03:27:48 INFO - 'SHLVL': '1', 03:27:48 INFO - 'TERM': 'linux', 03:27:48 INFO - 'TMOUT': '86400', 03:27:48 INFO - 'USER': 'cltbld', 03:27:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:48 INFO - '_': '/tools/buildbot/bin/python'} 03:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:48 INFO - Downloading/unpacking blobuploader==1.2.4 03:27: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 03:27: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 03:27: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 03:27: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 03:27: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 03:27: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 03:27:50 INFO - Downloading blobuploader-1.2.4.tar.gz 03:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:27:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:27:50 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 03:27:50 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 03:27:50 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 03:27:50 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 03:27:50 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 03:27:50 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 03:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:27:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:27:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:51 INFO - Downloading docopt-0.6.1.tar.gz 03:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:27:51 INFO - Installing collected packages: blobuploader, requests, docopt 03:27:51 INFO - Running setup.py install for blobuploader 03:27:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:27:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:27:51 INFO - Running setup.py install for requests 03:27:51 INFO - Running setup.py install for docopt 03:27:52 INFO - Successfully installed blobuploader requests docopt 03:27:52 INFO - Cleaning up... 03:27:52 INFO - Return code: 0 03:27:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:27:52 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')]} 03:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:52 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')]} 03:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:52 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 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:52 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 03:27:52 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 03:27:52 INFO - Using env: {'DISPLAY': ':0', 03:27:52 INFO - 'HOME': '/home/cltbld', 03:27:52 INFO - 'LANG': 'en_US.UTF-8', 03:27:52 INFO - 'LANGUAGE': 'en_US:en', 03:27:52 INFO - 'LOGNAME': 'cltbld', 03:27:52 INFO - 'MAIL': '/var/mail/cltbld', 03:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:52 INFO - 'MOZ_NO_REMOTE': '1', 03:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:52 INFO - 'NO_EM_RESTART': '1', 03:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:52 INFO - 'PWD': '/builds/slave/test', 03:27:52 INFO - 'SHELL': '/bin/bash', 03:27:52 INFO - 'SHLVL': '1', 03:27:52 INFO - 'TERM': 'linux', 03:27:52 INFO - 'TMOUT': '86400', 03:27:52 INFO - 'USER': 'cltbld', 03:27:52 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:52 INFO - '_': '/tools/buildbot/bin/python'} 03:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:27:52 INFO - Running setup.py (path:/tmp/pip-8ZufE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:27:52 INFO - Running setup.py (path:/tmp/pip-y53286-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:27:52 INFO - Running setup.py (path:/tmp/pip-AR2zJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:27:52 INFO - Running setup.py (path:/tmp/pip-YRhVNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:27:52 INFO - Running setup.py (path:/tmp/pip-LfiOZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:27:52 INFO - Running setup.py (path:/tmp/pip-8xNpat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:27:52 INFO - Running setup.py (path:/tmp/pip-9VAzJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:27:52 INFO - Running setup.py (path:/tmp/pip-QeA9kQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:27:52 INFO - Running setup.py (path:/tmp/pip-xkuxia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:27:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:27:52 INFO - Running setup.py (path:/tmp/pip-MuXlYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:27:53 INFO - Running setup.py (path:/tmp/pip-CVCzCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:27:53 INFO - Running setup.py (path:/tmp/pip-Jo3Y76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:27:53 INFO - Running setup.py (path:/tmp/pip-2_AGhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:27:53 INFO - Running setup.py (path:/tmp/pip-gvN1tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:27:53 INFO - Running setup.py (path:/tmp/pip-Ir8byL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:27:53 INFO - Running setup.py (path:/tmp/pip-gZ1xwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:27:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:27:53 INFO - Running setup.py (path:/tmp/pip-Ox3ztf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:27:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:27:53 INFO - Running setup.py install for manifestparser 03:27:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:27:53 INFO - Running setup.py install for mozcrash 03:27:53 INFO - Running setup.py install for mozdebug 03:27:53 INFO - Running setup.py install for mozdevice 03:27:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:27:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:27:53 INFO - Running setup.py install for mozfile 03:27:54 INFO - Running setup.py install for mozhttpd 03:27:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Running setup.py install for mozinfo 03:27:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Running setup.py install for mozInstall 03:27:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Running setup.py install for mozleak 03:27:54 INFO - Running setup.py install for mozlog 03:27:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Running setup.py install for moznetwork 03:27:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Running setup.py install for mozprocess 03:27:54 INFO - Running setup.py install for mozprofile 03:27:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:27:54 INFO - Running setup.py install for mozrunner 03:27:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:27:55 INFO - Running setup.py install for mozscreenshot 03:27:55 INFO - Running setup.py install for moztest 03:27:55 INFO - Running setup.py install for mozversion 03:27:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:27:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:27:55 INFO - Cleaning up... 03:27:55 INFO - Return code: 0 03:27:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:27:55 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')]} 03:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:55 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')]} 03:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:55 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 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:55 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 03:27:55 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 03:27:55 INFO - Using env: {'DISPLAY': ':0', 03:27:55 INFO - 'HOME': '/home/cltbld', 03:27:55 INFO - 'LANG': 'en_US.UTF-8', 03:27:55 INFO - 'LANGUAGE': 'en_US:en', 03:27:55 INFO - 'LOGNAME': 'cltbld', 03:27:55 INFO - 'MAIL': '/var/mail/cltbld', 03:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:55 INFO - 'MOZ_NO_REMOTE': '1', 03:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:55 INFO - 'NO_EM_RESTART': '1', 03:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:55 INFO - 'PWD': '/builds/slave/test', 03:27:55 INFO - 'SHELL': '/bin/bash', 03:27:55 INFO - 'SHLVL': '1', 03:27:55 INFO - 'TERM': 'linux', 03:27:55 INFO - 'TMOUT': '86400', 03:27:55 INFO - 'USER': 'cltbld', 03:27:55 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:55 INFO - '_': '/tools/buildbot/bin/python'} 03:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:27:55 INFO - Running setup.py (path:/tmp/pip-5q05xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:27:55 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)) 03:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:27:55 INFO - Running setup.py (path:/tmp/pip-b0imU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:27:55 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)) 03:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:27:55 INFO - Running setup.py (path:/tmp/pip-1JyJ1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:27:55 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)) 03:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:27:55 INFO - Running setup.py (path:/tmp/pip-tvF9jU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:27:55 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)) 03:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:27:55 INFO - Running setup.py (path:/tmp/pip-1vTZXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:27:55 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)) 03:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:27:55 INFO - Running setup.py (path:/tmp/pip-RLlGSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:27:55 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)) 03:27:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:27:55 INFO - Running setup.py (path:/tmp/pip-leaCc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:27:56 INFO - Running setup.py (path:/tmp/pip-YkmkLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:27:56 INFO - Running setup.py (path:/tmp/pip-WyT5Gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:27:56 INFO - Running setup.py (path:/tmp/pip-4X3NiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:27:56 INFO - Running setup.py (path:/tmp/pip-1MbXzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:27:56 INFO - Running setup.py (path:/tmp/pip-go7kZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:27:56 INFO - Running setup.py (path:/tmp/pip-fBeWdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:27:56 INFO - Running setup.py (path:/tmp/pip-K6Dutx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:27:56 INFO - Running setup.py (path:/tmp/pip-r7OBOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:27:56 INFO - Running setup.py (path:/tmp/pip-FhxZJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:27:56 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)) 03:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:27:56 INFO - Running setup.py (path:/tmp/pip-5JDn7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:27:56 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)) 03:27:56 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)) 03:27:56 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)) 03:27:56 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)) 03:27:56 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)) 03:27:56 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)) 03:27:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:27:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:27:58 INFO - Downloading blessings-1.6.tar.gz 03:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:27:58 INFO - Installing collected packages: blessings 03:27:58 INFO - Running setup.py install for blessings 03:27:58 INFO - Successfully installed blessings 03:27:58 INFO - Cleaning up... 03:27:58 INFO - Return code: 0 03:27:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:27:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:27:59 INFO - Reading from file tmpfile_stdout 03:27:59 INFO - Current package versions: 03:27:59 INFO - argparse == 1.2.1 03:27:59 INFO - blessings == 1.6 03:27:59 INFO - blobuploader == 1.2.4 03:27:59 INFO - docopt == 0.6.1 03:27:59 INFO - functools32 == 3.2.3-2 03:27:59 INFO - jsonschema == 2.5.1 03:27:59 INFO - manifestparser == 1.1 03:27:59 INFO - mozInstall == 1.12 03:27:59 INFO - mozcrash == 0.17 03:27:59 INFO - mozdebug == 0.1 03:27:59 INFO - mozdevice == 0.48 03:27:59 INFO - mozfile == 1.2 03:27:59 INFO - mozhttpd == 0.7 03:27:59 INFO - mozinfo == 0.9 03:27:59 INFO - mozleak == 0.1 03:27:59 INFO - mozlog == 3.4 03:27:59 INFO - moznetwork == 0.27 03:27:59 INFO - mozprocess == 0.23 03:27:59 INFO - mozprofile == 0.28 03:27:59 INFO - mozrunner == 6.12 03:27:59 INFO - mozscreenshot == 0.1 03:27:59 INFO - mozsystemmonitor == 0.3 03:27:59 INFO - moztest == 0.7 03:27:59 INFO - mozversion == 1.4 03:27:59 INFO - psutil == 3.1.1 03:27:59 INFO - requests == 1.2.3 03:27:59 INFO - wsgiref == 0.1.2 03:27:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:59 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 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:59 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 03:27:59 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 03:27:59 INFO - Using env: {'DISPLAY': ':0', 03:27:59 INFO - 'HOME': '/home/cltbld', 03:27:59 INFO - 'LANG': 'en_US.UTF-8', 03:27:59 INFO - 'LANGUAGE': 'en_US:en', 03:27:59 INFO - 'LOGNAME': 'cltbld', 03:27:59 INFO - 'MAIL': '/var/mail/cltbld', 03:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:59 INFO - 'MOZ_NO_REMOTE': '1', 03:27:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:59 INFO - 'NO_EM_RESTART': '1', 03:27:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:59 INFO - 'PWD': '/builds/slave/test', 03:27:59 INFO - 'SHELL': '/bin/bash', 03:27:59 INFO - 'SHLVL': '1', 03:27:59 INFO - 'TERM': 'linux', 03:27:59 INFO - 'TMOUT': '86400', 03:27:59 INFO - 'USER': 'cltbld', 03:27:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:59 INFO - '_': '/tools/buildbot/bin/python'} 03:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:59 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)) 03:27:59 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)) 03:27:59 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)) 03:27:59 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)) 03:27:59 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)) 03:27:59 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)) 03:27:59 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)) 03:27:59 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)) 03:27:59 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)) 03:27:59 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)) 03:27:59 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)) 03:27:59 INFO - Cleaning up... 03:27:59 INFO - Return code: 0 03:27:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:27:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff47eea3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1374ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', '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 03:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:27:59 INFO - Using env: {'DISPLAY': ':0', 03:27:59 INFO - 'HOME': '/home/cltbld', 03:27:59 INFO - 'LANG': 'en_US.UTF-8', 03:27:59 INFO - 'LANGUAGE': 'en_US:en', 03:27:59 INFO - 'LOGNAME': 'cltbld', 03:27:59 INFO - 'MAIL': '/var/mail/cltbld', 03:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:59 INFO - 'MOZ_NO_REMOTE': '1', 03:27:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:59 INFO - 'NO_EM_RESTART': '1', 03:27:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:59 INFO - 'PWD': '/builds/slave/test', 03:27:59 INFO - 'SHELL': '/bin/bash', 03:27:59 INFO - 'SHLVL': '1', 03:27:59 INFO - 'TERM': 'linux', 03:27:59 INFO - 'TMOUT': '86400', 03:27:59 INFO - 'USER': 'cltbld', 03:27:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:59 INFO - '_': '/tools/buildbot/bin/python'} 03:27:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:27:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:27:59 INFO - Cleaning up... 03:27:59 INFO - Return code: 0 03:27:59 INFO - Running post-action listener: _resource_record_post_action 03:27:59 INFO - Running post-action listener: _start_resource_monitoring 03:27:59 INFO - Starting resource monitoring. 03:27:59 INFO - [mozharness: 2016-11-12 11:27:59.664857Z] Finished create-virtualenv step (success) 03:27:59 INFO - [mozharness: 2016-11-12 11:27:59.665305Z] Running install step. 03:27:59 INFO - Running pre-action listener: _resource_record_pre_action 03:27:59 INFO - Running main action method: install 03:27:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:27:59 INFO - Reading from file tmpfile_stdout 03:27:59 INFO - Detecting whether we're running mozinstall >=1.0... 03:27:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:27:59 INFO - Reading from file tmpfile_stdout 03:27:59 INFO - Output received: 03:27:59 INFO - Usage: mozinstall [options] installer 03:27:59 INFO - Options: 03:27:59 INFO - -h, --help show this help message and exit 03:27:59 INFO - -d DEST, --destination=DEST 03:27:59 INFO - Directory to install application into. [default: 03:27:59 INFO - "/builds/slave/test"] 03:27:59 INFO - --app=APP Application being installed. [default: firefox] 03:27:59 INFO - mkdir: /builds/slave/test/build/application 03:27:59 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'] 03:27:59 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 03:28:15 INFO - Reading from file tmpfile_stdout 03:28:15 INFO - Output received: 03:28:15 INFO - /builds/slave/test/build/application/firefox/firefox 03:28:15 INFO - Running post-action listener: _resource_record_post_action 03:28:15 INFO - [mozharness: 2016-11-12 11:28:15.909708Z] Finished install step (success) 03:28:15 INFO - [mozharness: 2016-11-12 11:28:15.909956Z] Running run-tests step. 03:28:15 INFO - Running pre-action listener: _resource_record_pre_action 03:28:15 INFO - Running main action method: run_tests 03:28:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:28:15 INFO - Python 2.7.3 03:28:15 INFO - Return code: 0 03:28:15 INFO - grabbing minidump binary from tooltool 03:28:15 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')]} 03:28:15 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 0x1371f60>, '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 0x1372d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13731d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:28:15 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 03:28:15 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 03:28:15 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 03:28:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:28:16 INFO - Return code: 0 03:28:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:28:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:28:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:28:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:28:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:28:16 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/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 03:28:16 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/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 03:28:16 INFO - Using env: {'DISPLAY': ':0', 03:28:16 INFO - 'HOME': '/home/cltbld', 03:28:16 INFO - 'LANG': 'en_US.UTF-8', 03:28:16 INFO - 'LANGUAGE': 'en_US:en', 03:28:16 INFO - 'LOGNAME': 'cltbld', 03:28:16 INFO - 'MAIL': '/var/mail/cltbld', 03:28:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:28:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:16 INFO - 'MOZ_NO_REMOTE': '1', 03:28:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:28:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:16 INFO - 'NO_EM_RESTART': '1', 03:28:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:16 INFO - 'PWD': '/builds/slave/test', 03:28:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:28:16 INFO - 'SHELL': '/bin/bash', 03:28:16 INFO - 'SHLVL': '1', 03:28:16 INFO - 'TERM': 'linux', 03:28:16 INFO - 'TMOUT': '86400', 03:28:16 INFO - 'USER': 'cltbld', 03:28:16 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853', 03:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:16 INFO - '_': '/tools/buildbot/bin/python'} 03:28:16 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/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 03:28:16 INFO - mozversion application_buildid: 20161112004002 03:28:16 INFO - mozversion application_changeset: 3d380055aaedfda2da14f5aee37eda8079105387 03:28:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:28:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:28:16 INFO - mozversion application_name: Firefox 03:28:16 INFO - mozversion application_remotingname: firefox-dev 03:28:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:28:16 INFO - mozversion application_vendor: Mozilla 03:28:16 INFO - mozversion application_version: 51.0a2 03:28:16 INFO - mozversion platform_buildid: 20161112004002 03:28:16 INFO - mozversion platform_changeset: 3d380055aaedfda2da14f5aee37eda8079105387 03:28:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:28:16 INFO - mozversion platform_version: 51.0a2 03:28:16 INFO - using testdate: 1478950096 03:28:16 INFO - actual date: 1478950096 03:28:16 INFO - starting webserver on 'localhost:49214' 03:28:16 INFO - SUITE-START | Running 2 tests 03:28:16 INFO - TEST-START | dromaeo_css 03:28:16 INFO - operating with platform_type : linux_ 03:28:16 INFO - Initialising browser for dromaeo_css test... 03:28:16 INFO - TEST-INFO | started process 29741 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp408Qqx/profile http://localhost:49214/getInfo.html) 03:28:20 INFO - PROCESS | 29741 | __metrics Screen width/height:1600/1200 03:28:20 INFO - PROCESS | 29741 | colorDepth:24 03:28:20 INFO - PROCESS | 29741 | Browser inner width/height: 1024/697 03:28:20 INFO - PROCESS | 29741 | __metrics 03:28:20 INFO - PROCESS | 29741 | 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" 03:28:20 INFO - PROCESS | 29741 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:28:21 INFO - PROCESS | 29741 | console.error: PushService: 03:28:21 INFO - PROCESS | 29741 | stateChangeProcessEnqueue: Error transitioning state 03:28:21 INFO - PROCESS | 29741 | UnknownError 03:28:21 INFO - TEST-INFO | 29741: exit 0 03:28:21 INFO - Browser initialized. 03:28:21 INFO - Running cycle 1/1 for dromaeo_css test... 03:28:21 INFO - TEST-INFO | started process 29830 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp408Qqx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:28:33 INFO - PROCESS | 29830 | RSS: Main: 144433152 03:28:33 INFO - PROCESS | 29830 | 03:29:24 INFO - PROCESS | 29830 | 1478950164624 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] 03:31:34 INFO - PROCESS | 29830 | Cycle 1(1): loaded http://localhost:49214/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49214/tests/dromaeo/cssquery-ext.html) 03:31:35 INFO - PROCESS | 29830 | RSS: Main: 239751168 03:31:35 INFO - PROCESS | 29830 | 03:34:37 INFO - PROCESS | 29830 | Cycle 1(1): loaded http://localhost:49214/tests/dromaeo/cssquery-ext.html (next: http://localhost:49214/tests/dromaeo/cssquery-jquery.html) 03:34:38 INFO - PROCESS | 29830 | RSS: Main: 233099264 03:34:38 INFO - PROCESS | 29830 | 03:37:40 INFO - PROCESS | 29830 | Cycle 1(1): loaded http://localhost:49214/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49214/tests/dromaeo/cssquery-mootools.html) 03:37:40 INFO - PROCESS | 29830 | RSS: Main: 175280128 03:37:40 INFO - PROCESS | 29830 | 03:40:42 INFO - PROCESS | 29830 | Cycle 1(1): loaded http://localhost:49214/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49214/tests/dromaeo/cssquery-prototype.html) 03:40:43 INFO - PROCESS | 29830 | RSS: Main: 225189888 03:40:43 INFO - PROCESS | 29830 | 03:43:46 INFO - PROCESS | 29830 | Cycle 1(1): loaded http://localhost:49214/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49214/tests/dromaeo/cssquery-yui.html) 03:43:46 INFO - PROCESS | 29830 | RSS: Main: 175038464 03:43:46 INFO - PROCESS | 29830 | 03:46:49 INFO - PROCESS | 29830 | Cycle 1(1): loaded http://localhost:49214/tests/dromaeo/cssquery-yui.html (next: http://localhost:49214/tests/dromaeo/cssquery-dojo.html) 03:46:49 INFO - PROCESS | 29830 | RSS: Main: 263770112 03:46:49 INFO - PROCESS | 29830 | 03:46:49 INFO - PROCESS | 29830 | __start_tp_report 03:46:49 INFO - PROCESS | 29830 | _x_x_mozilla_page_load 03:46:49 INFO - PROCESS | 29830 | _x_x_mozilla_page_load_details 03:46:49 INFO - PROCESS | 29830 | |i|pagename|runs| 03:46:49 INFO - PROCESS | 29830 | |0;dojo.html;3140.0579826052185;3176.3329700762847;3194.584703988481;3214.807111573304;3231.2406584452665;5655.23654306669;5708.942910570893;5737.971310143454;5750.654960702361;5758.625689330196;5630.521405680512;5685.800996965107;5700.201971723962;5703.885922281552;5732.885342293152;5079.923801142986;5124.769385377648;5129.512696293846;5139.12634852076;5213.009528189651;5065.290859279704;5201.973990130044;5205.661631993928;5206.062908676437;5224.29472021278;5044.646874718771;5091.185410334348;5123.410807757118;5151.6908985460805;5175.068487672217;5130.333056702623;5152.562032227265;5156.20078887773;5242.973785131088;5243.318368612094;5090.643654944155;5110.105731496973;5191.5846732261325;5208.921866172011;5230.607704422146;5479.53423958964;5505.339359276913;5566.888662226774;5603.2715746953045;5635.8591035224035;5931.940680593182;5951.464368206839;5957.791477298274;6021.307549631804;6029.698515074273;6060.484858787008;6068.180795592582;6075.939240607581;6082.239720034996;6083.239595050619;5507.779688812483;5569.944300557024;5571.359293681238;5615.0454422748235;5624.6625202487985;6208.658523781145;6222.284437289679;6226.315105338409;6233.189685340877;6239.812805615838;5962.046072628359;5963.254593175853;5964.910526342108;5968.283805943228;5969.522438204933;5846.327672317653;5915.674637894957;5959.67221802805;6038.939610603926;6046.667433291212;6085.452309292096;6091.543134264948;6114.419130182626;6128.1114238435175;6128.662923539248;6024.638521688713;6053.243344581927;6056.9697151513965;6080.14877917092;6099.329073801879;4501.864944051684;4531.501534831166;4607.470140933836;4669.766511674471;4677.64917631179;4119.114390406077;4138.399932009842;4181.330987042059;4182.059036716714;4183.163367326486;5794.681292528951;5796.478316951495;5800.709964501758;5801.970990145022;5804.535637149018;5944.256967879015;6130.908036379368;6134.877302454015;6140.692965351804;6145.354737752561;5630.831075067755;5760.135979603009;5801.593888427854;5812.505936995402;5888.735006924699;5304;5333.57331413486;5344.251804747339;5345.65253258537;5348.277982472394;6346.397092276226;6352.587081839665;6358.1734374531015;6360.713767880364;6368.681565921685;5969.3732158122775;6013.579049466495;6065.909011364744;6102.176206212104;6122.204113465221;6353.237611486466;6353.428191462791;6357.682115894279;6361.650109244036;6364.968175159094;5958.374370691188;6019.909701354571;6026.668533230714;6030.939690603213;6050.273967123797;5863.560232982458;5922.881542369215;6023.698815059141;6061;6183.907241391293;5986;5988.341282459013;5992.760289588367;5992.970035149797;6025.0661147522205;5989.520838332835;5990.221271234712;5992.67040312787;5997.880042399041;6001.519878409804;5681.77272909079;5694.515966142924;5887.322957859901;5911.911321330097;5949.315828679584;6060;6063.696815159136;6077.088436734349;6104.114903338902;6110.236220472441;5872.823815285548;5887.882242355215;5891.05743081105;5910.674912879833;5916.615419997858 03:46:49 INFO - PROCESS | 29830 | |1;ext.html;592.7480820651222;620.5935112501312;632.0866348126958;640.4716109209903;647.475544808705;34957.650423495754;47461.52538474614;48995.75502122493;49058.75470622642;49094.75452622732;50157;50577.49422505774;50667;50707.74646126764;50781;8535.061143274235;9422.575984080715;9570;9584;9588.712338629852;8276.875846862302;11089.667309980674;11403.657890263305;11411.942940285287;11692.181547291693;11556.30662160269;11597.47811348487;11624.186306958516;11626;11629.418529073555;11450.083993280517;11508.712282192928;11522.481488333045;11526.135539834546;11535.307881527051;11198.608048718337;11245.943770281177;11267.830982535268;11268.323900565993;11272.774544509064;48079.278810817865;48282;48346.27480587794;48442;48572.271415928786;3929.9214015719813;3952.26883026641;4028;4097.160082183175;4116.485439320085;35539.0245365649;35541;35611.64388356109;35824.462633060524;35867.82066089679;4757.238841785333;4798.424189097331;4811.78346974387;4819.5180481951875;4921.286413470027;392917;393970;397032;397893;399062;39998.80000600037;63489.36510634835;63959.68020159962;63993.36006639967;63994.36005639977;39818.20363592712;42666.360004599956;42798.57201427946;42832.78583607062;42922.57077429248;18036.458906232834;18205.180766865524;18206.817931820777;18220.27118915255;18227.08864556767;13674.384652690454;13818.654533636538;14871.182084985214;15283.159426231441;15345.156016419203;9685.757856053655;9712.562934667956;9739.074787895139;9789.412635241766;9790.706278811647;3663.120850995742;3721.702263818888;3726.850925962931;3744.962550374516;3784.2053168834855;1011.2163073618005;1076.5959345120368;1123.9494222759995;1180.557291015869;1184.7156682396162;34864.47703284453;35060.64939350574;35167.824160879034;35403;35478;1122.9494672739747;1151.9366434846163;1177.2583272538245;1195.4101045609318;1298.0783643612951;18463;18555.90722046408;18587.535311617317;18678.252869885324;18761;640019;650163;650343;652456;653769;65768.67115664486;66586.66706666436;66598.66700666466;66606;66609;1336.2717319061135;1368.9726205475836;1423.3168079321777;1467.7211329847296;1468.258529442646;33829.83085084559;36204.81897590495;36313.63686363103;36535;36610;55783;62104.37895620986;63856.68071659612;63859;68942;553930;559769;560338;560427;561277;30952.535711963887;32060.358792823547;32321.191970200933;33850.49224261589;34143;53166.73416633047;53255.46744532505;53985;54420.455795441536;54987;44212.336814948445;44659.10681786281;45350.54649453595;46211.30683039824;47317.52682473269;45375.54624453713;45401.545984539734;46128.30807537823;46306.30540541827;46949.530504694514;45080.323795143755;45141.54858451374;45640.54359456363;45855.54144458646;46265.53734462612;39135;40244.79877600593;40742;40872;41361;30309.393812124075;30568.694313057473;30570;30619.2345191372;30977.845110774302 03:46:49 INFO - PROCESS | 29830 | |2;jquery.html;398.5456579499373;408.4946970414023;408.9243489954359;411.9691023173262;422.4276105876537;14374.784378234335;16474.17629118544;20489.07799149036;21227.893860530676;21978.780212197915;20024.799752002476;20888;20891.268805591808;20958.89520552395;21010.474738131554;5951.345352011275;5965.164876917224;6096.969515152418;6164.075388691687;6165.506759459255;4465.486469056052;4666.4400271967415;6039.516838652919;6126.571140020201;6140.877182456348;6488.837779055514;6762;6784.660766961632;6886.311368863124;6898.620575868325;6623.23832759234;6792;6809.829754256183;6820.079289295931;6861.622610756406;5344.4922732340365;6006.339302676702;6012.398760123996;6014.12795144706;6021.036634138561;14720.263986800725;15099.622509437351;15111.39554417822;15114.773278400833;15157.696846063127;2391.845572790639;2440.5362981033545;2471.7033955925403;2512.849229046263;2586.1465716313573;10548.525316360781;10789.676309710643;10791.136709063256;10965.780684386347;11266.774664506664;2002.149086638173;2039.8980050997538;2063.13348393675;2065.2874758208545;2151.6449785785326;73777.26222737758;79337.60331198365;80008;86137.56931215366;90259.09740902572;11226.101911847032;11268.323900565993;11398;11777.293362398112;11896.583619573274;16469.505914822574;16492.917535412485;16501.58746031359;16507.174641267888;16512.669746605;8765.737027889174;8913.732588022369;8915.643374265086;8992.595333210022;9062.592183351764;8405.117462666454;8537.871931921027;8707.564621768886;8805.251553617885;8891.911080889238;4898.853034408973;4919.77860996256;4980.178270585386;5049.671771334925;5078.263651770472;2014.1842553765748;2052.568960518308;2066.4730493724005;2104.810567048973;2163.967540486894;919.4207649180973;1004.0762498501397;1007.3905287301077;1082.7076689293847;1126.583164229224;20556.897215513825;20642.17431302731;21117.04973276206;21131.78868211329;21182.682259766116;1035.8135535603512;1044.0238377117419;1061.5541472581535;1081.3187691754144;1093.1200383691046;11254.549818007352;11633.59282425111;11665;11913.987311078483;11937.880621193677;397346;400097;518602;525659;525849;93632.78652460656;95458;101790.8567314613;106081;106952;1073.4471747050275;1093.9288946218471;1096.0957210301533;1100.5212732461405;1109.1570406490964;16622.667546648758;16881.40915067967;16974.915125424293;17256.568585785455;17627.20677569538;43405.56594434015;44464.55535444604;44627.290917165534;44804.32793508035;45092;64866.351336486034;65049;65115;65462.34537654753;65994;9070.546472676207;9199.908000919904;9407.576659050223;9435.103665151799;9574.808503830023;13280.402381892645;13879.653008674864;14229.71540569162;14353.497627582985;14372.784408233676;10168.84746728785;12126;12370.283487734343;12472;12518.24890506536;9846.359986600848;11911.880881191077;12275.324857132944;12541.184822986477;12792.168509046882;12086.42876426059;12111.75776484483;12381.319027453577;12659.177153484994;12675.429605667583;8945.59744811472;9062.50156241413;9185.632574696938;9259.120383563552;9453.905460945303;11341.886581134413;11805.940970295094;12186.817197742219;12289.503750434795;12549.372531373578 03:46:49 INFO - PROCESS | 29830 | |3;mootools.html;1430.113329735564;1555.7666350047496;1591.8806089543284;1772.1139430284857;1918.952026199344;7702.576358300291;9116.498592577405;9403.529823508832;9404.388714733537;9646.710598682033;9396.15434610885;9470.337076404654;9482.478463684512;9485.193758530517;9485.762855928606;2445.266420073975;2468.617364308535;2475.5667758142345;2476.418041760185;2476.5913624251975;2352.517733864562;2453.2394957563124;2454.435479839638;2455.5211733711935;2458.4591389894194;2499.8375105618165;2519.2945975126995;2559;2561.590145576708;2561.871906404682;2445.8504502883616;2454.5459090068307;2476.2818782553036;2476.4675594747036;2485.763852434025;2301.5396920615776;2326.720793504773;2336.556054349685;2338.6141286687675;2355.05797680929;9248.537573121317;9273.397229180082;9405.623775048989;9428.387154834914;9432.717018489388;5542;5709.62887412321;5743.396943320933;5749.511291540182;5755.510781583571;3745.6441638044344;3751.4935483709896;3794.29805485986;3825.3496905526126;3827.770333779982;1302.5636411802059;1307.8103674967076;1313.2645718397632;1346.481604582233;1350.723101764146;12372.505099795997;12422.62732118047;12508.624741257778;12521.37393130349;12527.74944501114;4697.767045399914;4709.364235828188;4756.9286460703115;4834.854954351375;4886.022795440926;9899.208063354914;9916.454594997344;9974.950125249472;9977.201823854075;9979.950100249453;5528.585356098309;5531;5564.638298510584;5572.888542229132;5577.944220557823;4708.246680531099;4857.611391088704;5141.203113517411;5150.742462876841;5252.737363131828;4050.4936882889638;4094.5700701426517;4136.9793151034055;4222.662187025031;4242.7242229255;1254.140913474273;1267.5170051040304;1269.955551555691;1270.0982302565283;1293.3727142335958;999.115782532464;1003.894591067942;1028.753099256173;1028.8456731490337;1032.0659802878406;6359.8092057238355;6408.55140140195;6416.101745755598;6690.297518760557;6707.899381509281;488.9144399730033;489.889774800667;496.6697146397605;503.17730510615405;508.15646027594755;4324.675649326313;4515.841945531891;4524;4527.7509736964785;4592.609628181575;11422.428878556038;11746.94126529379;11809.940950295366;11810.40947952599;11834.940825295991;11514.424278786028;11552.249103808226;11666.475008624462;11690.298582085103;11730.178887477965;8731.432456890281;8814.294856411472;8818.162274583905;8835.249003834746;8842.867356989522;6468.838279043061;6508.381703738138;6522.934770652233;6543.116679248431;6593.901091483535;10666.946665266623;10948.671539853816;10958.945205274242;10968.177386696188;10974.561017559208;10731.19516036316;10737.516811743333;10889.618863340062;10974.506147223554;11001.17491188148;7241.203467618663;7345.742898998509;7833.255840695125;7896.921030789848;7905.604719764104;9155.13026262504;9162.954185229299;9217.493037882712;9232.21526170283;9242.306826988137;7938.880916786368;8118.188181181835;8201.71294004707;8431.28334091609;8459.407841451039;7795.844083118419;7946.125926148235;8071.677132914618;8163;8252.174782521699;6997.405220556309;7067.151941767021;7092.503524753424;7106.111736032996;7269.273072692688;5174.404943431554;5263.973680131575;5367.892642147057;5390.029794637003;5475;5513.696746678971;5629.831105066854;5630.971845140912;5706.9714651426475;5775.94224057754 03:46:49 INFO - PROCESS | 29830 | |4;prototype.html;337.9932401351973;352.9876454324099;353.1506726323193;359.8578561468222;360.1015466411304;5517.586181036419;5583.776648934048;5592.161175823629;5625.353084395296;5664.433556644333;5515.751791169397;5563.638363506379;5567.777288908439;5583.357913839899;5584.357798853133;4092.3016947966557;4381.14567659305;4390.187815254172;4395.956040439587;4441.444819397583;3878.3212937735784;4302.225599392108;4355.5208927018;4363.301871700529;4398.362237475564;4298.247806633843;4319.33050377192;4323.546027667081;4335.6748243881675;4350.54319296474;4164.75011499311;4381.058072514409;4384.693071484998;4421.911561768763;4426.3581780641625;3792.924141517154;3833.8083095845095;3886.300465916134;3907.179492306619;3928.46965659638;5416.864578385533;5428.75570599324;5431.592630552725;5435.755391007414;5478.726063696839;4738.715677059353;5038.538823741111;5059.026979478006;5073.746312684388;5075.441701412841;5760.7695692172265;5769.249997500298;5789.623674461147;5816.418358164192;5886.381929897342;4204.138151678899;4208.305629571117;4219.91560168798;4257.276263035292;4275.7862106894845;5889.528837692974;6049.1833602463985;6149.538784591174;6177;6193.194884665009;4266.509351424564;4276.593723596254;4291.849785257501;4342.522322544518;4349.586789255016;4388.648908087409;4391.3412988051405;4394.472663280363;4412.051408947092;4419.558044195554;4916.631252656065;5027.924581131286;5064.290999260106;5070.543651071347;5071.239314102914;5870.471657550841;5870.589058765845;5877.2083260450945;5894.764209431574;5924.407559244128;1484.8960572759804;1495.4765831958728;1504.9473268435552;1511.644763480581;1512.160750783304;939.9248060155171;969.8399764038854;974.8196583632051;976.8925418203992;997.1623835977668;4955.355803745562;4983.377077865267;4990.2764099205415;5006.424261209934;5095.433196762281;5209.244659524424;5246.35378412;5248.580113590903;5249.553787927993;5273.973630131824;4808.879778005507;4959;5015.423226328946;5027.874303142451;5031.7987280509105;2814.4511820194866;2824.237455886899;2843.658760948658;2844.4879921613892;2854.716227018377;5418.512333889968;5459.099248623933;5473.863153421196;5493.148561972901;5495.53287970527;5421.349438067457;5784;6130.38307398637;6177.444030037228;6186.690665466709;5930.021546444875;6003.8198854034445;6046.3349031606485;6186.381361863778;6193.256809182927;5964.642121472725;5997.890390277813;6000.429959153874;6006.948783962702;6048.576599637983;5629.605927585029;5941.702914854153;5973.193618861572;5982.4316689914385;6021.337652858095;6229.563930525001;6230.719617617127;6243.906341404791;6247.468965138014;6249.90626640327;4336.566343365638;4381.693281470267;4402.229609818205;4404.713693609905;4427.955720442755;5905.940940590711;6012.9098063529955;6014.398560144125;6044.335123136363;6059.030814985235;5840.795572154954;5902.911456328073;5905.261842269716;5907.675077870586;5970.3432622412365;5677.829665110054;5863.355030946678;5905.202797622266;5950.375210602824;5974.492168165754;5875.823725288248;5887.8233652990475;5898.55760817949;5901.144334071621;5907.940920590911;5927.2590926134235;5931.733072011856;5943.791967281124;5967.194428752063;6000;5511;5753.626014309224;5770.913436298375;5792.8566430421015;5796.681182534832 03:46:49 INFO - PROCESS | 29830 | |5;yui.html;818.2145140664961;826.2770076183343;870.7126648206092;880.2561835249217;896.8430524658191;16680.58298542537;17860.64278714425;17864.464066078006;17899.642007159848;17923.55191120221;14488.855111448882;14505.05717128386;14508.984371094028;14522.564323070299;15519.922400387983;726.7964969808448;742.4505865659401;862.8576284912982;888.1739981816908;893.5576889439736;872.9869051964225;883.6023789294843;885.4156256870467;885.5262434597491;887.0552861202831;947.5925352098595;948.4830767231875;949.0225068179751;949.2738055387634;950.8621249918758;752.6951584608242;776.8057985503624;887.2901678657048;910.5492781073411;938.629241449627;677.0994577212346;677.2313424263447;677.485111315399;677.6306912732523;677.6645560447561;16105.43630972922;16209.108499032549;16263.861529692926;16286;16301.429449969195;14709.55871323862;15312.540623781304;15350.232488375537;15729.92135039329;15949;13506.122102063335;13752.381142848495;13758.724825503534;13898.652533686638;14115.223662698547;4642.326862604938;4676.438827340741;4690.718556886563;4759.095771803346;4846.927296090562;87294;87303.56348218281;87344;87588.12411875863;88247.55876220642;12255.448504817305;12351.629451116482;12468.563600273947;12491.812622810665;12676.556320528922;22781.31656050321;22840.771592283865;22874.428139296317;22915.88542057279;22935.197268095537;8655.78360540979;8731.60707768152;8760.51817150063;8870.157335053289;8955.641774328953;4821.156297647897;4919.311296418504;5016;5066.316047333563;5180.611454140954;3769.1707824278615;3849.0377405648587;3906.394508851076;3910.7066969977363;3914.549826769901;636.4685487617799;638.8051644248549;646.0986923242051;653.170473498654;654.2476152424655;589.3311091910626;658.8221180281297;672.3175976383974;685.7359916432209;689.5896941319907;9491.955884852661;9506.524673766284;9733.22217590661;9994.600215991424;10015.899841001643;615.1971676961518;615.2709039787866;633.7433339497511;655.4526969980025;665.8601693644292;4679.836205732851;4750.643701722384;4780.880477988008;4816.109019831342;4816.20532612115;76569.23430765733;77351;132832;134687.32656336852;135337;109771;112965.87034129554;115864;115948;118205;84028;84265.73426573427;98306;100762;101339.49330253301;9483.913708339833;9607.951960240154;9877.50612469388;9964.252681049094;10128.645497407555;54147.729261353445;54374;55412.72293638506;56056.43943560676;56500.434995651165;50556.24165637445;67843.66078169577;69678.65160674164;75370;76029.61985190038;12983.35083245815;13913.277726782952;14167.220802855529;14317.64205894861;14357.856421435652;15307.46423875159;15549.844501555292;15630;15841.002350503835;16408.343666253215;13989.300534973007;14451.85548144505;14697.485588004369;15067.698646027236;15858.60353491172;12613.369331533204;14276;14762.114273143647;15093.24533773285;15146.772798407812;13719.519816806363;14150.43398264099;14509.274536272933;14575.271236438348;15395.61510962235;12060.939695301819;12088.697782555506;12164.513419463123;12441.253524788543;12690.365481725692;11432.371219583003;11573.826392604286;11614.41342729799;11737.589184378847;11752.11859110553 03:46:49 INFO - PROCESS | 29830 | __end_tp_report 03:46:49 INFO - PROCESS | 29830 | __start_cc_report 03:46:49 INFO - PROCESS | 29830 | _x_x_mozilla_cycle_collect,910 03:46:49 INFO - PROCESS | 29830 | __end_cc_report 03:46:49 INFO - PROCESS | 29830 | __startTimestamp1478951209576__endTimestamp 03:46:49 INFO - PROCESS | 29830 | 03:46:49 INFO - PROCESS | 29830 | ------- Summary: start ------- 03:46:49 INFO - PROCESS | 29830 | Number of tests: 6 03:46:49 INFO - PROCESS | 29830 | 03:46:49 INFO - PROCESS | 29830 | [#0] dojo.html Cycles:165 Average:5644.54 Median:5891.06 stddev:657.33 (11.2%) stddev-sans-first:629.29 03:46:49 INFO - PROCESS | 29830 | Values: 3140.1 3176.3 3194.6 3214.8 3231.2 5655.2 5708.9 5738.0 5750.7 5758.6 5630.5 5685.8 5700.2 5703.9 5732.9 5079.9 5124.8 5129.5 5139.1 5213.0 5065.3 5202.0 5205.7 5206.1 5224.3 5044.6 5091.2 5123.4 5151.7 5175.1 5130.3 5152.6 5156.2 5243.0 5243.3 5090.6 5110.1 5191.6 5208.9 5230.6 5479.5 5505.3 5566.9 5603.3 5635.9 5931.9 5951.5 5957.8 6021.3 6029.7 6060.5 6068.2 6075.9 6082.2 6083.2 5507.8 5569.9 5571.4 5615.0 5624.7 6208.7 6222.3 6226.3 6233.2 6239.8 5962.0 5963.3 5964.9 5968.3 5969.5 5846.3 5915.7 5959.7 6038.9 6046.7 6085.5 6091.5 6114.4 6128.1 6128.7 6024.6 6053.2 6057.0 6080.1 6099.3 4501.9 4531.5 4607.5 4669.8 4677.6 4119.1 4138.4 4181.3 4182.1 4183.2 5794.7 5796.5 5800.7 5802.0 5804.5 5944.3 6130.9 6134.9 6140.7 6145.4 5630.8 5760.1 5801.6 5812.5 5888.7 5304.0 5333.6 5344.3 5345.7 5348.3 6346.4 6352.6 6358.2 6360.7 6368.7 5969.4 6013.6 6065.9 6102.2 6122.2 6353.2 6353.4 6357.7 6361.7 6365.0 5958.4 6019.9 6026.7 6030.9 6050.3 5863.6 5922.9 6023.7 6061.0 6183.9 5986.0 5988.3 5992.8 5993.0 6025.1 5989.5 5990.2 5992.7 5997.9 6001.5 5681.8 5694.5 5887.3 5911.9 5949.3 6060.0 6063.7 6077.1 6104.1 6110.2 5872.8 5887.9 5891.1 5910.7 5916.6 03:46:49 INFO - PROCESS | 29830 | 03:46:49 INFO - PROCESS | 29830 | [#1] ext.html Cycles:180 Average:69842.62 Median:22136.40 stddev:145197.63 (655.9%) stddev-sans-first:145511.86 03:46:49 INFO - PROCESS | 29830 | Values: 592.7 620.6 632.1 640.5 647.5 34957.7 47461.5 48995.8 49058.8 49094.8 50157.0 50577.5 50667.0 50707.7 50781.0 8535.1 9422.6 9570.0 9584.0 9588.7 8276.9 11089.7 11403.7 11411.9 11692.2 11556.3 11597.5 11624.2 11626.0 11629.4 11450.1 11508.7 11522.5 11526.1 11535.3 11198.6 11245.9 11267.8 11268.3 11272.8 48079.3 48282.0 48346.3 48442.0 48572.3 3929.9 3952.3 4028.0 4097.2 4116.5 35539.0 35541.0 35611.6 35824.5 35867.8 4757.2 4798.4 4811.8 4819.5 4921.3 392917.0 393970.0 397032.0 397893.0 399062.0 39998.8 63489.4 63959.7 63993.4 63994.4 39818.2 42666.4 42798.6 42832.8 42922.6 18036.5 18205.2 18206.8 18220.3 18227.1 13674.4 13818.7 14871.2 15283.2 15345.2 9685.8 9712.6 9739.1 9789.4 9790.7 3663.1 3721.7 3726.9 3745.0 3784.2 1011.2 1076.6 1123.9 1180.6 1184.7 34864.5 35060.6 35167.8 35403.0 35478.0 1122.9 1151.9 1177.3 1195.4 1298.1 18463.0 18555.9 18587.5 18678.3 18761.0 640019.0 650163.0 650343.0 652456.0 653769.0 65768.7 66586.7 66598.7 66606.0 66609.0 1336.3 1369.0 1423.3 1467.7 1468.3 33829.8 36204.8 36313.6 36535.0 36610.0 55783.0 62104.4 63856.7 63859.0 68942.0 553930.0 559769.0 560338.0 560427.0 561277.0 30952.5 32060.4 32321.2 33850.5 34143.0 53166.7 53255.5 53985.0 54420.5 54987.0 44212.3 44659.1 45350.5 46211.3 47317.5 45375.5 45401.5 46128.3 46306.3 46949.5 45080.3 45141.5 45640.5 45855.5 46265.5 39135.0 40244.8 40742.0 40872.0 41361.0 30309.4 30568.7 30570.0 30619.2 30977.8 03:46:49 INFO - PROCESS | 29830 | 03:46:49 INFO - PROCESS | 29830 | [#2] jquery.html Cycles:180 Average:29272.00 Median:21157.24 stddev:79106.21 (373.9%) stddev-sans-first:79298.42 03:46:49 INFO - PROCESS | 29830 | Values: 398.5 408.5 408.9 412.0 422.4 14374.8 16474.2 20489.1 21227.9 21978.8 20024.8 20888.0 20891.3 20958.9 21010.5 5951.3 5965.2 6097.0 6164.1 6165.5 4465.5 4666.4 6039.5 6126.6 6140.9 6488.8 6762.0 6784.7 6886.3 6898.6 6623.2 6792.0 6809.8 6820.1 6861.6 5344.5 6006.3 6012.4 6014.1 6021.0 14720.3 15099.6 15111.4 15114.8 15157.7 2391.8 2440.5 2471.7 2512.8 2586.1 10548.5 10789.7 10791.1 10965.8 11266.8 2002.1 2039.9 2063.1 2065.3 2151.6 73777.3 79337.6 80008.0 86137.6 90259.1 11226.1 11268.3 11398.0 11777.3 11896.6 16469.5 16492.9 16501.6 16507.2 16512.7 8765.7 8913.7 8915.6 8992.6 9062.6 8405.1 8537.9 8707.6 8805.3 8891.9 4898.9 4919.8 4980.2 5049.7 5078.3 2014.2 2052.6 2066.5 2104.8 2164.0 919.4 1004.1 1007.4 1082.7 1126.6 20556.9 20642.2 21117.0 21131.8 21182.7 1035.8 1044.0 1061.6 1081.3 1093.1 11254.5 11633.6 11665.0 11914.0 11937.9 397346.0 400097.0 518602.0 525659.0 525849.0 93632.8 95458.0 101790.9 106081.0 106952.0 1073.4 1093.9 1096.1 1100.5 1109.2 16622.7 16881.4 16974.9 17256.6 17627.2 43405.6 44464.6 44627.3 44804.3 45092.0 64866.4 65049.0 65115.0 65462.3 65994.0 9070.5 9199.9 9407.6 9435.1 9574.8 13280.4 13879.7 14229.7 14353.5 14372.8 10168.8 12126.0 12370.3 12472.0 12518.2 9846.4 11911.9 12275.3 12541.2 12792.2 12086.4 12111.8 12381.3 12659.2 12675.4 8945.6 9062.5 9185.6 9259.1 9453.9 11341.9 11805.9 12186.8 12289.5 12549.4 03:46:49 INFO - PROCESS | 29830 | 03:46:49 INFO - PROCESS | 29830 | [#3] mootools.html Cycles:180 Average:6110.74 Median:2687.47 stddev:3464.28 (128.9%) stddev-sans-first:3456.14 03:46:49 INFO - PROCESS | 29830 | Values: 1430.1 1555.8 1591.9 1772.1 1919.0 7702.6 9116.5 9403.5 9404.4 9646.7 9396.2 9470.3 9482.5 9485.2 9485.8 2445.3 2468.6 2475.6 2476.4 2476.6 2352.5 2453.2 2454.4 2455.5 2458.5 2499.8 2519.3 2559.0 2561.6 2561.9 2445.9 2454.5 2476.3 2476.5 2485.8 2301.5 2326.7 2336.6 2338.6 2355.1 9248.5 9273.4 9405.6 9428.4 9432.7 5542.0 5709.6 5743.4 5749.5 5755.5 3745.6 3751.5 3794.3 3825.3 3827.8 1302.6 1307.8 1313.3 1346.5 1350.7 12372.5 12422.6 12508.6 12521.4 12527.7 4697.8 4709.4 4756.9 4834.9 4886.0 9899.2 9916.5 9975.0 9977.2 9980.0 5528.6 5531.0 5564.6 5572.9 5577.9 4708.2 4857.6 5141.2 5150.7 5252.7 4050.5 4094.6 4137.0 4222.7 4242.7 1254.1 1267.5 1270.0 1270.1 1293.4 999.1 1003.9 1028.8 1028.8 1032.1 6359.8 6408.6 6416.1 6690.3 6707.9 488.9 489.9 496.7 503.2 508.2 4324.7 4515.8 4524.0 4527.8 4592.6 11422.4 11746.9 11809.9 11810.4 11834.9 11514.4 11552.2 11666.5 11690.3 11730.2 8731.4 8814.3 8818.2 8835.2 8842.9 6468.8 6508.4 6522.9 6543.1 6593.9 10666.9 10948.7 10958.9 10968.2 10974.6 10731.2 10737.5 10889.6 10974.5 11001.2 7241.2 7345.7 7833.3 7896.9 7905.6 9155.1 9163.0 9217.5 9232.2 9242.3 7938.9 8118.2 8201.7 8431.3 8459.4 7795.8 7946.1 8071.7 8163.0 8252.2 6997.4 7067.2 7092.5 7106.1 7269.3 5174.4 5264.0 5367.9 5390.0 5475.0 5513.7 5629.8 5631.0 5707.0 5775.9 03:46:49 INFO - PROCESS | 29830 | 03:46:49 INFO - PROCESS | 29830 | [#4] prototype.html Cycles:180 Average:4854.63 Median:5447.43 stddev:1429.87 (26.2%) stddev-sans-first:1393.11 03:46:49 INFO - PROCESS | 29830 | Values: 338.0 353.0 353.2 359.9 360.1 5517.6 5583.8 5592.2 5625.4 5664.4 5515.8 5563.6 5567.8 5583.4 5584.4 4092.3 4381.1 4390.2 4396.0 4441.4 3878.3 4302.2 4355.5 4363.3 4398.4 4298.2 4319.3 4323.5 4335.7 4350.5 4164.8 4381.1 4384.7 4421.9 4426.4 3792.9 3833.8 3886.3 3907.2 3928.5 5416.9 5428.8 5431.6 5435.8 5478.7 4738.7 5038.5 5059.0 5073.7 5075.4 5760.8 5769.2 5789.6 5816.4 5886.4 4204.1 4208.3 4219.9 4257.3 4275.8 5889.5 6049.2 6149.5 6177.0 6193.2 4266.5 4276.6 4291.8 4342.5 4349.6 4388.6 4391.3 4394.5 4412.1 4419.6 4916.6 5027.9 5064.3 5070.5 5071.2 5870.5 5870.6 5877.2 5894.8 5924.4 1484.9 1495.5 1504.9 1511.6 1512.2 939.9 969.8 974.8 976.9 997.2 4955.4 4983.4 4990.3 5006.4 5095.4 5209.2 5246.4 5248.6 5249.6 5274.0 4808.9 4959.0 5015.4 5027.9 5031.8 2814.5 2824.2 2843.7 2844.5 2854.7 5418.5 5459.1 5473.9 5493.1 5495.5 5421.3 5784.0 6130.4 6177.4 6186.7 5930.0 6003.8 6046.3 6186.4 6193.3 5964.6 5997.9 6000.4 6006.9 6048.6 5629.6 5941.7 5973.2 5982.4 6021.3 6229.6 6230.7 6243.9 6247.5 6249.9 4336.6 4381.7 4402.2 4404.7 4428.0 5905.9 6012.9 6014.4 6044.3 6059.0 5840.8 5902.9 5905.3 5907.7 5970.3 5677.8 5863.4 5905.2 5950.4 5974.5 5875.8 5887.8 5898.6 5901.1 5907.9 5927.3 5931.7 5943.8 5967.2 6000.0 5511.0 5753.6 5770.9 5792.9 5796.7 03:46:49 INFO - PROCESS | 29830 | 03:46:49 INFO - PROCESS | 29830 | [#5] yui.html Cycles:180 Average:22069.17 Median:4720.68 stddev:32008.67 (678.1%) stddev-sans-first:32058.69 03:46:49 INFO - PROCESS | 29830 | Values: 818.2 826.3 870.7 880.3 896.8 16680.6 17860.6 17864.5 17899.6 17923.6 14488.9 14505.1 14509.0 14522.6 15519.9 726.8 742.5 862.9 888.2 893.6 873.0 883.6 885.4 885.5 887.1 947.6 948.5 949.0 949.3 950.9 752.7 776.8 887.3 910.5 938.6 677.1 677.2 677.5 677.6 677.7 16105.4 16209.1 16263.9 16286.0 16301.4 14709.6 15312.5 15350.2 15729.9 15949.0 13506.1 13752.4 13758.7 13898.7 14115.2 4642.3 4676.4 4690.7 4759.1 4846.9 87294.0 87303.6 87344.0 87588.1 88247.6 12255.4 12351.6 12468.6 12491.8 12676.6 22781.3 22840.8 22874.4 22915.9 22935.2 8655.8 8731.6 8760.5 8870.2 8955.6 4821.2 4919.3 5016.0 5066.3 5180.6 3769.2 3849.0 3906.4 3910.7 3914.5 636.5 638.8 646.1 653.2 654.2 589.3 658.8 672.3 685.7 689.6 9492.0 9506.5 9733.2 9994.6 10015.9 615.2 615.3 633.7 655.5 665.9 4679.8 4750.6 4780.9 4816.1 4816.2 76569.2 77351.0 132832.0 134687.3 135337.0 109771.0 112965.9 115864.0 115948.0 118205.0 84028.0 84265.7 98306.0 100762.0 101339.5 9483.9 9608.0 9877.5 9964.3 10128.6 54147.7 54374.0 55412.7 56056.4 56500.4 50556.2 67843.7 69678.7 75370.0 76029.6 12983.4 13913.3 14167.2 14317.6 14357.9 15307.5 15549.8 15630.0 15841.0 16408.3 13989.3 14451.9 14697.5 15067.7 15858.6 12613.4 14276.0 14762.1 15093.2 15146.8 13719.5 14150.4 14509.3 14575.3 15395.6 12060.9 12088.7 12164.5 12441.3 12690.4 11432.4 11573.8 11614.4 11737.6 11752.1 03:46:49 INFO - PROCESS | 29830 | -------- Summary: end -------- 03:46:49 INFO - PROCESS | 29830 | 03:46:50 INFO - TEST-INFO | 29830: exit 0 03:46:50 INFO - TEST-OK | dromaeo_css | took 1114105ms 03:46:50 INFO - TEST-START | kraken 03:46:50 INFO - operating with platform_type : linux_ 03:46:50 INFO - Initialising browser for kraken test... 03:46:50 INFO - TEST-INFO | started process 30145 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLFUPMd/profile http://localhost:49214/getInfo.html) 03:46:54 INFO - PROCESS | 30145 | __metrics Screen width/height:1600/1200 03:46:54 INFO - PROCESS | 30145 | colorDepth:24 03:46:54 INFO - PROCESS | 30145 | Browser inner width/height: 1024/697 03:46:54 INFO - PROCESS | 30145 | __metrics 03:46:54 INFO - PROCESS | 30145 | 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" 03:46:54 INFO - PROCESS | 30145 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:46:55 INFO - PROCESS | 30145 | console.error: PushService: 03:46:55 INFO - PROCESS | 30145 | stateChangeProcessEnqueue: Error transitioning state 03:46:55 INFO - PROCESS | 30145 | UnknownError 03:46:55 INFO - TEST-INFO | 30145: exit 0 03:46:55 INFO - Browser initialized. 03:46:55 INFO - Running cycle 1/1 for kraken test... 03:46:55 INFO - TEST-INFO | started process 30236 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLFUPMd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:07 INFO - PROCESS | 30236 | RSS: Main: 144404480 03:47:07 INFO - PROCESS | 30236 | 03:47:10 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49214/tests/kraken/driver.html?testName=audio-beat-detection) 03:47:10 INFO - PROCESS | 30236 | RSS: Main: 185958400 03:47:10 INFO - PROCESS | 30236 | 03:47:13 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49214/tests/kraken/driver.html?testName=audio-dft) 03:47:14 INFO - PROCESS | 30236 | RSS: Main: 176070656 03:47:14 INFO - PROCESS | 30236 | 03:47:16 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49214/tests/kraken/driver.html?testName=audio-fft) 03:47:17 INFO - PROCESS | 30236 | RSS: Main: 267587584 03:47:17 INFO - PROCESS | 30236 | 03:47:19 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49214/tests/kraken/driver.html?testName=audio-oscillator) 03:47:20 INFO - PROCESS | 30236 | RSS: Main: 173977600 03:47:20 INFO - PROCESS | 30236 | 03:47:22 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49214/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:47:22 INFO - PROCESS | 30236 | RSS: Main: 175505408 03:47:22 INFO - PROCESS | 30236 | 03:47:25 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49214/tests/kraken/driver.html?testName=imaging-darkroom) 03:47:26 INFO - PROCESS | 30236 | RSS: Main: 211243008 03:47:26 INFO - PROCESS | 30236 | 03:47:29 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49214/tests/kraken/driver.html?testName=imaging-desaturate) 03:47:30 INFO - PROCESS | 30236 | RSS: Main: 209969152 03:47:30 INFO - PROCESS | 30236 | 03:47:33 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49214/tests/kraken/driver.html?testName=json-parse-financial) 03:47:33 INFO - PROCESS | 30236 | RSS: Main: 210477056 03:47:33 INFO - PROCESS | 30236 | 03:47:35 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49214/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:47:35 INFO - PROCESS | 30236 | RSS: Main: 172044288 03:47:35 INFO - PROCESS | 30236 | 03:47:37 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49214/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:47:38 INFO - PROCESS | 30236 | RSS: Main: 179265536 03:47:38 INFO - PROCESS | 30236 | 03:47:40 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49214/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:47:40 INFO - PROCESS | 30236 | RSS: Main: 188542976 03:47:40 INFO - PROCESS | 30236 | 03:47:43 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:47:43 INFO - PROCESS | 30236 | RSS: Main: 197451776 03:47:43 INFO - PROCESS | 30236 | 03:47:46 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:47:47 INFO - PROCESS | 30236 | RSS: Main: 194187264 03:47:47 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | Cycle 1(1): loaded http://localhost:49214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49214/tests/kraken/driver.html?testName=ai-astar) 03:47:48 INFO - PROCESS | 30236 | RSS: Main: 243142656 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | __start_tp_report 03:47:48 INFO - PROCESS | 30236 | _x_x_mozilla_page_load 03:47:48 INFO - PROCESS | 30236 | _x_x_mozilla_page_load_details 03:47:48 INFO - PROCESS | 30236 | |i|pagename|runs| 03:47:48 INFO - PROCESS | 30236 | |0;ai-astar;109;111;111;110;111;111;111;110;110;110 03:47:48 INFO - PROCESS | 30236 | |1;audio-beat-detection;148;120;119;155;117;126;117;116;144;117 03:47:48 INFO - PROCESS | 30236 | |2;audio-dft;161;160;160;159;160;160;162;159;160;161 03:47:48 INFO - PROCESS | 30236 | |3;audio-fft;75;76;75;113;75;75;76;75;94;74 03:47:48 INFO - PROCESS | 30236 | |4;audio-oscillator;81;81;110;81;82;82;107;81;81;114 03:47:48 INFO - PROCESS | 30236 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 03:47:48 INFO - PROCESS | 30236 | |6;imaging-darkroom;146;146;145;146;146;147;145;146;145;145 03:47:48 INFO - PROCESS | 30236 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 03:47:48 INFO - PROCESS | 30236 | |8;json-parse-financial;51;48;50;50;51;52;50;49;50;49 03:47:48 INFO - PROCESS | 30236 | |9;json-stringify-tinderbox;58;61;59;59;60;58;59;59;58;58 03:47:48 INFO - PROCESS | 30236 | |10;stanford-crypto-aes;73;73;73;81;78;72;81;72;72;73 03:47:48 INFO - PROCESS | 30236 | |11;stanford-crypto-ccm;120;121;123;118;121;121;122;124;157;124 03:47:48 INFO - PROCESS | 30236 | |12;stanford-crypto-pbkdf2;153;151;151;153;149;152;152;155;152;150 03:47:48 INFO - PROCESS | 30236 | |13;stanford-crypto-sha256-iterative;64;61;62;61;64;61;62;60;60;58 03:47:48 INFO - PROCESS | 30236 | __end_tp_report 03:47:48 INFO - PROCESS | 30236 | __start_cc_report 03:47:48 INFO - PROCESS | 30236 | _x_x_mozilla_cycle_collect,1715 03:47:48 INFO - PROCESS | 30236 | __end_cc_report 03:47:48 INFO - PROCESS | 30236 | __startTimestamp1478951268965__endTimestamp 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | ------- Summary: start ------- 03:47:48 INFO - PROCESS | 30236 | Number of tests: 14 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:0.70 (0.6%) stddev-sans-first:0.53 03:47:48 INFO - PROCESS | 30236 | Values: 109.0 111.0 111.0 110.0 111.0 111.0 111.0 110.0 110.0 110.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:123.00 stddev:15.06 (12.2%) stddev-sans-first:14.11 03:47:48 INFO - PROCESS | 30236 | Values: 148.0 120.0 119.0 155.0 117.0 126.0 117.0 116.0 144.0 117.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 03:47:48 INFO - PROCESS | 30236 | Values: 161.0 160.0 160.0 159.0 160.0 160.0 162.0 159.0 160.0 161.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:12.79 (17.0%) stddev-sans-first:13.39 03:47:48 INFO - PROCESS | 30236 | Values: 75.0 76.0 75.0 113.0 75.0 75.0 76.0 75.0 94.0 74.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:81.00 stddev:14.13 (17.4%) stddev-sans-first:14.61 03:47:48 INFO - PROCESS | 30236 | Values: 81.0 81.0 110.0 81.0 82.0 82.0 107.0 81.0 81.0 114.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 03:47:48 INFO - PROCESS | 30236 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 03:47:48 INFO - PROCESS | 30236 | Values: 146.0 146.0 145.0 146.0 146.0 147.0 145.0 146.0 145.0 145.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 03:47:48 INFO - PROCESS | 30236 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:1.15 (2.3%) stddev-sans-first:1.17 03:47:48 INFO - PROCESS | 30236 | Values: 51.0 48.0 50.0 50.0 51.0 52.0 50.0 49.0 50.0 49.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:0.99 (1.7%) stddev-sans-first:1.00 03:47:48 INFO - PROCESS | 30236 | Values: 58.0 61.0 59.0 59.0 60.0 58.0 59.0 59.0 58.0 58.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:73.00 stddev:3.71 (5.1%) stddev-sans-first:3.87 03:47:48 INFO - PROCESS | 30236 | Values: 73.0 73.0 73.0 81.0 78.0 72.0 81.0 72.0 72.0 73.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:122.50 stddev:11.36 (9.3%) stddev-sans-first:11.90 03:47:48 INFO - PROCESS | 30236 | Values: 120.0 121.0 123.0 118.0 121.0 121.0 122.0 124.0 157.0 124.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.80 Median:152.00 stddev:1.69 (1.1%) stddev-sans-first:1.73 03:47:48 INFO - PROCESS | 30236 | Values: 153.0 151.0 151.0 153.0 149.0 152.0 152.0 155.0 152.0 150.0 03:47:48 INFO - PROCESS | 30236 | 03:47:48 INFO - PROCESS | 30236 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.50 stddev:1.83 (3.0%) stddev-sans-first:1.66 03:47:48 INFO - PROCESS | 30236 | Values: 64.0 61.0 62.0 61.0 64.0 61.0 62.0 60.0 60.0 58.0 03:47:48 INFO - PROCESS | 30236 | -------- Summary: end -------- 03:47:48 INFO - PROCESS | 30236 | 03:47:50 INFO - TEST-INFO | 30236: exit 0 03:47:50 INFO - TEST-OK | kraken | took 59723ms 03:47:50 INFO - SUITE-END | took 1173s 03:47:50 INFO - Completed test suite (00:19:33) 03:47:50 INFO - javascript benchmark 03:47:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3140.0579826052185, 3176.3329700762847, 3194.584703988481, 3214.807111573304, 3231.2406584452665, 5655.23654306669, 5708.942910570893, 5737.971310143454, 5750.654960702361, 5758.625689330196, 5630.521405680512, 5685.800996965107, 5700.201971723962, 5703.885922281552, 5732.885342293152, 5079.923801142986, 5124.769385377648, 5129.512696293846, 5139.12634852076, 5213.009528189651, 5065.290859279704, 5201.973990130044, 5205.661631993928, 5206.062908676437, 5224.29472021278, 5044.646874718771, 5091.185410334348, 5123.410807757118, 5151.6908985460805, 5175.068487672217, 5130.333056702623, 5152.562032227265, 5156.20078887773, 5242.973785131088, 5243.318368612094, 5090.643654944155, 5110.105731496973, 5191.5846732261325, 5208.921866172011, 5230.607704422146, 5479.53423958964, 5505.339359276913, 5566.888662226774, 5603.2715746953045, 5635.8591035224035, 5931.940680593182, 5951.464368206839, 5957.791477298274, 6021.307549631804, 6029.698515074273, 6060.484858787008, 6068.180795592582, 6075.939240607581, 6082.239720034996, 6083.239595050619, 5507.779688812483, 5569.944300557024, 5571.359293681238, 5615.0454422748235, 5624.6625202487985, 6208.658523781145, 6222.284437289679, 6226.315105338409, 6233.189685340877, 6239.812805615838, 5962.046072628359, 5963.254593175853, 5964.910526342108, 5968.283805943228, 5969.522438204933, 5846.327672317653, 5915.674637894957, 5959.67221802805, 6038.939610603926, 6046.667433291212, 6085.452309292096, 6091.543134264948, 6114.419130182626, 6128.1114238435175, 6128.662923539248, 6024.638521688713, 6053.243344581927, 6056.9697151513965, 6080.14877917092, 6099.329073801879, 4501.864944051684, 4531.501534831166, 4607.470140933836, 4669.766511674471, 4677.64917631179, 4119.114390406077, 4138.399932009842, 4181.330987042059, 4182.059036716714, 4183.163367326486, 5794.681292528951, 5796.478316951495, 5800.709964501758, 5801.970990145022, 5804.535637149018, 5944.256967879015, 6130.908036379368, 6134.877302454015, 6140.692965351804, 6145.354737752561, 5630.831075067755, 5760.135979603009, 5801.593888427854, 5812.505936995402, 5888.735006924699, 5304.0, 5333.57331413486, 5344.251804747339, 5345.65253258537, 5348.277982472394, 6346.397092276226, 6352.587081839665, 6358.1734374531015, 6360.713767880364, 6368.681565921685, 5969.3732158122775, 6013.579049466495, 6065.909011364744, 6102.176206212104, 6122.204113465221, 6353.237611486466, 6353.428191462791, 6357.682115894279, 6361.650109244036, 6364.968175159094, 5958.374370691188, 6019.909701354571, 6026.668533230714, 6030.939690603213, 6050.273967123797, 5863.560232982458, 5922.881542369215, 6023.698815059141, 6061.0, 6183.907241391293, 5986.0, 5988.341282459013, 5992.760289588367, 5992.970035149797, 6025.0661147522205, 5989.520838332835, 5990.221271234712, 5992.67040312787, 5997.880042399041, 6001.519878409804, 5681.77272909079, 5694.515966142924, 5887.322957859901, 5911.911321330097, 5949.315828679584, 6060.0, 6063.696815159136, 6077.088436734349, 6104.114903338902, 6110.236220472441, 5872.823815285548, 5887.882242355215, 5891.05743081105, 5910.674912879833, 5916.615419997858], "value": 5598.454720504322, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.7480820651222, 620.5935112501312, 632.0866348126958, 640.4716109209903, 647.475544808705, 34957.650423495754, 47461.52538474614, 48995.75502122493, 49058.75470622642, 49094.75452622732, 50157.0, 50577.49422505774, 50667.0, 50707.74646126764, 50781.0, 8535.061143274235, 9422.575984080715, 9570.0, 9584.0, 9588.712338629852, 8276.875846862302, 11089.667309980674, 11403.657890263305, 11411.942940285287, 11692.181547291693, 11556.30662160269, 11597.47811348487, 11624.186306958516, 11626.0, 11629.418529073555, 11450.083993280517, 11508.712282192928, 11522.481488333045, 11526.135539834546, 11535.307881527051, 11198.608048718337, 11245.943770281177, 11267.830982535268, 11268.323900565993, 11272.774544509064, 48079.278810817865, 48282.0, 48346.27480587794, 48442.0, 48572.271415928786, 3929.9214015719813, 3952.26883026641, 4028.0, 4097.160082183175, 4116.485439320085, 35539.0245365649, 35541.0, 35611.64388356109, 35824.462633060524, 35867.82066089679, 4757.238841785333, 4798.424189097331, 4811.78346974387, 4819.5180481951875, 4921.286413470027, 392917.0, 393970.0, 397032.0, 397893.0, 399062.0, 39998.80000600037, 63489.36510634835, 63959.68020159962, 63993.36006639967, 63994.36005639977, 39818.20363592712, 42666.360004599956, 42798.57201427946, 42832.78583607062, 42922.57077429248, 18036.458906232834, 18205.180766865524, 18206.817931820777, 18220.27118915255, 18227.08864556767, 13674.384652690454, 13818.654533636538, 14871.182084985214, 15283.159426231441, 15345.156016419203, 9685.757856053655, 9712.562934667956, 9739.074787895139, 9789.412635241766, 9790.706278811647, 3663.120850995742, 3721.702263818888, 3726.850925962931, 3744.962550374516, 3784.2053168834855, 1011.2163073618005, 1076.5959345120368, 1123.9494222759995, 1180.557291015869, 1184.7156682396162, 34864.47703284453, 35060.64939350574, 35167.824160879034, 35403.0, 35478.0, 1122.9494672739747, 1151.9366434846163, 1177.2583272538245, 1195.4101045609318, 1298.0783643612951, 18463.0, 18555.90722046408, 18587.535311617317, 18678.252869885324, 18761.0, 640019.0, 650163.0, 650343.0, 652456.0, 653769.0, 65768.67115664486, 66586.66706666436, 66598.66700666466, 66606.0, 66609.0, 1336.2717319061135, 1368.9726205475836, 1423.3168079321777, 1467.7211329847296, 1468.258529442646, 33829.83085084559, 36204.81897590495, 36313.63686363103, 36535.0, 36610.0, 55783.0, 62104.37895620986, 63856.68071659612, 63859.0, 68942.0, 553930.0, 559769.0, 560338.0, 560427.0, 561277.0, 30952.535711963887, 32060.358792823547, 32321.191970200933, 33850.49224261589, 34143.0, 53166.73416633047, 53255.46744532505, 53985.0, 54420.455795441536, 54987.0, 44212.336814948445, 44659.10681786281, 45350.54649453595, 46211.30683039824, 47317.52682473269, 45375.54624453713, 45401.545984539734, 46128.30807537823, 46306.30540541827, 46949.530504694514, 45080.323795143755, 45141.54858451374, 45640.54359456363, 45855.54144458646, 46265.53734462612, 39135.0, 40244.79877600593, 40742.0, 40872.0, 41361.0, 30309.393812124075, 30568.694313057473, 30570.0, 30619.2345191372, 30977.845110774302], "value": 21534.448190268904, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.5456579499373, 408.4946970414023, 408.9243489954359, 411.9691023173262, 422.4276105876537, 14374.784378234335, 16474.17629118544, 20489.07799149036, 21227.893860530676, 21978.780212197915, 20024.799752002476, 20888.0, 20891.268805591808, 20958.89520552395, 21010.474738131554, 5951.345352011275, 5965.164876917224, 6096.969515152418, 6164.075388691687, 6165.506759459255, 4465.486469056052, 4666.4400271967415, 6039.516838652919, 6126.571140020201, 6140.877182456348, 6488.837779055514, 6762.0, 6784.660766961632, 6886.311368863124, 6898.620575868325, 6623.23832759234, 6792.0, 6809.829754256183, 6820.079289295931, 6861.622610756406, 5344.4922732340365, 6006.339302676702, 6012.398760123996, 6014.12795144706, 6021.036634138561, 14720.263986800725, 15099.622509437351, 15111.39554417822, 15114.773278400833, 15157.696846063127, 2391.845572790639, 2440.5362981033545, 2471.7033955925403, 2512.849229046263, 2586.1465716313573, 10548.525316360781, 10789.676309710643, 10791.136709063256, 10965.780684386347, 11266.774664506664, 2002.149086638173, 2039.8980050997538, 2063.13348393675, 2065.2874758208545, 2151.6449785785326, 73777.26222737758, 79337.60331198365, 80008.0, 86137.56931215366, 90259.09740902572, 11226.101911847032, 11268.323900565993, 11398.0, 11777.293362398112, 11896.583619573274, 16469.505914822574, 16492.917535412485, 16501.58746031359, 16507.174641267888, 16512.669746605, 8765.737027889174, 8913.732588022369, 8915.643374265086, 8992.595333210022, 9062.592183351764, 8405.117462666454, 8537.871931921027, 8707.564621768886, 8805.251553617885, 8891.911080889238, 4898.853034408973, 4919.77860996256, 4980.178270585386, 5049.671771334925, 5078.263651770472, 2014.1842553765748, 2052.568960518308, 2066.4730493724005, 2104.810567048973, 2163.967540486894, 919.4207649180973, 1004.0762498501397, 1007.3905287301077, 1082.7076689293847, 1126.583164229224, 20556.897215513825, 20642.17431302731, 21117.04973276206, 21131.78868211329, 21182.682259766116, 1035.8135535603512, 1044.0238377117419, 1061.5541472581535, 1081.3187691754144, 1093.1200383691046, 11254.549818007352, 11633.59282425111, 11665.0, 11913.987311078483, 11937.880621193677, 397346.0, 400097.0, 518602.0, 525659.0, 525849.0, 93632.78652460656, 95458.0, 101790.8567314613, 106081.0, 106952.0, 1073.4471747050275, 1093.9288946218471, 1096.0957210301533, 1100.5212732461405, 1109.1570406490964, 16622.667546648758, 16881.40915067967, 16974.915125424293, 17256.568585785455, 17627.20677569538, 43405.56594434015, 44464.55535444604, 44627.290917165534, 44804.32793508035, 45092.0, 64866.351336486034, 65049.0, 65115.0, 65462.34537654753, 65994.0, 9070.546472676207, 9199.908000919904, 9407.576659050223, 9435.103665151799, 9574.808503830023, 13280.402381892645, 13879.653008674864, 14229.71540569162, 14353.497627582985, 14372.784408233676, 10168.84746728785, 12126.0, 12370.283487734343, 12472.0, 12518.24890506536, 9846.359986600848, 11911.880881191077, 12275.324857132944, 12541.184822986477, 12792.168509046882, 12086.42876426059, 12111.75776484483, 12381.319027453577, 12659.177153484994, 12675.429605667583, 8945.59744811472, 9062.50156241413, 9185.632574696938, 9259.120383563552, 9453.905460945303, 11341.886581134413, 11805.940970295094, 12186.817197742219, 12289.503750434795, 12549.372531373578], "value": 9639.8406126769, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.113329735564, 1555.7666350047496, 1591.8806089543284, 1772.1139430284857, 1918.952026199344, 7702.576358300291, 9116.498592577405, 9403.529823508832, 9404.388714733537, 9646.710598682033, 9396.15434610885, 9470.337076404654, 9482.478463684512, 9485.193758530517, 9485.762855928606, 2445.266420073975, 2468.617364308535, 2475.5667758142345, 2476.418041760185, 2476.5913624251975, 2352.517733864562, 2453.2394957563124, 2454.435479839638, 2455.5211733711935, 2458.4591389894194, 2499.8375105618165, 2519.2945975126995, 2559.0, 2561.590145576708, 2561.871906404682, 2445.8504502883616, 2454.5459090068307, 2476.2818782553036, 2476.4675594747036, 2485.763852434025, 2301.5396920615776, 2326.720793504773, 2336.556054349685, 2338.6141286687675, 2355.05797680929, 9248.537573121317, 9273.397229180082, 9405.623775048989, 9428.387154834914, 9432.717018489388, 5542.0, 5709.62887412321, 5743.396943320933, 5749.511291540182, 5755.510781583571, 3745.6441638044344, 3751.4935483709896, 3794.29805485986, 3825.3496905526126, 3827.770333779982, 1302.5636411802059, 1307.8103674967076, 1313.2645718397632, 1346.481604582233, 1350.723101764146, 12372.505099795997, 12422.62732118047, 12508.624741257778, 12521.37393130349, 12527.74944501114, 4697.767045399914, 4709.364235828188, 4756.9286460703115, 4834.854954351375, 4886.022795440926, 9899.208063354914, 9916.454594997344, 9974.950125249472, 9977.201823854075, 9979.950100249453, 5528.585356098309, 5531.0, 5564.638298510584, 5572.888542229132, 5577.944220557823, 4708.246680531099, 4857.611391088704, 5141.203113517411, 5150.742462876841, 5252.737363131828, 4050.4936882889638, 4094.5700701426517, 4136.9793151034055, 4222.662187025031, 4242.7242229255, 1254.140913474273, 1267.5170051040304, 1269.955551555691, 1270.0982302565283, 1293.3727142335958, 999.115782532464, 1003.894591067942, 1028.753099256173, 1028.8456731490337, 1032.0659802878406, 6359.8092057238355, 6408.55140140195, 6416.101745755598, 6690.297518760557, 6707.899381509281, 488.9144399730033, 489.889774800667, 496.6697146397605, 503.17730510615405, 508.15646027594755, 4324.675649326313, 4515.841945531891, 4524.0, 4527.7509736964785, 4592.609628181575, 11422.428878556038, 11746.94126529379, 11809.940950295366, 11810.40947952599, 11834.940825295991, 11514.424278786028, 11552.249103808226, 11666.475008624462, 11690.298582085103, 11730.178887477965, 8731.432456890281, 8814.294856411472, 8818.162274583905, 8835.249003834746, 8842.867356989522, 6468.838279043061, 6508.381703738138, 6522.934770652233, 6543.116679248431, 6593.901091483535, 10666.946665266623, 10948.671539853816, 10958.945205274242, 10968.177386696188, 10974.561017559208, 10731.19516036316, 10737.516811743333, 10889.618863340062, 10974.506147223554, 11001.17491188148, 7241.203467618663, 7345.742898998509, 7833.255840695125, 7896.921030789848, 7905.604719764104, 9155.13026262504, 9162.954185229299, 9217.493037882712, 9232.21526170283, 9242.306826988137, 7938.880916786368, 8118.188181181835, 8201.71294004707, 8431.28334091609, 8459.407841451039, 7795.844083118419, 7946.125926148235, 8071.677132914618, 8163.0, 8252.174782521699, 6997.405220556309, 7067.151941767021, 7092.503524753424, 7106.111736032996, 7269.273072692688, 5174.404943431554, 5263.973680131575, 5367.892642147057, 5390.029794637003, 5475.0, 5513.696746678971, 5629.831105066854, 5630.971845140912, 5706.9714651426475, 5775.94224057754], "value": 4824.247115132713, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.9932401351973, 352.9876454324099, 353.1506726323193, 359.8578561468222, 360.1015466411304, 5517.586181036419, 5583.776648934048, 5592.161175823629, 5625.353084395296, 5664.433556644333, 5515.751791169397, 5563.638363506379, 5567.777288908439, 5583.357913839899, 5584.357798853133, 4092.3016947966557, 4381.14567659305, 4390.187815254172, 4395.956040439587, 4441.444819397583, 3878.3212937735784, 4302.225599392108, 4355.5208927018, 4363.301871700529, 4398.362237475564, 4298.247806633843, 4319.33050377192, 4323.546027667081, 4335.6748243881675, 4350.54319296474, 4164.75011499311, 4381.058072514409, 4384.693071484998, 4421.911561768763, 4426.3581780641625, 3792.924141517154, 3833.8083095845095, 3886.300465916134, 3907.179492306619, 3928.46965659638, 5416.864578385533, 5428.75570599324, 5431.592630552725, 5435.755391007414, 5478.726063696839, 4738.715677059353, 5038.538823741111, 5059.026979478006, 5073.746312684388, 5075.441701412841, 5760.7695692172265, 5769.249997500298, 5789.623674461147, 5816.418358164192, 5886.381929897342, 4204.138151678899, 4208.305629571117, 4219.91560168798, 4257.276263035292, 4275.7862106894845, 5889.528837692974, 6049.1833602463985, 6149.538784591174, 6177.0, 6193.194884665009, 4266.509351424564, 4276.593723596254, 4291.849785257501, 4342.522322544518, 4349.586789255016, 4388.648908087409, 4391.3412988051405, 4394.472663280363, 4412.051408947092, 4419.558044195554, 4916.631252656065, 5027.924581131286, 5064.290999260106, 5070.543651071347, 5071.239314102914, 5870.471657550841, 5870.589058765845, 5877.2083260450945, 5894.764209431574, 5924.407559244128, 1484.8960572759804, 1495.4765831958728, 1504.9473268435552, 1511.644763480581, 1512.160750783304, 939.9248060155171, 969.8399764038854, 974.8196583632051, 976.8925418203992, 997.1623835977668, 4955.355803745562, 4983.377077865267, 4990.2764099205415, 5006.424261209934, 5095.433196762281, 5209.244659524424, 5246.35378412, 5248.580113590903, 5249.553787927993, 5273.973630131824, 4808.879778005507, 4959.0, 5015.423226328946, 5027.874303142451, 5031.7987280509105, 2814.4511820194866, 2824.237455886899, 2843.658760948658, 2844.4879921613892, 2854.716227018377, 5418.512333889968, 5459.099248623933, 5473.863153421196, 5493.148561972901, 5495.53287970527, 5421.349438067457, 5784.0, 6130.38307398637, 6177.444030037228, 6186.690665466709, 5930.021546444875, 6003.8198854034445, 6046.3349031606485, 6186.381361863778, 6193.256809182927, 5964.642121472725, 5997.890390277813, 6000.429959153874, 6006.948783962702, 6048.576599637983, 5629.605927585029, 5941.702914854153, 5973.193618861572, 5982.4316689914385, 6021.337652858095, 6229.563930525001, 6230.719617617127, 6243.906341404791, 6247.468965138014, 6249.90626640327, 4336.566343365638, 4381.693281470267, 4402.229609818205, 4404.713693609905, 4427.955720442755, 5905.940940590711, 6012.9098063529955, 6014.398560144125, 6044.335123136363, 6059.030814985235, 5840.795572154954, 5902.911456328073, 5905.261842269716, 5907.675077870586, 5970.3432622412365, 5677.829665110054, 5863.355030946678, 5905.202797622266, 5950.375210602824, 5974.492168165754, 5875.823725288248, 5887.8233652990475, 5898.55760817949, 5901.144334071621, 5907.940920590911, 5927.2590926134235, 5931.733072011856, 5943.791967281124, 5967.194428752063, 6000.0, 5511.0, 5753.626014309224, 5770.913436298375, 5792.8566430421015, 5796.681182534832], "value": 4400.797332472152, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.2145140664961, 826.2770076183343, 870.7126648206092, 880.2561835249217, 896.8430524658191, 16680.58298542537, 17860.64278714425, 17864.464066078006, 17899.642007159848, 17923.55191120221, 14488.855111448882, 14505.05717128386, 14508.984371094028, 14522.564323070299, 15519.922400387983, 726.7964969808448, 742.4505865659401, 862.8576284912982, 888.1739981816908, 893.5576889439736, 872.9869051964225, 883.6023789294843, 885.4156256870467, 885.5262434597491, 887.0552861202831, 947.5925352098595, 948.4830767231875, 949.0225068179751, 949.2738055387634, 950.8621249918758, 752.6951584608242, 776.8057985503624, 887.2901678657048, 910.5492781073411, 938.629241449627, 677.0994577212346, 677.2313424263447, 677.485111315399, 677.6306912732523, 677.6645560447561, 16105.43630972922, 16209.108499032549, 16263.861529692926, 16286.0, 16301.429449969195, 14709.55871323862, 15312.540623781304, 15350.232488375537, 15729.92135039329, 15949.0, 13506.122102063335, 13752.381142848495, 13758.724825503534, 13898.652533686638, 14115.223662698547, 4642.326862604938, 4676.438827340741, 4690.718556886563, 4759.095771803346, 4846.927296090562, 87294.0, 87303.56348218281, 87344.0, 87588.12411875863, 88247.55876220642, 12255.448504817305, 12351.629451116482, 12468.563600273947, 12491.812622810665, 12676.556320528922, 22781.31656050321, 22840.771592283865, 22874.428139296317, 22915.88542057279, 22935.197268095537, 8655.78360540979, 8731.60707768152, 8760.51817150063, 8870.157335053289, 8955.641774328953, 4821.156297647897, 4919.311296418504, 5016.0, 5066.316047333563, 5180.611454140954, 3769.1707824278615, 3849.0377405648587, 3906.394508851076, 3910.7066969977363, 3914.549826769901, 636.4685487617799, 638.8051644248549, 646.0986923242051, 653.170473498654, 654.2476152424655, 589.3311091910626, 658.8221180281297, 672.3175976383974, 685.7359916432209, 689.5896941319907, 9491.955884852661, 9506.524673766284, 9733.22217590661, 9994.600215991424, 10015.899841001643, 615.1971676961518, 615.2709039787866, 633.7433339497511, 655.4526969980025, 665.8601693644292, 4679.836205732851, 4750.643701722384, 4780.880477988008, 4816.109019831342, 4816.20532612115, 76569.23430765733, 77351.0, 132832.0, 134687.32656336852, 135337.0, 109771.0, 112965.87034129554, 115864.0, 115948.0, 118205.0, 84028.0, 84265.73426573427, 98306.0, 100762.0, 101339.49330253301, 9483.913708339833, 9607.951960240154, 9877.50612469388, 9964.252681049094, 10128.645497407555, 54147.729261353445, 54374.0, 55412.72293638506, 56056.43943560676, 56500.434995651165, 50556.24165637445, 67843.66078169577, 69678.65160674164, 75370.0, 76029.61985190038, 12983.35083245815, 13913.277726782952, 14167.220802855529, 14317.64205894861, 14357.856421435652, 15307.46423875159, 15549.844501555292, 15630.0, 15841.002350503835, 16408.343666253215, 13989.300534973007, 14451.85548144505, 14697.485588004369, 15067.698646027236, 15858.60353491172, 12613.369331533204, 14276.0, 14762.114273143647, 15093.24533773285, 15146.772798407812, 13719.519816806363, 14150.43398264099, 14509.274536272933, 14575.271236438348, 15395.61510962235, 12060.939695301819, 12088.697782555506, 12164.513419463123, 12441.253524788543, 12690.365481725692, 11432.371219583003, 11573.826392604286, 11614.41342729799, 11737.589184378847, 11752.11859110553], "value": 8005.791323505225, "unit": "score"}], "name": "dromaeo_css", "value": 7631.563823089813, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 111.0, 110.0, 111.0, 111.0, 111.0, 110.0, 110.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 119.0, 155.0, 117.0, 126.0, 117.0, 116.0, 144.0, 117.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 159.0, 160.0, 160.0, 162.0, 159.0, 160.0, 161.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 113.0, 75.0, 75.0, 76.0, 75.0, 94.0, 74.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 110.0, 81.0, 82.0, 82.0, 107.0, 81.0, 81.0, 114.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 50.0, 50.0, 51.0, 52.0, 50.0, 49.0, 50.0, 49.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 59.0, 59.0, 60.0, 58.0, 59.0, 59.0, 58.0, 58.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 81.0, 78.0, 72.0, 81.0, 72.0, 72.0, 73.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 123.0, 118.0, 121.0, 121.0, 122.0, 124.0, 157.0, 124.0], "value": 125.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 151.0, 153.0, 149.0, 152.0, 152.0, 155.0, 152.0, 150.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 62.0, 61.0, 64.0, 61.0, 62.0, 60.0, 60.0, 58.0], "value": 61.3, "unit": "score"}], "name": "kraken", "value": 1448.7999999999997, "alertThreshold": 2.0}]} 03:47:50 INFO - Return code: 0 03:47:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:47:50 INFO - # TBPL SUCCESS # 03:47:50 INFO - Running post-action listener: _resource_record_post_action 03:47:50 INFO - [mozharness: 2016-11-12 11:47:50.279490Z] Finished run-tests step (success) 03:47:50 INFO - Running post-run listener: _resource_record_post_run 03:47:50 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 369684480; Read time: 52; Write time: 205836 03:47:50 INFO - TinderboxPrint: CPU usage
12.9% 03:47:50 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 52 03:47:50 INFO - TinderboxPrint: I/O write bytes / time
369,684,480 / 205,836 03:47:50 INFO - TinderboxPrint: CPU idle
8,267.1 (87.0%) 03:47:50 INFO - TinderboxPrint: CPU user
1,188.6 (12.5%) 03:47:50 INFO - TinderboxPrint: Swap in / out
0 / 0 03:47:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118816768; Read time: 0; Write time: 69856 03:47:50 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 250867712; Read time: 16; Write time: 135980 03:47:50 INFO - Running post-run listener: _upload_blobber_files 03:47:50 INFO - Blob upload gear active. 03:47:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:47:50 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 03:47:50 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'] 03:47:50 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 03:47:51 INFO - (blobuploader) - INFO - Open directory for files ... 03:47:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:47:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:47:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:52 INFO - (blobuploader) - INFO - Done attempting. 03:47:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:47:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:47:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:55 INFO - (blobuploader) - INFO - Done attempting. 03:47:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:47:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:47:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:47:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:47:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:47:56 INFO - (blobuploader) - INFO - Done attempting. 03:47:56 INFO - (blobuploader) - INFO - Iteration through files over. 03:47:56 INFO - Return code: 0 03:47:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:47:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:47:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2944f98faa450f2d461adacbbea99e25b78d68dd5d5b7a17d827ce49f2053057f11c4ff7c7b2f42cdf215cf8bfeac84cb501494ec8bb91c1ada37cd79eb19dcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18d524588ac4541c7817bb2db9d0bf3787cdb262674fb9d45d5f7e9484cccf561fd964abef8127de963193439669a06f702827d133cc79182d4e609b2b3a21de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9234deea0e22da224ba5384d8ef410f2389b5283c486ed4137b40f2b0f694e0ed70feb1785d86fe460862c774caf2821c5a24c8423be96de065a0366844e254"} 03:47:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:47:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:47:56 INFO - Contents: 03:47:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2944f98faa450f2d461adacbbea99e25b78d68dd5d5b7a17d827ce49f2053057f11c4ff7c7b2f42cdf215cf8bfeac84cb501494ec8bb91c1ada37cd79eb19dcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18d524588ac4541c7817bb2db9d0bf3787cdb262674fb9d45d5f7e9484cccf561fd964abef8127de963193439669a06f702827d133cc79182d4e609b2b3a21de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9234deea0e22da224ba5384d8ef410f2389b5283c486ed4137b40f2b0f694e0ed70feb1785d86fe460862c774caf2821c5a24c8423be96de065a0366844e254"} 03:47:56 INFO - Running post-run listener: copy_logs_to_upload_dir 03:47:56 INFO - Copying logs to upload dir... 03:47:56 INFO - mkdir: /builds/slave/test/build/upload/logs 03:47:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.555196 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-11-12 03:47:55.778819) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-12 03:47:55.782405) ========= 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=ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853 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/2944f98faa450f2d461adacbbea99e25b78d68dd5d5b7a17d827ce49f2053057f11c4ff7c7b2f42cdf215cf8bfeac84cb501494ec8bb91c1ada37cd79eb19dcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18d524588ac4541c7817bb2db9d0bf3787cdb262674fb9d45d5f7e9484cccf561fd964abef8127de963193439669a06f702827d133cc79182d4e609b2b3a21de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9234deea0e22da224ba5384d8ef410f2389b5283c486ed4137b40f2b0f694e0ed70feb1785d86fe460862c774caf2821c5a24c8423be96de065a0366844e254"} build_url:https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011386 build_url: 'https://queue.taskcluster.net/v1/task/aEquJXJgTASXvDXGk60WQA/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/2944f98faa450f2d461adacbbea99e25b78d68dd5d5b7a17d827ce49f2053057f11c4ff7c7b2f42cdf215cf8bfeac84cb501494ec8bb91c1ada37cd79eb19dcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18d524588ac4541c7817bb2db9d0bf3787cdb262674fb9d45d5f7e9484cccf561fd964abef8127de963193439669a06f702827d133cc79182d4e609b2b3a21de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9234deea0e22da224ba5384d8ef410f2389b5283c486ed4137b40f2b0f694e0ed70feb1785d86fe460862c774caf2821c5a24c8423be96de065a0366844e254"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-12 03:47:55.808299) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 03:47:55.808630) ========= 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=ed14279e38b05397cf4aa391000001a4-1478940034.188328-215649853 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-12 03:47:55.892875) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-12 03:47:55.893214) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-12 03:47:55.893557) ========= ========= Total master_lag: 0.19 =========