builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1475682668.18 results: success (0) buildid: 20161005060900 builduid: 982278a89a1f4dde99b621e46dd5960e revision: 02cc4b849623d4b28dc497ccc97cfbab9b951311 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:08.184905) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:08.185351) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:08.185639) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:08.203359) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:08.203663) ========= 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=652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-05 08:51:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 110M=0s 2016-10-05 08:51:08 (110 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100558 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:08.322309) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:08.322581) ========= 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=652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020211 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:08.390664) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:08.390981) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 02cc4b849623d4b28dc497ccc97cfbab9b951311 --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 02cc4b849623d4b28dc497ccc97cfbab9b951311 --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=652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-05 08:51:08,639 truncating revision to first 12 chars 2016-10-05 08:51:08,640 Setting DEBUG logging. 2016-10-05 08:51:08,640 attempt 1/10 2016-10-05 08:51:08,640 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/02cc4b849623?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 08:51:09,302 unpacking tar archive at: mozilla-aurora-02cc4b849623/testing/mozharness/ program finished with exit code 0 elapsedTime=0.974179 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:09.379299) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:09.379622) ========= script_repo_revision: 02cc4b849623d4b28dc497ccc97cfbab9b951311 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:09.380115) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:09.380434) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 08:51:09.390954) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-10-05 08:51:09.391310) ========= /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=652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:51:09 INFO - MultiFileLogger online at 20161005 08:51:09 in /builds/slave/test 08:51:09 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 08:51:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:51:09 INFO - {'append_to_log': False, 08:51:09 INFO - 'base_work_dir': '/builds/slave/test', 08:51:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:51:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:51:09 INFO - 'branch': 'Mozilla-Aurora', 08:51:09 INFO - 'buildbot_json_path': 'buildprops.json', 08:51:09 INFO - 'config_files': ('talos/linux_config.py',), 08:51:09 INFO - 'default_actions': ('clobber', 08:51:09 INFO - 'read-buildbot-config', 08:51:09 INFO - 'download-and-extract', 08:51:09 INFO - 'populate-webroot', 08:51:09 INFO - 'create-virtualenv', 08:51:09 INFO - 'install', 08:51:09 INFO - 'run-tests'), 08:51:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:51:09 INFO - 'download_minidump_stackwalk': True, 08:51:09 INFO - 'download_symbols': 'ondemand', 08:51:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:51:09 INFO - 'tooltool.py': '/tools/tooltool.py', 08:51:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:51:09 INFO - '/tools/misc-python/virtualenv.py')}, 08:51:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:51:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:51:09 INFO - 'installer_path': 'installer.exe', 08:51:09 INFO - 'log_level': 'info', 08:51:09 INFO - 'log_name': 'talos', 08:51:09 INFO - 'log_to_console': True, 08:51:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:51:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:51:09 INFO - 'opt_config_files': (), 08:51:09 INFO - 'pip_index': False, 08:51:09 INFO - 'sps_profile': False, 08:51:09 INFO - 'sps_profile_interval': 0, 08:51:09 INFO - 'suite': 'dromaeojs', 08:51:09 INFO - 'system_bits': '32', 08:51:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:51:09 INFO - 'title': 'talos-linux64-ix-046', 08:51:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:51:09 INFO - 'use_talos_json': True, 08:51:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:51:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:51:09 INFO - 'work_dir': 'build'} 08:51:09 INFO - [mozharness: 2016-10-05 15:51:09.730738Z] Running clobber step. 08:51:09 INFO - Running pre-action listener: _resource_record_pre_action 08:51:09 INFO - Running main action method: clobber 08:51:09 INFO - rmtree: /builds/slave/test/build 08:51:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:51:10 INFO - Running post-action listener: _resource_record_post_action 08:51:10 INFO - [mozharness: 2016-10-05 15:51:10.189136Z] Finished clobber step (success) 08:51:10 INFO - [mozharness: 2016-10-05 15:51:10.189236Z] Running read-buildbot-config step. 08:51:10 INFO - Running pre-action listener: _resource_record_pre_action 08:51:10 INFO - Running main action method: read_buildbot_config 08:51:10 INFO - Using buildbot properties: 08:51:10 INFO - { 08:51:10 INFO - "project": "", 08:51:10 INFO - "product": "firefox", 08:51:10 INFO - "script_repo_revision": "production", 08:51:10 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:51:10 INFO - "repository": "", 08:51:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:51:10 INFO - "buildid": "20161005060900", 08:51:10 INFO - "pgo_build": "True", 08:51:10 INFO - "basedir": "/builds/slave/test", 08:51:10 INFO - "buildnumber": 551, 08:51:10 INFO - "slavename": "talos-linux64-ix-046", 08:51:10 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:51:10 INFO - "platform": "ubuntu64_hw", 08:51:10 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:51:10 INFO - "branch": "mozilla-aurora", 08:51:10 INFO - "repo_path": "releases/mozilla-aurora", 08:51:10 INFO - "revision": "02cc4b849623d4b28dc497ccc97cfbab9b951311", 08:51:10 INFO - "stage_platform": "linux64-pgo", 08:51:10 INFO - "builduid": "982278a89a1f4dde99b621e46dd5960e", 08:51:10 INFO - "slavebuilddir": "test" 08:51:10 INFO - } 08:51:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 08:51:10 INFO - Running post-action listener: _resource_record_post_action 08:51:10 INFO - [mozharness: 2016-10-05 15:51:10.191687Z] Finished read-buildbot-config step (success) 08:51:10 INFO - [mozharness: 2016-10-05 15:51:10.191777Z] Running download-and-extract step. 08:51:10 INFO - Running pre-action listener: _resource_record_pre_action 08:51:10 INFO - Running main action method: download_and_extract 08:51:10 INFO - mkdir: /builds/slave/test/build/tests 08:51:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:10 INFO - trying https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 08:51:10 INFO - Downloading https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/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 08:51:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/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 08:51:10 INFO - Downloaded 1361 bytes. 08:51:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 08:51:10 INFO - Using the following test package requirements: 08:51:10 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 08:51:10 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:51:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:51:10 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:51:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:51:10 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:51:10 INFO - u'jsshell-linux-x86_64.zip'], 08:51:10 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:51:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:51:10 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 08:51:10 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:51:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:51:10 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:51:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:51:10 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:51:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:51:10 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 08:51:10 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:51:10 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:51:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 08:51:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:51:10 INFO - Fetch https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 08:51:11 INFO - Expected file size: 21905888 08:51:15 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 08:51:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 08:51:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:51:15 INFO - Fetch https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 08:51:16 INFO - Expected file size: 21905888 08:51:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 08:51:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:51:20 INFO - Fetch https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 08:51:22 INFO - Expected file size: 14414059 08:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:22 INFO - trying https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 08:51:22 INFO - Downloading https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/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 08:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/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 08:51:25 INFO - Downloaded 63947632 bytes. 08:51:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 08:51:25 INFO - mkdir: /builds/slave/test/properties 08:51:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:51:25 INFO - Writing to file /builds/slave/test/properties/build_url 08:51:25 INFO - Contents: 08:51:25 INFO - build_url:https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 08:51:25 INFO - Running post-action listener: _resource_record_post_action 08:51:25 INFO - Running post-action listener: set_extra_try_arguments 08:51:25 INFO - [mozharness: 2016-10-05 15:51:25.814614Z] Finished download-and-extract step (success) 08:51:25 INFO - [mozharness: 2016-10-05 15:51:25.814843Z] Running populate-webroot step. 08:51:25 INFO - Running pre-action listener: _resource_record_pre_action 08:51:25 INFO - Running main action method: populate_webroot 08:51:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:51:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:51:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:51:25 INFO - '--tppagecycles', 08:51:25 INFO - '7'], 08:51:25 INFO - 'tests': ['tsvgm']}}, 08:51:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:51:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:51:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:51:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:51:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:25 INFO - 'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:25 INFO - 'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['damp', 'tps']}, 08:51:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:25 INFO - 'tests': ['damp', 'tps']}, 08:51:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['dromaeo_dom']}, 08:51:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:51:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['basic_compositor_video']}, 08:51:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:51:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['a11yr', 08:51:25 INFO - 'ts_paint', 08:51:25 INFO - 'tpaint', 08:51:25 INFO - 'sessionrestore', 08:51:25 INFO - 'sessionrestore_no_auto_restore', 08:51:25 INFO - 'tabpaint']}, 08:51:25 INFO - 'other-e10s': {'tests': ['a11yr', 08:51:25 INFO - 'ts_paint', 08:51:25 INFO - 'tpaint', 08:51:25 INFO - 'sessionrestore', 08:51:25 INFO - 'sessionrestore_no_auto_restore', 08:51:25 INFO - 'tabpaint']}, 08:51:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:51:25 INFO - 'ts_paint', 08:51:25 INFO - 'tpaint', 08:51:25 INFO - 'sessionrestore', 08:51:25 INFO - 'sessionrestore_no_auto_restore', 08:51:25 INFO - 'tabpaint']}, 08:51:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:51:25 INFO - 'ts_paint', 08:51:25 INFO - 'tpaint', 08:51:25 INFO - 'sessionrestore', 08:51:25 INFO - 'sessionrestore_no_auto_restore', 08:51:25 INFO - 'tabpaint']}, 08:51:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['a11yr', 08:51:25 INFO - 'ts_paint', 08:51:25 INFO - 'tpaint', 08:51:25 INFO - 'sessionrestore', 08:51:25 INFO - 'sessionrestore_no_auto_restore', 08:51:25 INFO - 'tabpaint']}, 08:51:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['a11yr', 08:51:25 INFO - 'ts_paint', 08:51:25 INFO - 'tpaint', 08:51:25 INFO - 'sessionrestore', 08:51:25 INFO - 'sessionrestore_no_auto_restore', 08:51:25 INFO - 'tabpaint']}, 08:51:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['tsvgx', 08:51:25 INFO - 'tsvgr_opacity', 08:51:25 INFO - 'tart', 08:51:25 INFO - 'tscrollx', 08:51:25 INFO - 'cart']}, 08:51:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:51:25 INFO - 'tsvgr_opacity', 08:51:25 INFO - 'tart', 08:51:25 INFO - 'tscrollx', 08:51:25 INFO - 'cart']}, 08:51:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:25 INFO - 'talos_options': ['--disable-e10s'], 08:51:25 INFO - 'tests': ['tp5o']}, 08:51:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:25 INFO - 'tests': ['tp5o']}, 08:51:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:25 INFO - 'talos_options': ['--disable-e10s', 08:51:25 INFO - '--xperf_path', 08:51:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:25 INFO - 'tests': ['tp5n']}, 08:51:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:51:25 INFO - 'talos_options': ['--xperf_path', 08:51:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:25 INFO - 'tests': ['tp5n']}}, 08:51:25 INFO - 'talos.zip': {'path': '', 08:51:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:51:25 INFO - Running post-action listener: _resource_record_post_action 08:51:25 INFO - [mozharness: 2016-10-05 15:51:25.832386Z] Finished populate-webroot step (success) 08:51:25 INFO - [mozharness: 2016-10-05 15:51:25.832475Z] Running create-virtualenv step. 08:51:25 INFO - Running pre-action listener: _resource_record_pre_action 08:51:25 INFO - Running main action method: create_virtualenv 08:51:25 INFO - Creating virtualenv /builds/slave/test/build/venv 08:51:25 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 08:51:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:51:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:51:25 INFO - Using real prefix '/usr' 08:51:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:51:26 INFO - Installing distribute.............................................................................................................................................................................................done. 08:51:28 INFO - Installing pip.................done. 08:51:28 INFO - Return code: 0 08:51:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:51:28 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')]} 08:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:28 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')]} 08:51:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:28 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 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:28 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 08:51:28 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 08:51:28 INFO - Using env: {'DISPLAY': ':0', 08:51:28 INFO - 'HOME': '/home/cltbld', 08:51:28 INFO - 'LANG': 'en_US.UTF-8', 08:51:28 INFO - 'LANGUAGE': 'en_US:en', 08:51:28 INFO - 'LOGNAME': 'cltbld', 08:51:28 INFO - 'MAIL': '/var/mail/cltbld', 08:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:28 INFO - 'MOZ_NO_REMOTE': '1', 08:51:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:28 INFO - 'NO_EM_RESTART': '1', 08:51:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:28 INFO - 'PWD': '/builds/slave/test', 08:51:28 INFO - 'SHELL': '/bin/bash', 08:51:28 INFO - 'SHLVL': '1', 08:51:28 INFO - 'TERM': 'linux', 08:51:28 INFO - 'TMOUT': '86400', 08:51:28 INFO - 'USER': 'cltbld', 08:51:28 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:28 INFO - '_': '/tools/buildbot/bin/python'} 08:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:28 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 08:51:28 INFO - Cleaning up... 08:51:28 INFO - Return code: 0 08:51:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:51:28 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')]} 08:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:28 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')]} 08:51:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:28 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 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:28 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 08:51:28 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 08:51:28 INFO - Using env: {'DISPLAY': ':0', 08:51:28 INFO - 'HOME': '/home/cltbld', 08:51:28 INFO - 'LANG': 'en_US.UTF-8', 08:51:28 INFO - 'LANGUAGE': 'en_US:en', 08:51:28 INFO - 'LOGNAME': 'cltbld', 08:51:28 INFO - 'MAIL': '/var/mail/cltbld', 08:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:28 INFO - 'MOZ_NO_REMOTE': '1', 08:51:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:28 INFO - 'NO_EM_RESTART': '1', 08:51:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:28 INFO - 'PWD': '/builds/slave/test', 08:51:28 INFO - 'SHELL': '/bin/bash', 08:51:28 INFO - 'SHLVL': '1', 08:51:28 INFO - 'TERM': 'linux', 08:51:28 INFO - 'TMOUT': '86400', 08:51:28 INFO - 'USER': 'cltbld', 08:51:28 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:28 INFO - '_': '/tools/buildbot/bin/python'} 08:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:28 INFO - Downloading/unpacking psutil>=3.1.1 08:51:28 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 08:51:28 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 08:51:28 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 08:51:28 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 08:51:28 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 08:51:28 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 08:51:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:51:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:51:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:51:30 INFO - Installing collected packages: psutil 08:51:30 INFO - Running setup.py install for psutil 08:51:30 INFO - building 'psutil._psutil_linux' extension 08:51:30 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 08:51:31 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 08:51:31 INFO - building 'psutil._psutil_posix' extension 08:51:31 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 08:51:31 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 08:51:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:51:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:51:31 INFO - Successfully installed psutil 08:51:31 INFO - Cleaning up... 08:51:31 INFO - Return code: 0 08:51:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:51:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:51:31 INFO - Using env: {'DISPLAY': ':0', 08:51:31 INFO - 'HOME': '/home/cltbld', 08:51:31 INFO - 'LANG': 'en_US.UTF-8', 08:51:31 INFO - 'LANGUAGE': 'en_US:en', 08:51:31 INFO - 'LOGNAME': 'cltbld', 08:51:31 INFO - 'MAIL': '/var/mail/cltbld', 08:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:31 INFO - 'MOZ_NO_REMOTE': '1', 08:51:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:31 INFO - 'NO_EM_RESTART': '1', 08:51:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:31 INFO - 'PWD': '/builds/slave/test', 08:51:31 INFO - 'SHELL': '/bin/bash', 08:51:31 INFO - 'SHLVL': '1', 08:51:31 INFO - 'TERM': 'linux', 08:51:31 INFO - 'TMOUT': '86400', 08:51:31 INFO - 'USER': 'cltbld', 08:51:31 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:31 INFO - '_': '/tools/buildbot/bin/python'} 08:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:51:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:51:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:51:33 INFO - Installing collected packages: mozsystemmonitor 08:51:33 INFO - Running setup.py install for mozsystemmonitor 08:51:33 INFO - Successfully installed mozsystemmonitor 08:51:33 INFO - Cleaning up... 08:51:33 INFO - Return code: 0 08:51:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:51:33 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')]} 08:51:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:33 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')]} 08:51:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:33 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 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:33 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 08:51:33 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 08:51:33 INFO - Using env: {'DISPLAY': ':0', 08:51:33 INFO - 'HOME': '/home/cltbld', 08:51:33 INFO - 'LANG': 'en_US.UTF-8', 08:51:33 INFO - 'LANGUAGE': 'en_US:en', 08:51:33 INFO - 'LOGNAME': 'cltbld', 08:51:33 INFO - 'MAIL': '/var/mail/cltbld', 08:51:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:33 INFO - 'MOZ_NO_REMOTE': '1', 08:51:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:33 INFO - 'NO_EM_RESTART': '1', 08:51:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:33 INFO - 'PWD': '/builds/slave/test', 08:51:33 INFO - 'SHELL': '/bin/bash', 08:51:33 INFO - 'SHLVL': '1', 08:51:33 INFO - 'TERM': 'linux', 08:51:33 INFO - 'TMOUT': '86400', 08:51:33 INFO - 'USER': 'cltbld', 08:51:33 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:33 INFO - '_': '/tools/buildbot/bin/python'} 08:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:33 INFO - Downloading/unpacking jsonschema==2.5.1 08:51:33 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 08:51:33 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 08:51:33 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 08:51:33 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 08:51:33 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 08:51:33 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 08:51:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:51:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:51:35 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 08:51:35 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 08:51:35 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 08:51:35 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 08:51:35 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 08:51:35 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 08:51:36 INFO - Downloading functools32-3.2.3-2.tar.gz 08:51:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:51:36 INFO - warning: no files found matching '*.txt' 08:51:36 INFO - no previously-included directories found matching 'build' 08:51:36 INFO - no previously-included directories found matching 'dist' 08:51:36 INFO - no previously-included directories found matching '.git*' 08:51:36 INFO - Installing collected packages: jsonschema, functools32 08:51:36 INFO - Running setup.py install for functools32 08:51:36 INFO - warning: no files found matching '*.txt' 08:51:36 INFO - no previously-included directories found matching 'build' 08:51:36 INFO - no previously-included directories found matching 'dist' 08:51:36 INFO - no previously-included directories found matching '.git*' 08:51:36 INFO - Successfully installed jsonschema functools32 08:51:36 INFO - Cleaning up... 08:51:36 INFO - Return code: 0 08:51:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:36 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 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:36 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 08:51:36 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 08:51:36 INFO - Using env: {'DISPLAY': ':0', 08:51:36 INFO - 'HOME': '/home/cltbld', 08:51:36 INFO - 'LANG': 'en_US.UTF-8', 08:51:36 INFO - 'LANGUAGE': 'en_US:en', 08:51:36 INFO - 'LOGNAME': 'cltbld', 08:51:36 INFO - 'MAIL': '/var/mail/cltbld', 08:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:36 INFO - 'MOZ_NO_REMOTE': '1', 08:51:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:36 INFO - 'NO_EM_RESTART': '1', 08:51:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:36 INFO - 'PWD': '/builds/slave/test', 08:51:36 INFO - 'SHELL': '/bin/bash', 08:51:36 INFO - 'SHLVL': '1', 08:51:36 INFO - 'TERM': 'linux', 08:51:36 INFO - 'TMOUT': '86400', 08:51:36 INFO - 'USER': 'cltbld', 08:51:36 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:36 INFO - '_': '/tools/buildbot/bin/python'} 08:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:51:36 INFO - Cleaning up... 08:51:36 INFO - Return code: 0 08:51:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:36 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 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:36 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 08:51:36 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 08:51:36 INFO - Using env: {'DISPLAY': ':0', 08:51:36 INFO - 'HOME': '/home/cltbld', 08:51:36 INFO - 'LANG': 'en_US.UTF-8', 08:51:36 INFO - 'LANGUAGE': 'en_US:en', 08:51:36 INFO - 'LOGNAME': 'cltbld', 08:51:36 INFO - 'MAIL': '/var/mail/cltbld', 08:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:36 INFO - 'MOZ_NO_REMOTE': '1', 08:51:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:36 INFO - 'NO_EM_RESTART': '1', 08:51:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:36 INFO - 'PWD': '/builds/slave/test', 08:51:36 INFO - 'SHELL': '/bin/bash', 08:51:36 INFO - 'SHLVL': '1', 08:51:36 INFO - 'TERM': 'linux', 08:51:36 INFO - 'TMOUT': '86400', 08:51:36 INFO - 'USER': 'cltbld', 08:51:36 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:36 INFO - '_': '/tools/buildbot/bin/python'} 08:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:36 INFO - Downloading/unpacking blobuploader==1.2.4 08:51:36 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 08:51:36 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 08:51:36 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 08:51:36 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 08:51:36 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 08:51:36 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 08:51:38 INFO - Downloading blobuploader-1.2.4.tar.gz 08:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:51:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:51:38 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 08:51:38 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 08:51:38 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 08:51:38 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 08:51:38 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 08:51:38 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 08:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:51:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:39 INFO - Downloading docopt-0.6.1.tar.gz 08:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:51:39 INFO - Installing collected packages: blobuploader, requests, docopt 08:51:39 INFO - Running setup.py install for blobuploader 08:51:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:51:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:51:39 INFO - Running setup.py install for requests 08:51:39 INFO - Running setup.py install for docopt 08:51:39 INFO - Successfully installed blobuploader requests docopt 08:51:39 INFO - Cleaning up... 08:51:39 INFO - Return code: 0 08:51:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:39 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 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:39 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 08:51:39 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 08:51:39 INFO - Using env: {'DISPLAY': ':0', 08:51:39 INFO - 'HOME': '/home/cltbld', 08:51:39 INFO - 'LANG': 'en_US.UTF-8', 08:51:39 INFO - 'LANGUAGE': 'en_US:en', 08:51:39 INFO - 'LOGNAME': 'cltbld', 08:51:39 INFO - 'MAIL': '/var/mail/cltbld', 08:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:39 INFO - 'MOZ_NO_REMOTE': '1', 08:51:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:39 INFO - 'NO_EM_RESTART': '1', 08:51:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:39 INFO - 'PWD': '/builds/slave/test', 08:51:39 INFO - 'SHELL': '/bin/bash', 08:51:39 INFO - 'SHLVL': '1', 08:51:39 INFO - 'TERM': 'linux', 08:51:39 INFO - 'TMOUT': '86400', 08:51:39 INFO - 'USER': 'cltbld', 08:51:39 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:39 INFO - '_': '/tools/buildbot/bin/python'} 08:51:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:51:40 INFO - Running setup.py (path:/tmp/pip-7VX3L0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:51:40 INFO - Running setup.py (path:/tmp/pip-JzJJKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:51:40 INFO - Running setup.py (path:/tmp/pip-7VB9dH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:51:40 INFO - Running setup.py (path:/tmp/pip-IUthGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:51:40 INFO - Running setup.py (path:/tmp/pip-2feJsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:51:40 INFO - Running setup.py (path:/tmp/pip-E6RXn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:51:40 INFO - Running setup.py (path:/tmp/pip-ZOlJpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:51:40 INFO - Running setup.py (path:/tmp/pip-GHbN2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:51:40 INFO - Running setup.py (path:/tmp/pip-lGaSeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:51:40 INFO - Running setup.py (path:/tmp/pip-vjbj0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:51:40 INFO - Running setup.py (path:/tmp/pip-ztYkwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:51:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:51:40 INFO - Running setup.py (path:/tmp/pip-Oh1kzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:51:41 INFO - Running setup.py (path:/tmp/pip-JYD4io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:51:41 INFO - Running setup.py (path:/tmp/pip-iiY7px-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:41 INFO - Running setup.py (path:/tmp/pip-_K6_fN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:51:41 INFO - Running setup.py (path:/tmp/pip-H3KNHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:51:41 INFO - Running setup.py (path:/tmp/pip-1CI5VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:51:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:51:41 INFO - Running setup.py install for manifestparser 08:51:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:51:41 INFO - Running setup.py install for mozcrash 08:51:41 INFO - Running setup.py install for mozdebug 08:51:41 INFO - Running setup.py install for mozdevice 08:51:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:51:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:51:41 INFO - Running setup.py install for mozfile 08:51:41 INFO - Running setup.py install for mozhttpd 08:51:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Running setup.py install for mozinfo 08:51:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Running setup.py install for mozInstall 08:51:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Running setup.py install for mozleak 08:51:42 INFO - Running setup.py install for mozlog 08:51:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Running setup.py install for moznetwork 08:51:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Running setup.py install for mozprocess 08:51:42 INFO - Running setup.py install for mozprofile 08:51:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Running setup.py install for mozrunner 08:51:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:51:42 INFO - Running setup.py install for mozscreenshot 08:51:42 INFO - Running setup.py install for moztest 08:51:43 INFO - Running setup.py install for mozversion 08:51:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:51:43 INFO - Cleaning up... 08:51:43 INFO - Return code: 0 08:51:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51: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')]} 08:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51: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')]} 08:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:43 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 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:43 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 08:51:43 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 08:51:43 INFO - Using env: {'DISPLAY': ':0', 08:51:43 INFO - 'HOME': '/home/cltbld', 08:51:43 INFO - 'LANG': 'en_US.UTF-8', 08:51:43 INFO - 'LANGUAGE': 'en_US:en', 08:51:43 INFO - 'LOGNAME': 'cltbld', 08:51:43 INFO - 'MAIL': '/var/mail/cltbld', 08:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:43 INFO - 'MOZ_NO_REMOTE': '1', 08:51:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:43 INFO - 'NO_EM_RESTART': '1', 08:51:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:43 INFO - 'PWD': '/builds/slave/test', 08:51:43 INFO - 'SHELL': '/bin/bash', 08:51:43 INFO - 'SHLVL': '1', 08:51:43 INFO - 'TERM': 'linux', 08:51:43 INFO - 'TMOUT': '86400', 08:51:43 INFO - 'USER': 'cltbld', 08:51:43 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:43 INFO - '_': '/tools/buildbot/bin/python'} 08:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:51:43 INFO - Running setup.py (path:/tmp/pip-j4gonX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:51:43 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)) 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:51:43 INFO - Running setup.py (path:/tmp/pip-68KeE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:51:43 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)) 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:51:43 INFO - Running setup.py (path:/tmp/pip-8YQnov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:51:43 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)) 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:51:43 INFO - Running setup.py (path:/tmp/pip-l99wNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:51:43 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)) 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:51:43 INFO - Running setup.py (path:/tmp/pip-UA7hrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:51:43 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)) 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:51:43 INFO - Running setup.py (path:/tmp/pip-GGZbkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:51:43 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)) 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:51:43 INFO - Running setup.py (path:/tmp/pip-KU39fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:51:43 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)) 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:51:43 INFO - Running setup.py (path:/tmp/pip-Ws2MiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:51:44 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)) 08:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:51:44 INFO - Running setup.py (path:/tmp/pip-YrEtD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:51:44 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)) 08:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:51:44 INFO - Running setup.py (path:/tmp/pip-lIQ97e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:51:44 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)) 08:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:51:44 INFO - Running setup.py (path:/tmp/pip-QUalKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:51:44 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)) 08:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:51:44 INFO - Running setup.py (path:/tmp/pip-IJcRP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:51:44 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)) 08:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:51:44 INFO - Running setup.py (path:/tmp/pip-VcD6gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:51:44 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)) 08:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:51:44 INFO - Running setup.py (path:/tmp/pip-a9Nkrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:51:44 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)) 08:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:44 INFO - Running setup.py (path:/tmp/pip-tKuwaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:44 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)) 08:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:51:44 INFO - Running setup.py (path:/tmp/pip-oFVJSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:51:44 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)) 08:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:51:44 INFO - Running setup.py (path:/tmp/pip-4t6M2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:51:44 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)) 08:51:44 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)) 08:51:44 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)) 08:51:44 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)) 08:51:44 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)) 08:51:44 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)) 08:51:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:51:44 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 08:51:44 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 08:51:44 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 08:51:44 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 08:51:44 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 08:51:44 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 08:51:46 INFO - Downloading blessings-1.6.tar.gz 08:51:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:51:46 INFO - Installing collected packages: blessings 08:51:46 INFO - Running setup.py install for blessings 08:51:46 INFO - Successfully installed blessings 08:51:46 INFO - Cleaning up... 08:51:46 INFO - Return code: 0 08:51:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:51:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:46 INFO - Reading from file tmpfile_stdout 08:51:46 INFO - Current package versions: 08:51:46 INFO - argparse == 1.2.1 08:51:46 INFO - blessings == 1.6 08:51:46 INFO - blobuploader == 1.2.4 08:51:46 INFO - docopt == 0.6.1 08:51:46 INFO - functools32 == 3.2.3-2 08:51:46 INFO - jsonschema == 2.5.1 08:51:46 INFO - manifestparser == 1.1 08:51:46 INFO - mozInstall == 1.12 08:51:46 INFO - mozcrash == 0.17 08:51:46 INFO - mozdebug == 0.1 08:51:46 INFO - mozdevice == 0.48 08:51:46 INFO - mozfile == 1.2 08:51:46 INFO - mozhttpd == 0.7 08:51:46 INFO - mozinfo == 0.9 08:51:46 INFO - mozleak == 0.1 08:51:46 INFO - mozlog == 3.4 08:51:46 INFO - moznetwork == 0.27 08:51:46 INFO - mozprocess == 0.23 08:51:46 INFO - mozprofile == 0.28 08:51:46 INFO - mozrunner == 6.12 08:51:46 INFO - mozscreenshot == 0.1 08:51:46 INFO - mozsystemmonitor == 0.3 08:51:46 INFO - moztest == 0.7 08:51:46 INFO - mozversion == 1.4 08:51:46 INFO - psutil == 3.1.1 08:51:46 INFO - requests == 1.2.3 08:51:46 INFO - wsgiref == 0.1.2 08:51:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:47 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 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:47 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 08:51:47 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 08:51:47 INFO - Using env: {'DISPLAY': ':0', 08:51:47 INFO - 'HOME': '/home/cltbld', 08:51:47 INFO - 'LANG': 'en_US.UTF-8', 08:51:47 INFO - 'LANGUAGE': 'en_US:en', 08:51:47 INFO - 'LOGNAME': 'cltbld', 08:51:47 INFO - 'MAIL': '/var/mail/cltbld', 08:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:47 INFO - 'MOZ_NO_REMOTE': '1', 08:51:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:47 INFO - 'NO_EM_RESTART': '1', 08:51:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:47 INFO - 'PWD': '/builds/slave/test', 08:51:47 INFO - 'SHELL': '/bin/bash', 08:51:47 INFO - 'SHLVL': '1', 08:51:47 INFO - 'TERM': 'linux', 08:51:47 INFO - 'TMOUT': '86400', 08:51:47 INFO - 'USER': 'cltbld', 08:51:47 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:47 INFO - '_': '/tools/buildbot/bin/python'} 08:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:47 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)) 08:51:47 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)) 08:51:47 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)) 08:51:47 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)) 08:51:47 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)) 08:51:47 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)) 08:51:47 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)) 08:51:47 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)) 08:51:47 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)) 08:51:47 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)) 08:51:47 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)) 08:51:47 INFO - Cleaning up... 08:51:47 INFO - Return code: 0 08:51:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:47 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 0x7fa593a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe37ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', '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 08:51:47 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 08:51:47 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 08:51:47 INFO - Using env: {'DISPLAY': ':0', 08:51:47 INFO - 'HOME': '/home/cltbld', 08:51:47 INFO - 'LANG': 'en_US.UTF-8', 08:51:47 INFO - 'LANGUAGE': 'en_US:en', 08:51:47 INFO - 'LOGNAME': 'cltbld', 08:51:47 INFO - 'MAIL': '/var/mail/cltbld', 08:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:47 INFO - 'MOZ_NO_REMOTE': '1', 08:51:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:47 INFO - 'NO_EM_RESTART': '1', 08:51:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:47 INFO - 'PWD': '/builds/slave/test', 08:51:47 INFO - 'SHELL': '/bin/bash', 08:51:47 INFO - 'SHLVL': '1', 08:51:47 INFO - 'TERM': 'linux', 08:51:47 INFO - 'TMOUT': '86400', 08:51:47 INFO - 'USER': 'cltbld', 08:51:47 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:47 INFO - '_': '/tools/buildbot/bin/python'} 08:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:51:47 INFO - Cleaning up... 08:51:47 INFO - Return code: 0 08:51:47 INFO - Running post-action listener: _resource_record_post_action 08:51:47 INFO - Running post-action listener: _start_resource_monitoring 08:51:47 INFO - Starting resource monitoring. 08:51:47 INFO - [mozharness: 2016-10-05 15:51:47.474589Z] Finished create-virtualenv step (success) 08:51:47 INFO - [mozharness: 2016-10-05 15:51:47.475058Z] Running install step. 08:51:47 INFO - Running pre-action listener: _resource_record_pre_action 08:51:47 INFO - Running main action method: install 08:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:47 INFO - Reading from file tmpfile_stdout 08:51:47 INFO - Detecting whether we're running mozinstall >=1.0... 08:51:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:51:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:51:47 INFO - Reading from file tmpfile_stdout 08:51:47 INFO - Output received: 08:51:47 INFO - Usage: mozinstall [options] installer 08:51:47 INFO - Options: 08:51:47 INFO - -h, --help show this help message and exit 08:51:47 INFO - -d DEST, --destination=DEST 08:51:47 INFO - Directory to install application into. [default: 08:51:47 INFO - "/builds/slave/test"] 08:51:47 INFO - --app=APP Application being installed. [default: firefox] 08:51:47 INFO - mkdir: /builds/slave/test/build/application 08:51:47 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'] 08:51:47 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 08:52:03 INFO - Reading from file tmpfile_stdout 08:52:03 INFO - Output received: 08:52:03 INFO - /builds/slave/test/build/application/firefox/firefox 08:52:03 INFO - Running post-action listener: _resource_record_post_action 08:52:03 INFO - [mozharness: 2016-10-05 15:52:03.736349Z] Finished install step (success) 08:52:03 INFO - [mozharness: 2016-10-05 15:52:03.736595Z] Running run-tests step. 08:52:03 INFO - Running pre-action listener: _resource_record_pre_action 08:52:03 INFO - Running main action method: run_tests 08:52:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:52:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:52:03 INFO - Python 2.7.3 08:52:03 INFO - Return code: 0 08:52:03 INFO - grabbing minidump binary from tooltool 08:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:03 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 0xe34f60>, '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 0xe35d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe361d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:52:03 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 08:52:03 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 08:52:03 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 08:52:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:52:03 INFO - Return code: 0 08:52:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:52:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:52:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:52:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:52:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:52:03 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/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 08:52:03 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/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --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 08:52:03 INFO - Using env: {'DISPLAY': ':0', 08:52:03 INFO - 'HOME': '/home/cltbld', 08:52:03 INFO - 'LANG': 'en_US.UTF-8', 08:52:03 INFO - 'LANGUAGE': 'en_US:en', 08:52:03 INFO - 'LOGNAME': 'cltbld', 08:52:03 INFO - 'MAIL': '/var/mail/cltbld', 08:52:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:52:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:03 INFO - 'MOZ_NO_REMOTE': '1', 08:52:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:52:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:03 INFO - 'NO_EM_RESTART': '1', 08:52:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:03 INFO - 'PWD': '/builds/slave/test', 08:52:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:52:03 INFO - 'SHELL': '/bin/bash', 08:52:03 INFO - 'SHLVL': '1', 08:52:03 INFO - 'TERM': 'linux', 08:52:03 INFO - 'TMOUT': '86400', 08:52:03 INFO - 'USER': 'cltbld', 08:52:03 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577', 08:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:03 INFO - '_': '/tools/buildbot/bin/python'} 08:52:03 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/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 08:52:04 INFO - mozversion application_buildid: 20161005060900 08:52:04 INFO - mozversion application_changeset: 02cc4b849623d4b28dc497ccc97cfbab9b951311 08:52:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:52:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:52:04 INFO - mozversion application_name: Firefox 08:52:04 INFO - mozversion application_remotingname: firefox-dev 08:52:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:52:04 INFO - mozversion application_vendor: Mozilla 08:52:04 INFO - mozversion application_version: 51.0a2 08:52:04 INFO - mozversion platform_buildid: 20161005060900 08:52:04 INFO - mozversion platform_changeset: 02cc4b849623d4b28dc497ccc97cfbab9b951311 08:52:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:52:04 INFO - mozversion platform_version: 51.0a2 08:52:04 INFO - using testdate: 1475682724 08:52:04 INFO - actual date: 1475682724 08:52:04 INFO - starting webserver on 'localhost:53990' 08:52:04 INFO - SUITE-START | Running 2 tests 08:52:04 INFO - TEST-START | dromaeo_css 08:52:04 INFO - operating with platform_type : linux_ 08:52:04 INFO - Initialising browser for dromaeo_css test... 08:52:04 INFO - TEST-INFO | started process 12887 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqnNtHG/profile http://localhost:53990/getInfo.html) 08:52:08 INFO - PROCESS | 12887 | __metrics Screen width/height:1600/1200 08:52:08 INFO - PROCESS | 12887 | colorDepth:24 08:52:08 INFO - PROCESS | 12887 | Browser inner width/height: 1024/697 08:52:08 INFO - PROCESS | 12887 | __metrics 08:52:08 INFO - PROCESS | 12887 | 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" 08:52:08 INFO - PROCESS | 12887 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:52:09 INFO - PROCESS | 12887 | console.error: PushService: 08:52:09 INFO - PROCESS | 12887 | stateChangeProcessEnqueue: Error transitioning state 08:52:09 INFO - PROCESS | 12887 | UnknownError 08:52:09 INFO - TEST-INFO | 12887: exit 0 08:52:09 INFO - Browser initialized. 08:52:09 INFO - Running cycle 1/1 for dromaeo_css test... 08:52:09 INFO - TEST-INFO | started process 12979 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqnNtHG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:52:21 INFO - PROCESS | 12979 | RSS: Main: 144175104 08:52:21 INFO - PROCESS | 12979 | 08:53:13 INFO - PROCESS | 12979 | 1475682793266 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] 08:55:21 INFO - PROCESS | 12979 | Cycle 1(1): loaded http://localhost:53990/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53990/tests/dromaeo/cssquery-ext.html) 08:55:22 INFO - PROCESS | 12979 | RSS: Main: 249131008 08:55:22 INFO - PROCESS | 12979 | 08:58:24 INFO - PROCESS | 12979 | Cycle 1(1): loaded http://localhost:53990/tests/dromaeo/cssquery-ext.html (next: http://localhost:53990/tests/dromaeo/cssquery-jquery.html) 08:58:25 INFO - PROCESS | 12979 | RSS: Main: 234696704 08:58:25 INFO - PROCESS | 12979 | 09:01:27 INFO - PROCESS | 12979 | Cycle 1(1): loaded http://localhost:53990/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53990/tests/dromaeo/cssquery-mootools.html) 09:01:28 INFO - PROCESS | 12979 | RSS: Main: 223391744 09:01:28 INFO - PROCESS | 12979 | 09:04:30 INFO - PROCESS | 12979 | Cycle 1(1): loaded http://localhost:53990/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53990/tests/dromaeo/cssquery-prototype.html) 09:04:30 INFO - PROCESS | 12979 | RSS: Main: 211992576 09:04:30 INFO - PROCESS | 12979 | 09:07:33 INFO - PROCESS | 12979 | Cycle 1(1): loaded http://localhost:53990/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53990/tests/dromaeo/cssquery-yui.html) 09:07:33 INFO - PROCESS | 12979 | RSS: Main: 175611904 09:07:33 INFO - PROCESS | 12979 | 09:10:36 INFO - PROCESS | 12979 | Cycle 1(1): loaded http://localhost:53990/tests/dromaeo/cssquery-yui.html (next: http://localhost:53990/tests/dromaeo/cssquery-dojo.html) 09:10:36 INFO - PROCESS | 12979 | RSS: Main: 265625600 09:10:36 INFO - PROCESS | 12979 | 09:10:36 INFO - PROCESS | 12979 | __start_tp_report 09:10:36 INFO - PROCESS | 12979 | _x_x_mozilla_page_load 09:10:36 INFO - PROCESS | 12979 | _x_x_mozilla_page_load_details 09:10:36 INFO - PROCESS | 12979 | |i|pagename|runs| 09:10:36 INFO - PROCESS | 12979 | |0;dojo.html;3066.4633689104407;3091.0881290019443;3122.4379611669906;3142.340108577199;3161.4625513662677;5341.278927344807;5406.91889621656;5425.945740542594;5432.864178395542;5449.20986456964;5402.810901618444;5423.918641220375;5425.159100339453;5428.511433970942;5435.42927992561;4892.461829198785;4907.509249075082;4953.777080031389;4966.404031516223;4974.7015179089185;4851.320815085891;4864.683795553296;4884.365032545764;4944.87637809054;5003.949960500385;4872.707637541741;4936.456989731127;4942.3327850740225;4947.084789313988;4965.875853103683;4948.059868624986;4949.232868905326;4965.602751779867;4971.204607262821;4991.950080499185;4765.880852978668;4788.209945359012;4805.783739731721;4850.078485087822;4870.025994801019;5237.816676416345;5247.422783493813;5258.55302299305;5338.359396872362;5424.0236757383655;5701.14482827575;5710.486056254916;5710.857228569277;5720.799772008001;5722.62802917809;5579.525740312079;5684.204211410406;5751.683657398841;5754.108113242454;5762.596618236725;5371.785128594852;5389.164679474688;5433.809816656477;5442.102053161184;5452.318460192476;5948.464638182584;5958.493528050164;5961.8211453655595;5964.343922168558;5965.373635768268;5490.231367608538;5534.806281780118;5719.628224165416;5725.541956643458;5727.971360143172;5676.091825307931;5713.22871412354;5745.195672605839;5778.88442231153;5810.6513609183585;5725.969325521362;5732.799352022659;5734.971325143431;5736.856578585569;5765.4522820332;5618.438156184349;5730.34101078373;5785.247917770663;5802.4197580241635;5816.505597024216;4380.518143004267;4444.511103778582;4450.799714012877;4457.487388950312;4487.371767952489;3971.920561588752;3972.066564357374;3974.980125099356;3975.4235635832642;3998.8000599969887;5433.157860531624;5457.863553411196;5505.119180931032;5507.0637991541535;5513.751881165357;5645.971770141123;5681.431856814368;5783.624064435799;5807.303123625192;5809.186713860063;5493.121100623881;5522.420145884705;5526.972365138148;5528.5024347808085;5552.139418390076;4966.15575352191;4998.375203099613;4998.700077995332;5000.799968001312;5028.622853285945;5813.476787089182;5820.272465941757;5834.941650583524;5999.490043346277;6099.207103076572;5855.443732845373;5879.588428810113;5883.61756485827;5890.175375447527;5923.851903702442;6058.90911636317;6094.695265236632;6097;6101.359357267423;6111.8777624446375;5566.081596536526;5598.944010559842;5619.578531610227;5775.249217601853;5784.161296611884;5738.770449182153;5798.739056742373;5815.2149459822385;5848.883022339444;5853.268341457318;5647.265855438931;5674.233978413026;5698.373178950395;5706.657600543981;5710.3433105193435;5360.3299587551555;5375.220593013913;5674.971625141848;5682.3465301489205;5697.173909783141;5558.527525160407;5607.831765047055;5609.663420194802;5679.290088738908;5703.657780533182;5694.373618902;5729.656220626775;5737.368889422077;5741.827745167651;5752.050911599539;5465.7813687453645;5507.4767897051315;5521.641093328921;5587.0502014656795;5594.552435805044 09:10:36 INFO - PROCESS | 12979 | |1;ext.html;580.2514755964805;633.6071635585937;636.9394907483788;641.7144370755013;665.650533469928;33824;45203;45896.999999999956;45997.31004034934;46112.07775844481;44636.55363446373;45185.74071296438;45270.773646131805;45395.09209815802;45413.545864541346;8322.334213262955;9131.77170570736;9270.953645231799;9274.39716418434;9280.257579393632;8382.664693412256;8664.826703465927;8669.176428239307;10909.509072091774;11166.665000050012;11286.66140015801;11329.376884271362;11346.092312615012;11358.545658173663;11363.090952723762;11053.447327633627;11115.055220306203;11127.888721112766;11136.33182009078;11136.498857551349;10936.234463587552;10996.06533444658;10997.450127493594;11003.669889903316;11014.339139651567;45468.3179752304;45792.54207457916;45851;45867.77066114682;45878;3512.877049303262;3807.257584770971;3943.0339566806315;4004.3392840181336;4253.1706317268145;33968.830155849064;34086.48870266948;34229.315413691584;34373.656263437544;34403.4839477408;4694.342792009122;4782.33047373368;4784.162771515006;4790.4251489821445;4846.39420072491;381364;381755;382048;382348;382546;62138.37861621416;62158;62549.06176407357;62578;62609;40868.59131408648;41585.58414415817;41866;43705.78147109244;67729;19727;19883;19951.700724489143;19959.900200499196;19960.401187964384;13974.720505590034;15354.53936381888;15453.072815631098;15465;15477;18187.272509099486;19924;20553.122562841214;21338.78661213399;21426.464338391666;7401.741703910348;7437.925620743831;7440;7493.4379921505015;7748.6439873022;987.8172538080478;1019.5259204469957;1043.7390652336917;1067.5996501312009;1112.866456025266;33491.66508334885;33723;34025.149371265914;34220;34249.315013700085;1127.3968426891574;1129.7062763681338;1142.2118738070872;1209.727811242481;1228.600704770953;15650.68698626021;18762.906185468986;18980.43058708241;19674.114664840115;19745;621853;623390;628985;630895;632353;64834;65146;65376;65380.01929971054;65452.34547654462;1332.2339654698587;1395.1768456610648;1400.544822932551;1435.1082568624022;1466.6773309871812;33025.83487082549;34732.65267347395;35247.47128793019;35247.647523524436;35451.64548354483;53660.46339536554;54290.18564721453;54309.18536221881;54412.45587544074;54467.182992254355;439437;443222;444227;444852;581803;44494;46540;46916.53083469121;47373.52626473829;48140.27789583229;51469.742651286506;51685.7415712919;51849;52195.47804521906;52266;43489;43987;45261.77369113265;45326.773366132955;45902;43900.780496097315;44360;44669.97984152656;45388.31917521306;45807.77096114498;44715.776421117684;45627;45709.54290457053;46055.76972115118;46064.309035365164;36727.26545469129;39174.216515670094;39647.60352396439;39924.60075399325;40093.59906400899;29404.558931615615;29477.557836632037;29771.85114074416;30160.396792064475;30193.245168870955 09:10:36 INFO - PROCESS | 12979 | |2;jquery.html;403.38282427885326;407.50895171318564;417.6679539765886;424.9617534421901;428.09244401868045;14330.641733956656;16167.676646467064;20057.498562535944;20096.09567569461;20908.790912090873;19462.318818841344;19570.510737231576;19879.105440255185;20742.170313187493;20853;5821.912671309934;5889.85275368115;6071.78748743794;6111.480524155452;6117.602355846878;4127.62851343379;6094.878102437949;6130.785422510213;6134;6141.600795948271;4329.826806927719;5982.491488223506;5994.6403215807195;6078.5441091918065;6085.665288409135;6027.487663548582;6066.423689749467;6082.0268756998885;6612.471002319851;6661.9000714989315;5988.221531200959;5995.760169593211;5998.760049598054;6006.189164462785;6010.939890601082;14507.27463626825;14563.563093107117;14836.258187090601;14925.92537037308;15020.648141667303;2382.4758553118286;2425.745296743834;2485.5774518331928;2694.312950197707;3040.3767227718263;10662.786744265071;10724.678259652223;10881.619143329945;11064.612738554111;11227.607033753779;1775.529484686559;1959.8922059286733;2012.1750082466265;2080.44868109951;2111.915523379063;67505.66247168781;68723.65638171777;73905;75331;81504.59247703724;11786.764264714659;11960.342181180118;11997.940010299893;12101.273923564615;12283.631491055283;17009.404670836462;17039.40362087321;17055;17068.658626827393;17068.914655426892;8753.693620723245;8835.293176545862;8963.910360896438;9035.819283614423;9138.497382643885;8607.493688604469;8614.956925215334;8722.825543489094;8842.77893052666;8848.601812918434;5038.622103342264;5091.439941606421;5129.666571672829;5213.113770658997;5313.884084342331;2049.3749406430884;2129.2228336657317;2137.6793480977976;2150.3119001919235;2169.620316444616;927.188709878856;976.7362812040709;1017.7150397888603;1074.301703892476;1084.7776205877776;20312.18751249963;20430.489237768878;20674;20675;20979.370618881152;989.6684610655511;1017.4658304390107;1019.3374306700556;1019.4393083803878;1051.4007016000805;10592.523336449878;11399.316041037564;11430;11468.311901285782;11545.826812597817;505591;508386;508523;509275;509558;90548;91893;91970.54014730017;92113.53943230375;92515.53742231245;1030.644427672452;1116.7487315354142;1150.2408410449232;1151.3782557419067;1152.2164927848976;18005.2797888083;18282.451526454228;18577.95769779719;18891.244350225836;19038.619227615647;38470.80764596159;41505.58494415017;44182.33726494169;45249.54750452453;46215.3067703978;53771.73114134404;62372.37627623847;63877;64113.35886641074;65033.024504631525;8679.435836670596;8970.37207395476;9050.592723327598;9106.725058491684;9196.448213107235;13107.14803537767;13128.803067953797;13450.327483625975;13790.65523361924;14108;12422.565210217599;12545.372731363213;12698.492060317483;12936.159149655243;12937.353132343533;12041.638750837488;12207.877921220674;12226.633201003628;12291.385430728607;12834.486620535074;11787.292762434281;12407;12439.00487960979;12624.17942833713;12736.299503527025;9090.590923408594;9560.28297877648;9582.377145485521;9601.807963840824;9684.515774211402;11392.601258955896;12611.43248553799;12651.367431628565;12955.805662915252;13143.408546615234 09:10:36 INFO - PROCESS | 12979 | |3;mootools.html;1401.8107555480008;1494.9402023919044;1527.6715506166177;1673.4393978017363;1811.728240763886;7756.728514501993;8337.624806883698;8710.128987101303;8714.1721536454;8894.421862578927;8445.197706217914;8516.787080322994;8519.616617252223;8528.658853645862;8532.23209911108;2448.7551244875503;2474.443250268693;2476.913308034219;2477.950440991179;2478.5166892455977;2370.845895016827;2462.495188486356;2462.495188486365;2463.8275320727557;2464.839785413951;2393.054743376374;2411.9035238590436;2442.096424322994;2450.3751543356507;2452.31335226137;2343.203310874311;2385.1771138957033;2390.816545809818;2394.7126344838557;2423.430493833948;2358.5872472317446;2371.1463873005705;2375.10933399975;2381.6903802505735;2381.97618023821;8311.625976831057;8659.393842431033;8707.303415726727;8712.477251364939;8739.388242823004;4940.975295123538;5109.105906466353;5118.411382690963;5122.538971492546;5122.795088196505;3378.527006219131;3525.136341596325;3540.203454222805;3570.071781336845;3583.5699716033982;1203.9638810835602;1216.9939150304192;1239.863615002349;1247.694314892848;1251.6808213905397;11870.643880683594;11958.043356531456;11977.760444791142;11983.340916249603;11997.160198786089;4271.658267338606;4438.467383913951;4477.977610111929;4522.163399771053;4549;9503.382280151767;9506;9515.809683806423;9528.142467177988;9528.380655257386;5220.504052115043;5247.02929957959;5248.029114613809;5254.448282930313;5256.369235691665;4201.33421419953;4456.22016147173;4529.796159172845;4590.288505281686;4938.308636790852;3656.1956369598647;3962.5244970603712;3979.880603581897;3989.062570296036;3996.9200615987515;1134.183387960667;1167.9240849344765;1175.9118066144902;1200.8979236764974;1207.2153100484754;934.3693007220235;958.9280803939732;991.6876184002016;993.8967745802154;997.0727223682045;5654.15187721845;5855.414458554111;5888;5946.256717910262;5958.106284057426;469.2023559948102;469.2562288772266;471.3942583779826;474.1323378217853;477.2507163752944;3745.9625403745613;3806.5812760596314;3838.673712734393;3849.172427927953;3917.745346552465;10189.898101019044;10345.068943795095;10633.361998280128;10659.733506662396;10803.83794243087;10730.463476826128;10732.409717465458;10742.78514429707;10751.569937202581;10754.623588174376;8113.959430202929;8124.715634952749;8258.463199891989;8308.54303013328;8324.334053275723;5622.240997465454;5682.516986056232;5754.6547207167705;5849.298084229919;5852.092925596539;9579.568919398784;9755.414675119515;9820.803583928424;9820.852687209555;9902.851457228004;9010.36927415101;9736.172425343924;9803;10060.798784024131;10071.647492337734;6913.619750913748;7261.782146535613;7323.597202153933;7382.741604043833;7530.322270995636;8098.190180981855;8110.270075693216;8161.673533058611;8321.12628174033;8424.915750842658;7411.4811963162065;7525.849483010418;7569.545827250383;7629.046369203849;7636.389088872988;7404;7436.851262974818;7490;7536.397088233038;7712.074551053909;7183.173934997542;7195.244499327495;7280.599567023864;7286.380657644159;7289.890651640336;4899.265110233494;5019.297298378303;5026.698398096126;5051;5187.870303242449;4872.366592342973;4966.081274964144;5016.54851063406;5049.495050494921;5276.76254568553 09:10:36 INFO - PROCESS | 12979 | |4;prototype.html;312.6045552376244;318.8852013275221;333.5613668026546;343.33221883436727;344.1877169879085;5438.184272359148;5523.585731070176;5537.640053396532;5545.112781954893;5564.8052318168875;5102.826349939507;5227;5258.710770907599;5385.21914322424;5407.297051383324;4252.383404406375;4256.787160641971;4260.615300027488;4280.336547835091;4282.764447955369;4278.251306021434;4283.935740963888;4300.6989510734265;4304.117655880537;4310.633596144332;4007.73949693269;4026.255142798592;4232.407462955189;4249.617534421902;4252.447181866369;4211.536730959592;4240.15196960606;4241.133901083529;4246.789664945476;4251.447311849455;3750.2124553843723;3753.680937120321;3757.1734218472166;3777.8855237704943;3826.885193444173;5182.97408512955;5285.233641122054;5304.602154838364;5378.731063446812;5423.511883930426;4883.438404583483;4892.926606100911;4897.51024897511;4900.803967841282;4905.386826646669;5634.3802181759975;5654.688992105433;5668.546516278729;5682.744276507526;5682.7442765075675;4100.979495102535;4137.220994972161;4140.0891803803415;4171.6036976487185;4173.561776013506;5899.734511946973;5904.852378690524;5923.289205295349;5929.703514824285;5933.4659880610525;4258.254805409041;4284.700070995001;4303.849365272201;4311.805968731414;4328.545502722169;4005.679545636342;4175.749455032707;4215.704900656986;4362.585554372329;4379.299312110111;4795.640326975491;4808.495108013678;4824.445188803333;4827.179379505493;4836.081144582518;5647.350554686182;5715.571332150105;5726.79956201531;5746.913796293059;5758.193852860602;1340.5777180188209;1402.4390243902521;1417.6172433442912;1420.6007083023214;1444.1335198880588;925.0804580495499;953.6137864165024;958.9856152157723;1004.995004995005;1011.5599714124379;5611.186377975171;5632.802851900164;5656.264685590847;5665.583604098975;5695.316562012502;5964.85087872798;5977.462028417463;5983;6011.308699499614;6020.4780203581395;5396.946030539723;5619.157126431068;5672.376038635747;5673.716314184274;5709.001849352693;3121.609798775153;3149.755846440689;3179.4118088153764;3189.489681650925;3190.1865024418626;5849.590528662953;6086.421789929949;6134.343727193722;6150.662748155762;6190.7523699052435;6001.2896324548165;6056.576704474445;6064.423879731418;6067.332593414721;6074.9018000819015;5820.534357251495;6145.139680444741;6179.69101544921;6183.258009038854;6208.782692605737;5842.503387212135;5882.264716910387;5896.6756828374855;5903.91144132847;5913.905927403444;5794.9138310207445;5795.739191736467;5797.594168408171;5812.546863284179;5827.446392592697;5880.47107751979;6032.375916476347;6072.787452439144;6082.513398928164;6136.693165341804;4143.3784932260405;4183.184279065675;4207.93688094685;4217.831286748496;4230.70385073042;5881.794137205177;5906.080523829686;6010.459058684739;6027.975244208583;6033.336333003454;5790.305163380422;5791.884162316815;5797.91303130445;5811.651300921958;5830.271216097988;5705.62913410627;5720.799772007959;5732.598718089694;5752.769889204553;5777.162311464898;5259.179695269866;5274.472552744694;5595.804146855004;5720.083771936448;5742;5698.492663228167;5702.344230413555;5703.116017017369;5711.571632127524;5730.762119402691;5468.808591699271;5579.609427340048;5585.385607583082;5617.876424714992;5621.4659607337235 09:10:36 INFO - PROCESS | 12979 | |5;yui.html;790.786487648335;809.6397103289039;821.0147822612867;830.9916900830992;861.3152543727736;16248.350065997362;17244.568885777862;17426.47720568385;17457.563560910985;17505.56236094098;15052.096874187557;15336.923315383436;15571.221438928065;15753.054816711005;15758;854.0477367734977;879.7932485865816;880.4717169698162;881.9162179592961;883.1080608585343;865.0484467086186;867.3754896474528;869.6912596028413;870.1994165367845;870.899846517649;927.9072092790734;928.8978212396664;930.4696323095839;930.6882194464862;931.1293940165932;917.2111983694051;919.1819280840058;927.8283517549207;928.22029495224;928.2481190235897;680.4692339974778;680.6868840333452;680.9557378770413;680.9897851532231;681.3118750062473;16719;16749.162541872858;16817.66364672712;16846.578835529086;16857.24142413582;14696.779548306677;15328.386864525406;16162.676746465006;16166.191690415431;16649.25078371476;13011;13127.671808204872;13164.473421063147;13263.668408289774;13432.664183395396;3964.365701487748;3992.4210989406665;4018.9799051004557;4035.152617950205;4092.9181416371507;48282;56886;71453.28546714518;81605;84308;12473.93763031179;12603.18079324841;12880.162789418659;12921;13179.07746457758;22738.43153921132;23083.307500774667;23265.418364541023;23281.534369312518;23484.647730284058;9036.683716069905;9100.908990910139;9158.725238242863;9200;9275.304352173614;4917.852464426073;5205.453427390145;5244.082285600081;5278.683279003195;5346.599005074635;3454.516367708523;3601.1177261571083;3622.836972336251;3631.1103779574173;3639.490471334015;619.306376857913;620.4756980351596;633.467886974934;635.5170070746174;637.3403527349265;612.7426480878042;734.6547122852251;758.12058012705;773.4547144263281;778.661282342183;9991.950040249752;10071.59713611462;10255.641052563124;10269.948650256702;10480.895191047992;713.3223437734069;784.5802495664792;799.3485309472877;799.672134424883;799.8240387114826;4597.839075632403;4615.14619795339;4628.236341003764;4629.560191781776;4716.056788642217;72161;89257.55371223102;112314.43842780734;130554;135746.32126839302;79653.60173199097;119630;121835;122175;122659.38670306592;58078.70960645254;60874.39125608776;82921;104841;104968.99999999847;8991.865122023306;9088.409253398508;9246.95376523113;9395.201407880133;9564.66523671668;60936;60950.390496096246;62229;62753.293502988374;63703;75575;76108.61945690236;77512.34925904464;79505.20494794978;82375;14001.859981400463;14013.019088664103;14388.352524136648;14570.708585828435;14717.484888028868;15591.688166236838;15947.840521594635;16327.265273062503;16359.263833127776;16788.664226715155;14778.113313201242;14937.850621493646;15497.380104796133;15932.522024339289;16415.507534773977;14842.183679897278;15011.549653510412;15708.293126809092;16339.754903676216;16393.42623008189;12769.61691149267;13327.333633318489;13981.161130332213;14022.509212177525;14117.43530258778;11626.76746465083;11769.117316201147;12053.57812476559;12141.575044873387;12511.812322814983;12393.194442361219;12424.49020045088;12809.167404118702;12893.613191604267;12919.741605167656 09:10:36 INFO - PROCESS | 12979 | __end_tp_report 09:10:36 INFO - PROCESS | 12979 | __start_cc_report 09:10:36 INFO - PROCESS | 12979 | _x_x_mozilla_cycle_collect,845 09:10:36 INFO - PROCESS | 12979 | __end_cc_report 09:10:36 INFO - PROCESS | 12979 | __startTimestamp1475683836729__endTimestamp 09:10:36 INFO - PROCESS | 12979 | 09:10:36 INFO - PROCESS | 12979 | ------- Summary: start ------- 09:10:36 INFO - PROCESS | 12979 | Number of tests: 6 09:10:36 INFO - PROCESS | 12979 | 09:10:36 INFO - PROCESS | 12979 | [#0] dojo.html Cycles:165 Average:5369.02 Median:5558.53 stddev:608.94 (11.0%) stddev-sans-first:583.40 09:10:36 INFO - PROCESS | 12979 | Values: 3066.5 3091.1 3122.4 3142.3 3161.5 5341.3 5406.9 5425.9 5432.9 5449.2 5402.8 5423.9 5425.2 5428.5 5435.4 4892.5 4907.5 4953.8 4966.4 4974.7 4851.3 4864.7 4884.4 4944.9 5003.9 4872.7 4936.5 4942.3 4947.1 4965.9 4948.1 4949.2 4965.6 4971.2 4992.0 4765.9 4788.2 4805.8 4850.1 4870.0 5237.8 5247.4 5258.6 5338.4 5424.0 5701.1 5710.5 5710.9 5720.8 5722.6 5579.5 5684.2 5751.7 5754.1 5762.6 5371.8 5389.2 5433.8 5442.1 5452.3 5948.5 5958.5 5961.8 5964.3 5965.4 5490.2 5534.8 5719.6 5725.5 5728.0 5676.1 5713.2 5745.2 5778.9 5810.7 5726.0 5732.8 5735.0 5736.9 5765.5 5618.4 5730.3 5785.2 5802.4 5816.5 4380.5 4444.5 4450.8 4457.5 4487.4 3971.9 3972.1 3975.0 3975.4 3998.8 5433.2 5457.9 5505.1 5507.1 5513.8 5646.0 5681.4 5783.6 5807.3 5809.2 5493.1 5522.4 5527.0 5528.5 5552.1 4966.2 4998.4 4998.7 5000.8 5028.6 5813.5 5820.3 5834.9 5999.5 6099.2 5855.4 5879.6 5883.6 5890.2 5923.9 6058.9 6094.7 6097.0 6101.4 6111.9 5566.1 5598.9 5619.6 5775.2 5784.2 5738.8 5798.7 5815.2 5848.9 5853.3 5647.3 5674.2 5698.4 5706.7 5710.3 5360.3 5375.2 5675.0 5682.3 5697.2 5558.5 5607.8 5609.7 5679.3 5703.7 5694.4 5729.7 5737.4 5741.8 5752.1 5465.8 5507.5 5521.6 5587.1 5594.6 09:10:36 INFO - PROCESS | 12979 | 09:10:36 INFO - PROCESS | 12979 | [#1] ext.html Cycles:180 Average:66545.29 Median:43597.39 stddev:134055.79 (307.5%) stddev-sans-first:134340.36 09:10:36 INFO - PROCESS | 12979 | Values: 580.3 633.6 636.9 641.7 665.7 33824.0 45203.0 45897.0 45997.3 46112.1 44636.6 45185.7 45270.8 45395.1 45413.5 8322.3 9131.8 9271.0 9274.4 9280.3 8382.7 8664.8 8669.2 10909.5 11166.7 11286.7 11329.4 11346.1 11358.5 11363.1 11053.4 11115.1 11127.9 11136.3 11136.5 10936.2 10996.1 10997.5 11003.7 11014.3 45468.3 45792.5 45851.0 45867.8 45878.0 3512.9 3807.3 3943.0 4004.3 4253.2 33968.8 34086.5 34229.3 34373.7 34403.5 4694.3 4782.3 4784.2 4790.4 4846.4 381364.0 381755.0 382048.0 382348.0 382546.0 62138.4 62158.0 62549.1 62578.0 62609.0 40868.6 41585.6 41866.0 43705.8 67729.0 19727.0 19883.0 19951.7 19959.9 19960.4 13974.7 15354.5 15453.1 15465.0 15477.0 18187.3 19924.0 20553.1 21338.8 21426.5 7401.7 7437.9 7440.0 7493.4 7748.6 987.8 1019.5 1043.7 1067.6 1112.9 33491.7 33723.0 34025.1 34220.0 34249.3 1127.4 1129.7 1142.2 1209.7 1228.6 15650.7 18762.9 18980.4 19674.1 19745.0 621853.0 623390.0 628985.0 630895.0 632353.0 64834.0 65146.0 65376.0 65380.0 65452.3 1332.2 1395.2 1400.5 1435.1 1466.7 33025.8 34732.7 35247.5 35247.6 35451.6 53660.5 54290.2 54309.2 54412.5 54467.2 439437.0 443222.0 444227.0 444852.0 581803.0 44494.0 46540.0 46916.5 47373.5 48140.3 51469.7 51685.7 51849.0 52195.5 52266.0 43489.0 43987.0 45261.8 45326.8 45902.0 43900.8 44360.0 44670.0 45388.3 45807.8 44715.8 45627.0 45709.5 46055.8 46064.3 36727.3 39174.2 39647.6 39924.6 40093.6 29404.6 29477.6 29771.9 30160.4 30193.2 09:10:36 INFO - PROCESS | 12979 | 09:10:36 INFO - PROCESS | 12979 | [#2] jquery.html Cycles:180 Average:29635.94 Median:2159.97 stddev:83572.95 (3869.2%) stddev-sans-first:83778.57 09:10:36 INFO - PROCESS | 12979 | Values: 403.4 407.5 417.7 425.0 428.1 14330.6 16167.7 20057.5 20096.1 20908.8 19462.3 19570.5 19879.1 20742.2 20853.0 5821.9 5889.9 6071.8 6111.5 6117.6 4127.6 6094.9 6130.8 6134.0 6141.6 4329.8 5982.5 5994.6 6078.5 6085.7 6027.5 6066.4 6082.0 6612.5 6661.9 5988.2 5995.8 5998.8 6006.2 6010.9 14507.3 14563.6 14836.3 14925.9 15020.6 2382.5 2425.7 2485.6 2694.3 3040.4 10662.8 10724.7 10881.6 11064.6 11227.6 1775.5 1959.9 2012.2 2080.4 2111.9 67505.7 68723.7 73905.0 75331.0 81504.6 11786.8 11960.3 11997.9 12101.3 12283.6 17009.4 17039.4 17055.0 17068.7 17068.9 8753.7 8835.3 8963.9 9035.8 9138.5 8607.5 8615.0 8722.8 8842.8 8848.6 5038.6 5091.4 5129.7 5213.1 5313.9 2049.4 2129.2 2137.7 2150.3 2169.6 927.2 976.7 1017.7 1074.3 1084.8 20312.2 20430.5 20674.0 20675.0 20979.4 989.7 1017.5 1019.3 1019.4 1051.4 10592.5 11399.3 11430.0 11468.3 11545.8 505591.0 508386.0 508523.0 509275.0 509558.0 90548.0 91893.0 91970.5 92113.5 92515.5 1030.6 1116.7 1150.2 1151.4 1152.2 18005.3 18282.5 18578.0 18891.2 19038.6 38470.8 41505.6 44182.3 45249.5 46215.3 53771.7 62372.4 63877.0 64113.4 65033.0 8679.4 8970.4 9050.6 9106.7 9196.4 13107.1 13128.8 13450.3 13790.7 14108.0 12422.6 12545.4 12698.5 12936.2 12937.4 12041.6 12207.9 12226.6 12291.4 12834.5 11787.3 12407.0 12439.0 12624.2 12736.3 9090.6 9560.3 9582.4 9601.8 9684.5 11392.6 12611.4 12651.4 12955.8 13143.4 09:10:36 INFO - PROCESS | 12979 | 09:10:36 INFO - PROCESS | 12979 | [#3] mootools.html Cycles:180 Average:5659.55 Median:4991.31 stddev:3176.02 (63.6%) stddev-sans-first:3168.81 09:10:36 INFO - PROCESS | 12979 | Values: 1401.8 1494.9 1527.7 1673.4 1811.7 7756.7 8337.6 8710.1 8714.2 8894.4 8445.2 8516.8 8519.6 8528.7 8532.2 2448.8 2474.4 2476.9 2478.0 2478.5 2370.8 2462.5 2462.5 2463.8 2464.8 2393.1 2411.9 2442.1 2450.4 2452.3 2343.2 2385.2 2390.8 2394.7 2423.4 2358.6 2371.1 2375.1 2381.7 2382.0 8311.6 8659.4 8707.3 8712.5 8739.4 4941.0 5109.1 5118.4 5122.5 5122.8 3378.5 3525.1 3540.2 3570.1 3583.6 1204.0 1217.0 1239.9 1247.7 1251.7 11870.6 11958.0 11977.8 11983.3 11997.2 4271.7 4438.5 4478.0 4522.2 4549.0 9503.4 9506.0 9515.8 9528.1 9528.4 5220.5 5247.0 5248.0 5254.4 5256.4 4201.3 4456.2 4529.8 4590.3 4938.3 3656.2 3962.5 3979.9 3989.1 3996.9 1134.2 1167.9 1175.9 1200.9 1207.2 934.4 958.9 991.7 993.9 997.1 5654.2 5855.4 5888.0 5946.3 5958.1 469.2 469.3 471.4 474.1 477.3 3746.0 3806.6 3838.7 3849.2 3917.7 10189.9 10345.1 10633.4 10659.7 10803.8 10730.5 10732.4 10742.8 10751.6 10754.6 8114.0 8124.7 8258.5 8308.5 8324.3 5622.2 5682.5 5754.7 5849.3 5852.1 9579.6 9755.4 9820.8 9820.9 9902.9 9010.4 9736.2 9803.0 10060.8 10071.6 6913.6 7261.8 7323.6 7382.7 7530.3 8098.2 8110.3 8161.7 8321.1 8424.9 7411.5 7525.8 7569.5 7629.0 7636.4 7404.0 7436.9 7490.0 7536.4 7712.1 7183.2 7195.2 7280.6 7286.4 7289.9 4899.3 5019.3 5026.7 5051.0 5187.9 4872.4 4966.1 5016.5 5049.5 5276.8 09:10:36 INFO - PROCESS | 12979 | 09:10:36 INFO - PROCESS | 12979 | [#4] prototype.html Cycles:180 Average:4837.41 Median:5572.21 stddev:1429.86 (25.7%) stddev-sans-first:1392.96 09:10:36 INFO - PROCESS | 12979 | Values: 312.6 318.9 333.6 343.3 344.2 5438.2 5523.6 5537.6 5545.1 5564.8 5102.8 5227.0 5258.7 5385.2 5407.3 4252.4 4256.8 4260.6 4280.3 4282.8 4278.3 4283.9 4300.7 4304.1 4310.6 4007.7 4026.3 4232.4 4249.6 4252.4 4211.5 4240.2 4241.1 4246.8 4251.4 3750.2 3753.7 3757.2 3777.9 3826.9 5183.0 5285.2 5304.6 5378.7 5423.5 4883.4 4892.9 4897.5 4900.8 4905.4 5634.4 5654.7 5668.5 5682.7 5682.7 4101.0 4137.2 4140.1 4171.6 4173.6 5899.7 5904.9 5923.3 5929.7 5933.5 4258.3 4284.7 4303.8 4311.8 4328.5 4005.7 4175.7 4215.7 4362.6 4379.3 4795.6 4808.5 4824.4 4827.2 4836.1 5647.4 5715.6 5726.8 5746.9 5758.2 1340.6 1402.4 1417.6 1420.6 1444.1 925.1 953.6 959.0 1005.0 1011.6 5611.2 5632.8 5656.3 5665.6 5695.3 5964.9 5977.5 5983.0 6011.3 6020.5 5396.9 5619.2 5672.4 5673.7 5709.0 3121.6 3149.8 3179.4 3189.5 3190.2 5849.6 6086.4 6134.3 6150.7 6190.8 6001.3 6056.6 6064.4 6067.3 6074.9 5820.5 6145.1 6179.7 6183.3 6208.8 5842.5 5882.3 5896.7 5903.9 5913.9 5794.9 5795.7 5797.6 5812.5 5827.4 5880.5 6032.4 6072.8 6082.5 6136.7 4143.4 4183.2 4207.9 4217.8 4230.7 5881.8 5906.1 6010.5 6028.0 6033.3 5790.3 5791.9 5797.9 5811.7 5830.3 5705.6 5720.8 5732.6 5752.8 5777.2 5259.2 5274.5 5595.8 5720.1 5742.0 5698.5 5702.3 5703.1 5711.6 5730.8 5468.8 5579.6 5585.4 5617.9 5621.5 09:10:36 INFO - PROCESS | 12979 | 09:10:36 INFO - PROCESS | 12979 | [#5] yui.html Cycles:180 Average:21672.25 Median:4027.07 stddev:30930.34 (768.1%) stddev-sans-first:30977.37 09:10:36 INFO - PROCESS | 12979 | Values: 790.8 809.6 821.0 831.0 861.3 16248.4 17244.6 17426.5 17457.6 17505.6 15052.1 15336.9 15571.2 15753.1 15758.0 854.0 879.8 880.5 881.9 883.1 865.0 867.4 869.7 870.2 870.9 927.9 928.9 930.5 930.7 931.1 917.2 919.2 927.8 928.2 928.2 680.5 680.7 681.0 681.0 681.3 16719.0 16749.2 16817.7 16846.6 16857.2 14696.8 15328.4 16162.7 16166.2 16649.3 13011.0 13127.7 13164.5 13263.7 13432.7 3964.4 3992.4 4019.0 4035.2 4092.9 48282.0 56886.0 71453.3 81605.0 84308.0 12473.9 12603.2 12880.2 12921.0 13179.1 22738.4 23083.3 23265.4 23281.5 23484.6 9036.7 9100.9 9158.7 9200.0 9275.3 4917.9 5205.5 5244.1 5278.7 5346.6 3454.5 3601.1 3622.8 3631.1 3639.5 619.3 620.5 633.5 635.5 637.3 612.7 734.7 758.1 773.5 778.7 9992.0 10071.6 10255.6 10269.9 10480.9 713.3 784.6 799.3 799.7 799.8 4597.8 4615.1 4628.2 4629.6 4716.1 72161.0 89257.6 112314.4 130554.0 135746.3 79653.6 119630.0 121835.0 122175.0 122659.4 58078.7 60874.4 82921.0 104841.0 104969.0 8991.9 9088.4 9247.0 9395.2 9564.7 60936.0 60950.4 62229.0 62753.3 63703.0 75575.0 76108.6 77512.3 79505.2 82375.0 14001.9 14013.0 14388.4 14570.7 14717.5 15591.7 15947.8 16327.3 16359.3 16788.7 14778.1 14937.9 15497.4 15932.5 16415.5 14842.2 15011.5 15708.3 16339.8 16393.4 12769.6 13327.3 13981.2 14022.5 14117.4 11626.8 11769.1 12053.6 12141.6 12511.8 12393.2 12424.5 12809.2 12893.6 12919.7 09:10:36 INFO - PROCESS | 12979 | -------- Summary: end -------- 09:10:36 INFO - PROCESS | 12979 | 09:10:37 INFO - TEST-INFO | 12979: exit 0 09:10:37 INFO - TEST-OK | dromaeo_css | took 1113399ms 09:10:37 INFO - TEST-START | kraken 09:10:37 INFO - operating with platform_type : linux_ 09:10:37 INFO - Initialising browser for kraken test... 09:10:37 INFO - TEST-INFO | started process 13299 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAJ5MTE/profile http://localhost:53990/getInfo.html) 09:10:41 INFO - PROCESS | 13299 | __metrics Screen width/height:1600/1200 09:10:41 INFO - PROCESS | 13299 | colorDepth:24 09:10:41 INFO - PROCESS | 13299 | Browser inner width/height: 1024/697 09:10:41 INFO - PROCESS | 13299 | __metrics 09:10:41 INFO - PROCESS | 13299 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:10:41 INFO - PROCESS | 13299 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:10:42 INFO - PROCESS | 13299 | console.error: PushService: 09:10:42 INFO - PROCESS | 13299 | stateChangeProcessEnqueue: Error transitioning state 09:10:42 INFO - PROCESS | 13299 | UnknownError 09:10:42 INFO - TEST-INFO | 13299: exit 0 09:10:42 INFO - Browser initialized. 09:10:42 INFO - Running cycle 1/1 for kraken test... 09:10:42 INFO - TEST-INFO | started process 13392 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAJ5MTE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:54 INFO - PROCESS | 13392 | RSS: Main: 144052224 09:10:54 INFO - PROCESS | 13392 | 09:10:57 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53990/tests/kraken/driver.html?testName=audio-beat-detection) 09:10:58 INFO - PROCESS | 13392 | RSS: Main: 185937920 09:10:58 INFO - PROCESS | 13392 | 09:11:00 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53990/tests/kraken/driver.html?testName=audio-dft) 09:11:01 INFO - PROCESS | 13392 | RSS: Main: 175894528 09:11:01 INFO - PROCESS | 13392 | 09:11:04 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53990/tests/kraken/driver.html?testName=audio-fft) 09:11:04 INFO - PROCESS | 13392 | RSS: Main: 267161600 09:11:04 INFO - PROCESS | 13392 | 09:11:06 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53990/tests/kraken/driver.html?testName=audio-oscillator) 09:11:07 INFO - PROCESS | 13392 | RSS: Main: 174018560 09:11:07 INFO - PROCESS | 13392 | 09:11:09 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53990/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:11:10 INFO - PROCESS | 13392 | RSS: Main: 175513600 09:11:10 INFO - PROCESS | 13392 | 09:11:13 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53990/tests/kraken/driver.html?testName=imaging-darkroom) 09:11:13 INFO - PROCESS | 13392 | RSS: Main: 211632128 09:11:13 INFO - PROCESS | 13392 | 09:11:16 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53990/tests/kraken/driver.html?testName=imaging-desaturate) 09:11:17 INFO - PROCESS | 13392 | RSS: Main: 210022400 09:11:17 INFO - PROCESS | 13392 | 09:11:20 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53990/tests/kraken/driver.html?testName=json-parse-financial) 09:11:20 INFO - PROCESS | 13392 | RSS: Main: 208613376 09:11:20 INFO - PROCESS | 13392 | 09:11:22 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53990/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:11:22 INFO - PROCESS | 13392 | RSS: Main: 171761664 09:11:22 INFO - PROCESS | 13392 | 09:11:24 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53990/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:11:25 INFO - PROCESS | 13392 | RSS: Main: 179404800 09:11:25 INFO - PROCESS | 13392 | 09:11:27 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53990/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:11:27 INFO - PROCESS | 13392 | RSS: Main: 188862464 09:11:27 INFO - PROCESS | 13392 | 09:11:30 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53990/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:11:31 INFO - PROCESS | 13392 | RSS: Main: 197373952 09:11:31 INFO - PROCESS | 13392 | 09:11:33 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53990/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:11:34 INFO - PROCESS | 13392 | RSS: Main: 193056768 09:11:34 INFO - PROCESS | 13392 | 09:11:35 INFO - PROCESS | 13392 | Cycle 1(1): loaded http://localhost:53990/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53990/tests/kraken/driver.html?testName=ai-astar) 09:11:36 INFO - PROCESS | 13392 | RSS: Main: 244998144 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | __start_tp_report 09:11:36 INFO - PROCESS | 13392 | _x_x_mozilla_page_load 09:11:36 INFO - PROCESS | 13392 | _x_x_mozilla_page_load_details 09:11:36 INFO - PROCESS | 13392 | |i|pagename|runs| 09:11:36 INFO - PROCESS | 13392 | |0;ai-astar;111;111;112;112;113;113;114;112;112;113 09:11:36 INFO - PROCESS | 13392 | |1;audio-beat-detection;140;117;119;142;121;118;117;116;155;117 09:11:36 INFO - PROCESS | 13392 | |2;audio-dft;161;162;162;161;160;160;160;159;159;161 09:11:36 INFO - PROCESS | 13392 | |3;audio-fft;75;75;76;118;76;76;76;77;103;75 09:11:36 INFO - PROCESS | 13392 | |4;audio-oscillator;82;81;112;82;83;82;110;82;82;111 09:11:36 INFO - PROCESS | 13392 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 09:11:36 INFO - PROCESS | 13392 | |6;imaging-darkroom;146;145;145;146;146;145;145;147;145;146 09:11:36 INFO - PROCESS | 13392 | |7;imaging-desaturate;95;96;94;96;95;95;95;95;96;96 09:11:36 INFO - PROCESS | 13392 | |8;json-parse-financial;52;51;51;51;52;50;50;50;52;52 09:11:36 INFO - PROCESS | 13392 | |9;json-stringify-tinderbox;57;58;59;58;57;57;58;58;57;57 09:11:36 INFO - PROCESS | 13392 | |10;stanford-crypto-aes;74;74;73;74;75;74;75;74;80;73 09:11:36 INFO - PROCESS | 13392 | |11;stanford-crypto-ccm;129;120;122;128;131;126;118;126;156;124 09:11:36 INFO - PROCESS | 13392 | |12;stanford-crypto-pbkdf2;158;156;155;153;154;150;155;152;151;156 09:11:36 INFO - PROCESS | 13392 | |13;stanford-crypto-sha256-iterative;62;61;63;62;62;63;62;62;61;60 09:11:36 INFO - PROCESS | 13392 | __end_tp_report 09:11:36 INFO - PROCESS | 13392 | __start_cc_report 09:11:36 INFO - PROCESS | 13392 | _x_x_mozilla_cycle_collect,1576 09:11:36 INFO - PROCESS | 13392 | __end_cc_report 09:11:36 INFO - PROCESS | 13392 | __startTimestamp1475683896189__endTimestamp 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | ------- Summary: start ------- 09:11:36 INFO - PROCESS | 13392 | Number of tests: 14 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.50 stddev:0.95 (0.8%) stddev-sans-first:0.88 09:11:36 INFO - PROCESS | 13392 | Values: 111.0 111.0 112.0 112.0 113.0 113.0 114.0 112.0 112.0 113.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#1] audio-beat-detection Cycles:10 Average:126.20 Median:120.00 stddev:14.04 (11.7%) stddev-sans-first:13.97 09:11:36 INFO - PROCESS | 13392 | Values: 140.0 117.0 119.0 142.0 121.0 118.0 117.0 116.0 155.0 117.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 09:11:36 INFO - PROCESS | 13392 | Values: 161.0 162.0 162.0 161.0 160.0 160.0 160.0 159.0 159.0 161.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#3] audio-fft Cycles:10 Average:82.70 Median:76.00 stddev:15.09 (19.8%) stddev-sans-first:15.74 09:11:36 INFO - PROCESS | 13392 | Values: 75.0 75.0 76.0 118.0 76.0 76.0 76.0 77.0 103.0 75.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:82.00 stddev:14.02 (17.1%) stddev-sans-first:14.52 09:11:36 INFO - PROCESS | 13392 | Values: 82.0 81.0 112.0 82.0 83.0 82.0 110.0 82.0 82.0 111.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:11:36 INFO - PROCESS | 13392 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 09:11:36 INFO - PROCESS | 13392 | Values: 146.0 145.0 145.0 146.0 146.0 145.0 145.0 147.0 145.0 146.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:11:36 INFO - PROCESS | 13392 | Values: 95.0 96.0 94.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:0.88 (1.7%) stddev-sans-first:0.87 09:11:36 INFO - PROCESS | 13392 | Values: 52.0 51.0 51.0 51.0 52.0 50.0 50.0 50.0 52.0 52.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#9] json-stringify-tinderbox Cycles:10 Average:57.60 Median:58.00 stddev:0.70 (1.2%) stddev-sans-first:0.71 09:11:36 INFO - PROCESS | 13392 | Values: 57.0 58.0 59.0 58.0 57.0 57.0 58.0 58.0 57.0 57.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.00 stddev:2.01 (2.7%) stddev-sans-first:2.12 09:11:36 INFO - PROCESS | 13392 | Values: 74.0 74.0 73.0 74.0 75.0 74.0 75.0 74.0 80.0 73.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:127.00 stddev:10.64 (8.4%) stddev-sans-first:11.27 09:11:36 INFO - PROCESS | 13392 | Values: 129.0 120.0 122.0 128.0 131.0 126.0 118.0 126.0 156.0 124.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.00 Median:155.00 stddev:2.49 (1.6%) stddev-sans-first:2.19 09:11:36 INFO - PROCESS | 13392 | Values: 158.0 156.0 155.0 153.0 154.0 150.0 155.0 152.0 151.0 156.0 09:11:36 INFO - PROCESS | 13392 | 09:11:36 INFO - PROCESS | 13392 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 09:11:36 INFO - PROCESS | 13392 | Values: 62.0 61.0 63.0 62.0 62.0 63.0 62.0 62.0 61.0 60.0 09:11:36 INFO - PROCESS | 13392 | -------- Summary: end -------- 09:11:36 INFO - PROCESS | 13392 | 09:11:37 INFO - TEST-INFO | 13392: exit 0 09:11:37 INFO - TEST-OK | kraken | took 59881ms 09:11:37 INFO - SUITE-END | took 1173s 09:11:37 INFO - Completed test suite (00:19:33) 09:11:37 INFO - javascript benchmark 09:11:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3066.4633689104407, 3091.0881290019443, 3122.4379611669906, 3142.340108577199, 3161.4625513662677, 5341.278927344807, 5406.91889621656, 5425.945740542594, 5432.864178395542, 5449.20986456964, 5402.810901618444, 5423.918641220375, 5425.159100339453, 5428.511433970942, 5435.42927992561, 4892.461829198785, 4907.509249075082, 4953.777080031389, 4966.404031516223, 4974.7015179089185, 4851.320815085891, 4864.683795553296, 4884.365032545764, 4944.87637809054, 5003.949960500385, 4872.707637541741, 4936.456989731127, 4942.3327850740225, 4947.084789313988, 4965.875853103683, 4948.059868624986, 4949.232868905326, 4965.602751779867, 4971.204607262821, 4991.950080499185, 4765.880852978668, 4788.209945359012, 4805.783739731721, 4850.078485087822, 4870.025994801019, 5237.816676416345, 5247.422783493813, 5258.55302299305, 5338.359396872362, 5424.0236757383655, 5701.14482827575, 5710.486056254916, 5710.857228569277, 5720.799772008001, 5722.62802917809, 5579.525740312079, 5684.204211410406, 5751.683657398841, 5754.108113242454, 5762.596618236725, 5371.785128594852, 5389.164679474688, 5433.809816656477, 5442.102053161184, 5452.318460192476, 5948.464638182584, 5958.493528050164, 5961.8211453655595, 5964.343922168558, 5965.373635768268, 5490.231367608538, 5534.806281780118, 5719.628224165416, 5725.541956643458, 5727.971360143172, 5676.091825307931, 5713.22871412354, 5745.195672605839, 5778.88442231153, 5810.6513609183585, 5725.969325521362, 5732.799352022659, 5734.971325143431, 5736.856578585569, 5765.4522820332, 5618.438156184349, 5730.34101078373, 5785.247917770663, 5802.4197580241635, 5816.505597024216, 4380.518143004267, 4444.511103778582, 4450.799714012877, 4457.487388950312, 4487.371767952489, 3971.920561588752, 3972.066564357374, 3974.980125099356, 3975.4235635832642, 3998.8000599969887, 5433.157860531624, 5457.863553411196, 5505.119180931032, 5507.0637991541535, 5513.751881165357, 5645.971770141123, 5681.431856814368, 5783.624064435799, 5807.303123625192, 5809.186713860063, 5493.121100623881, 5522.420145884705, 5526.972365138148, 5528.5024347808085, 5552.139418390076, 4966.15575352191, 4998.375203099613, 4998.700077995332, 5000.799968001312, 5028.622853285945, 5813.476787089182, 5820.272465941757, 5834.941650583524, 5999.490043346277, 6099.207103076572, 5855.443732845373, 5879.588428810113, 5883.61756485827, 5890.175375447527, 5923.851903702442, 6058.90911636317, 6094.695265236632, 6097.0, 6101.359357267423, 6111.8777624446375, 5566.081596536526, 5598.944010559842, 5619.578531610227, 5775.249217601853, 5784.161296611884, 5738.770449182153, 5798.739056742373, 5815.2149459822385, 5848.883022339444, 5853.268341457318, 5647.265855438931, 5674.233978413026, 5698.373178950395, 5706.657600543981, 5710.3433105193435, 5360.3299587551555, 5375.220593013913, 5674.971625141848, 5682.3465301489205, 5697.173909783141, 5558.527525160407, 5607.831765047055, 5609.663420194802, 5679.290088738908, 5703.657780533182, 5694.373618902, 5729.656220626775, 5737.368889422077, 5741.827745167651, 5752.050911599539, 5465.7813687453645, 5507.4767897051315, 5521.641093328921, 5587.0502014656795, 5594.552435805044], "value": 5328.002099929656, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.2514755964805, 633.6071635585937, 636.9394907483788, 641.7144370755013, 665.650533469928, 33824.0, 45203.0, 45896.999999999956, 45997.31004034934, 46112.07775844481, 44636.55363446373, 45185.74071296438, 45270.773646131805, 45395.09209815802, 45413.545864541346, 8322.334213262955, 9131.77170570736, 9270.953645231799, 9274.39716418434, 9280.257579393632, 8382.664693412256, 8664.826703465927, 8669.176428239307, 10909.509072091774, 11166.665000050012, 11286.66140015801, 11329.376884271362, 11346.092312615012, 11358.545658173663, 11363.090952723762, 11053.447327633627, 11115.055220306203, 11127.888721112766, 11136.33182009078, 11136.498857551349, 10936.234463587552, 10996.06533444658, 10997.450127493594, 11003.669889903316, 11014.339139651567, 45468.3179752304, 45792.54207457916, 45851.0, 45867.77066114682, 45878.0, 3512.877049303262, 3807.257584770971, 3943.0339566806315, 4004.3392840181336, 4253.1706317268145, 33968.830155849064, 34086.48870266948, 34229.315413691584, 34373.656263437544, 34403.4839477408, 4694.342792009122, 4782.33047373368, 4784.162771515006, 4790.4251489821445, 4846.39420072491, 381364.0, 381755.0, 382048.0, 382348.0, 382546.0, 62138.37861621416, 62158.0, 62549.06176407357, 62578.0, 62609.0, 40868.59131408648, 41585.58414415817, 41866.0, 43705.78147109244, 67729.0, 19727.0, 19883.0, 19951.700724489143, 19959.900200499196, 19960.401187964384, 13974.720505590034, 15354.53936381888, 15453.072815631098, 15465.0, 15477.0, 18187.272509099486, 19924.0, 20553.122562841214, 21338.78661213399, 21426.464338391666, 7401.741703910348, 7437.925620743831, 7440.0, 7493.4379921505015, 7748.6439873022, 987.8172538080478, 1019.5259204469957, 1043.7390652336917, 1067.5996501312009, 1112.866456025266, 33491.66508334885, 33723.0, 34025.149371265914, 34220.0, 34249.315013700085, 1127.3968426891574, 1129.7062763681338, 1142.2118738070872, 1209.727811242481, 1228.600704770953, 15650.68698626021, 18762.906185468986, 18980.43058708241, 19674.114664840115, 19745.0, 621853.0, 623390.0, 628985.0, 630895.0, 632353.0, 64834.0, 65146.0, 65376.0, 65380.01929971054, 65452.34547654462, 1332.2339654698587, 1395.1768456610648, 1400.544822932551, 1435.1082568624022, 1466.6773309871812, 33025.83487082549, 34732.65267347395, 35247.47128793019, 35247.647523524436, 35451.64548354483, 53660.46339536554, 54290.18564721453, 54309.18536221881, 54412.45587544074, 54467.182992254355, 439437.0, 443222.0, 444227.0, 444852.0, 581803.0, 44494.0, 46540.0, 46916.53083469121, 47373.52626473829, 48140.27789583229, 51469.742651286506, 51685.7415712919, 51849.0, 52195.47804521906, 52266.0, 43489.0, 43987.0, 45261.77369113265, 45326.773366132955, 45902.0, 43900.780496097315, 44360.0, 44669.97984152656, 45388.31917521306, 45807.77096114498, 44715.776421117684, 45627.0, 45709.54290457053, 46055.76972115118, 46064.309035365164, 36727.26545469129, 39174.216515670094, 39647.60352396439, 39924.60075399325, 40093.59906400899, 29404.558931615615, 29477.557836632037, 29771.85114074416, 30160.396792064475, 30193.245168870955], "value": 22044.24748834842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.38282427885326, 407.50895171318564, 417.6679539765886, 424.9617534421901, 428.09244401868045, 14330.641733956656, 16167.676646467064, 20057.498562535944, 20096.09567569461, 20908.790912090873, 19462.318818841344, 19570.510737231576, 19879.105440255185, 20742.170313187493, 20853.0, 5821.912671309934, 5889.85275368115, 6071.78748743794, 6111.480524155452, 6117.602355846878, 4127.62851343379, 6094.878102437949, 6130.785422510213, 6134.0, 6141.600795948271, 4329.826806927719, 5982.491488223506, 5994.6403215807195, 6078.5441091918065, 6085.665288409135, 6027.487663548582, 6066.423689749467, 6082.0268756998885, 6612.471002319851, 6661.9000714989315, 5988.221531200959, 5995.760169593211, 5998.760049598054, 6006.189164462785, 6010.939890601082, 14507.27463626825, 14563.563093107117, 14836.258187090601, 14925.92537037308, 15020.648141667303, 2382.4758553118286, 2425.745296743834, 2485.5774518331928, 2694.312950197707, 3040.3767227718263, 10662.786744265071, 10724.678259652223, 10881.619143329945, 11064.612738554111, 11227.607033753779, 1775.529484686559, 1959.8922059286733, 2012.1750082466265, 2080.44868109951, 2111.915523379063, 67505.66247168781, 68723.65638171777, 73905.0, 75331.0, 81504.59247703724, 11786.764264714659, 11960.342181180118, 11997.940010299893, 12101.273923564615, 12283.631491055283, 17009.404670836462, 17039.40362087321, 17055.0, 17068.658626827393, 17068.914655426892, 8753.693620723245, 8835.293176545862, 8963.910360896438, 9035.819283614423, 9138.497382643885, 8607.493688604469, 8614.956925215334, 8722.825543489094, 8842.77893052666, 8848.601812918434, 5038.622103342264, 5091.439941606421, 5129.666571672829, 5213.113770658997, 5313.884084342331, 2049.3749406430884, 2129.2228336657317, 2137.6793480977976, 2150.3119001919235, 2169.620316444616, 927.188709878856, 976.7362812040709, 1017.7150397888603, 1074.301703892476, 1084.7776205877776, 20312.18751249963, 20430.489237768878, 20674.0, 20675.0, 20979.370618881152, 989.6684610655511, 1017.4658304390107, 1019.3374306700556, 1019.4393083803878, 1051.4007016000805, 10592.523336449878, 11399.316041037564, 11430.0, 11468.311901285782, 11545.826812597817, 505591.0, 508386.0, 508523.0, 509275.0, 509558.0, 90548.0, 91893.0, 91970.54014730017, 92113.53943230375, 92515.53742231245, 1030.644427672452, 1116.7487315354142, 1150.2408410449232, 1151.3782557419067, 1152.2164927848976, 18005.2797888083, 18282.451526454228, 18577.95769779719, 18891.244350225836, 19038.619227615647, 38470.80764596159, 41505.58494415017, 44182.33726494169, 45249.54750452453, 46215.3067703978, 53771.73114134404, 62372.37627623847, 63877.0, 64113.35886641074, 65033.024504631525, 8679.435836670596, 8970.37207395476, 9050.592723327598, 9106.725058491684, 9196.448213107235, 13107.14803537767, 13128.803067953797, 13450.327483625975, 13790.65523361924, 14108.0, 12422.565210217599, 12545.372731363213, 12698.492060317483, 12936.159149655243, 12937.353132343533, 12041.638750837488, 12207.877921220674, 12226.633201003628, 12291.385430728607, 12834.486620535074, 11787.292762434281, 12407.0, 12439.00487960979, 12624.17942833713, 12736.299503527025, 9090.590923408594, 9560.28297877648, 9582.377145485521, 9601.807963840824, 9684.515774211402, 11392.601258955896, 12611.43248553799, 12651.367431628565, 12955.805662915252, 13143.408546615234], "value": 9582.15594146055, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.8107555480008, 1494.9402023919044, 1527.6715506166177, 1673.4393978017363, 1811.728240763886, 7756.728514501993, 8337.624806883698, 8710.128987101303, 8714.1721536454, 8894.421862578927, 8445.197706217914, 8516.787080322994, 8519.616617252223, 8528.658853645862, 8532.23209911108, 2448.7551244875503, 2474.443250268693, 2476.913308034219, 2477.950440991179, 2478.5166892455977, 2370.845895016827, 2462.495188486356, 2462.495188486365, 2463.8275320727557, 2464.839785413951, 2393.054743376374, 2411.9035238590436, 2442.096424322994, 2450.3751543356507, 2452.31335226137, 2343.203310874311, 2385.1771138957033, 2390.816545809818, 2394.7126344838557, 2423.430493833948, 2358.5872472317446, 2371.1463873005705, 2375.10933399975, 2381.6903802505735, 2381.97618023821, 8311.625976831057, 8659.393842431033, 8707.303415726727, 8712.477251364939, 8739.388242823004, 4940.975295123538, 5109.105906466353, 5118.411382690963, 5122.538971492546, 5122.795088196505, 3378.527006219131, 3525.136341596325, 3540.203454222805, 3570.071781336845, 3583.5699716033982, 1203.9638810835602, 1216.9939150304192, 1239.863615002349, 1247.694314892848, 1251.6808213905397, 11870.643880683594, 11958.043356531456, 11977.760444791142, 11983.340916249603, 11997.160198786089, 4271.658267338606, 4438.467383913951, 4477.977610111929, 4522.163399771053, 4549.0, 9503.382280151767, 9506.0, 9515.809683806423, 9528.142467177988, 9528.380655257386, 5220.504052115043, 5247.02929957959, 5248.029114613809, 5254.448282930313, 5256.369235691665, 4201.33421419953, 4456.22016147173, 4529.796159172845, 4590.288505281686, 4938.308636790852, 3656.1956369598647, 3962.5244970603712, 3979.880603581897, 3989.062570296036, 3996.9200615987515, 1134.183387960667, 1167.9240849344765, 1175.9118066144902, 1200.8979236764974, 1207.2153100484754, 934.3693007220235, 958.9280803939732, 991.6876184002016, 993.8967745802154, 997.0727223682045, 5654.15187721845, 5855.414458554111, 5888.0, 5946.256717910262, 5958.106284057426, 469.2023559948102, 469.2562288772266, 471.3942583779826, 474.1323378217853, 477.2507163752944, 3745.9625403745613, 3806.5812760596314, 3838.673712734393, 3849.172427927953, 3917.745346552465, 10189.898101019044, 10345.068943795095, 10633.361998280128, 10659.733506662396, 10803.83794243087, 10730.463476826128, 10732.409717465458, 10742.78514429707, 10751.569937202581, 10754.623588174376, 8113.959430202929, 8124.715634952749, 8258.463199891989, 8308.54303013328, 8324.334053275723, 5622.240997465454, 5682.516986056232, 5754.6547207167705, 5849.298084229919, 5852.092925596539, 9579.568919398784, 9755.414675119515, 9820.803583928424, 9820.852687209555, 9902.851457228004, 9010.36927415101, 9736.172425343924, 9803.0, 10060.798784024131, 10071.647492337734, 6913.619750913748, 7261.782146535613, 7323.597202153933, 7382.741604043833, 7530.322270995636, 8098.190180981855, 8110.270075693216, 8161.673533058611, 8321.12628174033, 8424.915750842658, 7411.4811963162065, 7525.849483010418, 7569.545827250383, 7629.046369203849, 7636.389088872988, 7404.0, 7436.851262974818, 7490.0, 7536.397088233038, 7712.074551053909, 7183.173934997542, 7195.244499327495, 7280.599567023864, 7286.380657644159, 7289.890651640336, 4899.265110233494, 5019.297298378303, 5026.698398096126, 5051.0, 5187.870303242449, 4872.366592342973, 4966.081274964144, 5016.54851063406, 5049.495050494921, 5276.76254568553], "value": 4501.633281351594, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.6045552376244, 318.8852013275221, 333.5613668026546, 343.33221883436727, 344.1877169879085, 5438.184272359148, 5523.585731070176, 5537.640053396532, 5545.112781954893, 5564.8052318168875, 5102.826349939507, 5227.0, 5258.710770907599, 5385.21914322424, 5407.297051383324, 4252.383404406375, 4256.787160641971, 4260.615300027488, 4280.336547835091, 4282.764447955369, 4278.251306021434, 4283.935740963888, 4300.6989510734265, 4304.117655880537, 4310.633596144332, 4007.73949693269, 4026.255142798592, 4232.407462955189, 4249.617534421902, 4252.447181866369, 4211.536730959592, 4240.15196960606, 4241.133901083529, 4246.789664945476, 4251.447311849455, 3750.2124553843723, 3753.680937120321, 3757.1734218472166, 3777.8855237704943, 3826.885193444173, 5182.97408512955, 5285.233641122054, 5304.602154838364, 5378.731063446812, 5423.511883930426, 4883.438404583483, 4892.926606100911, 4897.51024897511, 4900.803967841282, 4905.386826646669, 5634.3802181759975, 5654.688992105433, 5668.546516278729, 5682.744276507526, 5682.7442765075675, 4100.979495102535, 4137.220994972161, 4140.0891803803415, 4171.6036976487185, 4173.561776013506, 5899.734511946973, 5904.852378690524, 5923.289205295349, 5929.703514824285, 5933.4659880610525, 4258.254805409041, 4284.700070995001, 4303.849365272201, 4311.805968731414, 4328.545502722169, 4005.679545636342, 4175.749455032707, 4215.704900656986, 4362.585554372329, 4379.299312110111, 4795.640326975491, 4808.495108013678, 4824.445188803333, 4827.179379505493, 4836.081144582518, 5647.350554686182, 5715.571332150105, 5726.79956201531, 5746.913796293059, 5758.193852860602, 1340.5777180188209, 1402.4390243902521, 1417.6172433442912, 1420.6007083023214, 1444.1335198880588, 925.0804580495499, 953.6137864165024, 958.9856152157723, 1004.995004995005, 1011.5599714124379, 5611.186377975171, 5632.802851900164, 5656.264685590847, 5665.583604098975, 5695.316562012502, 5964.85087872798, 5977.462028417463, 5983.0, 6011.308699499614, 6020.4780203581395, 5396.946030539723, 5619.157126431068, 5672.376038635747, 5673.716314184274, 5709.001849352693, 3121.609798775153, 3149.755846440689, 3179.4118088153764, 3189.489681650925, 3190.1865024418626, 5849.590528662953, 6086.421789929949, 6134.343727193722, 6150.662748155762, 6190.7523699052435, 6001.2896324548165, 6056.576704474445, 6064.423879731418, 6067.332593414721, 6074.9018000819015, 5820.534357251495, 6145.139680444741, 6179.69101544921, 6183.258009038854, 6208.782692605737, 5842.503387212135, 5882.264716910387, 5896.6756828374855, 5903.91144132847, 5913.905927403444, 5794.9138310207445, 5795.739191736467, 5797.594168408171, 5812.546863284179, 5827.446392592697, 5880.47107751979, 6032.375916476347, 6072.787452439144, 6082.513398928164, 6136.693165341804, 4143.3784932260405, 4183.184279065675, 4207.93688094685, 4217.831286748496, 4230.70385073042, 5881.794137205177, 5906.080523829686, 6010.459058684739, 6027.975244208583, 6033.336333003454, 5790.305163380422, 5791.884162316815, 5797.91303130445, 5811.651300921958, 5830.271216097988, 5705.62913410627, 5720.799772007959, 5732.598718089694, 5752.769889204553, 5777.162311464898, 5259.179695269866, 5274.472552744694, 5595.804146855004, 5720.083771936448, 5742.0, 5698.492663228167, 5702.344230413555, 5703.116017017369, 5711.571632127524, 5730.762119402691, 5468.808591699271, 5579.609427340048, 5585.385607583082, 5617.876424714992, 5621.4659607337235], "value": 4375.062946065763, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.786487648335, 809.6397103289039, 821.0147822612867, 830.9916900830992, 861.3152543727736, 16248.350065997362, 17244.568885777862, 17426.47720568385, 17457.563560910985, 17505.56236094098, 15052.096874187557, 15336.923315383436, 15571.221438928065, 15753.054816711005, 15758.0, 854.0477367734977, 879.7932485865816, 880.4717169698162, 881.9162179592961, 883.1080608585343, 865.0484467086186, 867.3754896474528, 869.6912596028413, 870.1994165367845, 870.899846517649, 927.9072092790734, 928.8978212396664, 930.4696323095839, 930.6882194464862, 931.1293940165932, 917.2111983694051, 919.1819280840058, 927.8283517549207, 928.22029495224, 928.2481190235897, 680.4692339974778, 680.6868840333452, 680.9557378770413, 680.9897851532231, 681.3118750062473, 16719.0, 16749.162541872858, 16817.66364672712, 16846.578835529086, 16857.24142413582, 14696.779548306677, 15328.386864525406, 16162.676746465006, 16166.191690415431, 16649.25078371476, 13011.0, 13127.671808204872, 13164.473421063147, 13263.668408289774, 13432.664183395396, 3964.365701487748, 3992.4210989406665, 4018.9799051004557, 4035.152617950205, 4092.9181416371507, 48282.0, 56886.0, 71453.28546714518, 81605.0, 84308.0, 12473.93763031179, 12603.18079324841, 12880.162789418659, 12921.0, 13179.07746457758, 22738.43153921132, 23083.307500774667, 23265.418364541023, 23281.534369312518, 23484.647730284058, 9036.683716069905, 9100.908990910139, 9158.725238242863, 9200.0, 9275.304352173614, 4917.852464426073, 5205.453427390145, 5244.082285600081, 5278.683279003195, 5346.599005074635, 3454.516367708523, 3601.1177261571083, 3622.836972336251, 3631.1103779574173, 3639.490471334015, 619.306376857913, 620.4756980351596, 633.467886974934, 635.5170070746174, 637.3403527349265, 612.7426480878042, 734.6547122852251, 758.12058012705, 773.4547144263281, 778.661282342183, 9991.950040249752, 10071.59713611462, 10255.641052563124, 10269.948650256702, 10480.895191047992, 713.3223437734069, 784.5802495664792, 799.3485309472877, 799.672134424883, 799.8240387114826, 4597.839075632403, 4615.14619795339, 4628.236341003764, 4629.560191781776, 4716.056788642217, 72161.0, 89257.55371223102, 112314.43842780734, 130554.0, 135746.32126839302, 79653.60173199097, 119630.0, 121835.0, 122175.0, 122659.38670306592, 58078.70960645254, 60874.39125608776, 82921.0, 104841.0, 104968.99999999847, 8991.865122023306, 9088.409253398508, 9246.95376523113, 9395.201407880133, 9564.66523671668, 60936.0, 60950.390496096246, 62229.0, 62753.293502988374, 63703.0, 75575.0, 76108.61945690236, 77512.34925904464, 79505.20494794978, 82375.0, 14001.859981400463, 14013.019088664103, 14388.352524136648, 14570.708585828435, 14717.484888028868, 15591.688166236838, 15947.840521594635, 16327.265273062503, 16359.263833127776, 16788.664226715155, 14778.113313201242, 14937.850621493646, 15497.380104796133, 15932.522024339289, 16415.507534773977, 14842.183679897278, 15011.549653510412, 15708.293126809092, 16339.754903676216, 16393.42623008189, 12769.61691149267, 13327.333633318489, 13981.161130332213, 14022.509212177525, 14117.43530258778, 11626.76746465083, 11769.117316201147, 12053.57812476559, 12141.575044873387, 12511.812322814983, 12393.194442361219, 12424.49020045088, 12809.167404118702, 12893.613191604267, 12919.741605167656], "value": 8065.302745375645, "unit": "score"}], "name": "dromaeo_css", "value": 7505.699196731111, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 112.0, 112.0, 113.0, 113.0, 114.0, 112.0, 112.0, 113.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 117.0, 119.0, 142.0, 121.0, 118.0, 117.0, 116.0, 155.0, 117.0], "value": 126.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 160.0, 160.0, 160.0, 159.0, 159.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 118.0, 76.0, 76.0, 76.0, 77.0, 103.0, 75.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 112.0, 82.0, 83.0, 82.0, 110.0, 82.0, 82.0, 111.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 147.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 51.0, 52.0, 50.0, 50.0, 50.0, 52.0, 52.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 59.0, 58.0, 57.0, 57.0, 58.0, 58.0, 57.0, 57.0], "value": 57.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 75.0, 74.0, 75.0, 74.0, 80.0, 73.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 120.0, 122.0, 128.0, 131.0, 126.0, 118.0, 126.0, 156.0, 124.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 156.0, 155.0, 153.0, 154.0, 150.0, 155.0, 152.0, 151.0, 156.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 63.0, 62.0, 62.0, 63.0, 62.0, 62.0, 61.0, 60.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1457.3999999999999, "alertThreshold": 2.0}]} 09:11:37 INFO - Return code: 0 09:11:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:11:37 INFO - # TBPL SUCCESS # 09:11:37 INFO - Running post-action listener: _resource_record_post_action 09:11:37 INFO - [mozharness: 2016-10-05 16:11:37.488563Z] Finished run-tests step (success) 09:11:37 INFO - Running post-run listener: _resource_record_post_run 09:11:37 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 363311104; Read time: 64; Write time: 260392 09:11:37 INFO - TinderboxPrint: CPU usage
12.9% 09:11:37 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 64 09:11:37 INFO - TinderboxPrint: I/O write bytes / time
363,311,104 / 260,392 09:11:37 INFO - TinderboxPrint: CPU idle
8,265.0 (87.1%) 09:11:37 INFO - TinderboxPrint: CPU user
1,182.5 (12.5%) 09:11:37 INFO - TinderboxPrint: Swap in / out
0 / 0 09:11:37 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107630592; Read time: 0; Write time: 103268 09:11:37 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 243720192; Read time: 16; Write time: 154756 09:11:38 INFO - Running post-run listener: _upload_blobber_files 09:11:38 INFO - Blob upload gear active. 09:11:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:11:38 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 09:11:38 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'] 09:11:38 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 09:11:38 INFO - (blobuploader) - INFO - Open directory for files ... 09:11:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:11:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:11:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:39 INFO - (blobuploader) - INFO - Done attempting. 09:11:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:11:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:11:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:39 INFO - (blobuploader) - INFO - Done attempting. 09:11:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:11:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:11:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:43 INFO - (blobuploader) - INFO - Done attempting. 09:11:43 INFO - (blobuploader) - INFO - Iteration through files over. 09:11:43 INFO - Return code: 0 09:11:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:11:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:11:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/97f9227cc5dd8cec91a75c156516e07e7b03c3ea0b837d3c57c32faf094d0536ce138297dd9f3079e490a3f0e77dcbc68fa62d1b07ad4e63746752b0ac2140f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b04297fb85e9e04db028b9f037e89953c952b6fa7890015089595508b62eda04ba95aa104104517fafcc43e7cdd3a86618fa0cdf8cf9514459c9c0efd638f5b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bce3dd997a74078ce15ddf29a1244c7f5743c392fc793b2ad74b879cf982e5baeef998ce51cd5c160f6f64dc604a1f69edd4249d39a7391cda22a3ebf43cbb94"} 09:11:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:11:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:11:43 INFO - Contents: 09:11:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/97f9227cc5dd8cec91a75c156516e07e7b03c3ea0b837d3c57c32faf094d0536ce138297dd9f3079e490a3f0e77dcbc68fa62d1b07ad4e63746752b0ac2140f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b04297fb85e9e04db028b9f037e89953c952b6fa7890015089595508b62eda04ba95aa104104517fafcc43e7cdd3a86618fa0cdf8cf9514459c9c0efd638f5b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bce3dd997a74078ce15ddf29a1244c7f5743c392fc793b2ad74b879cf982e5baeef998ce51cd5c160f6f64dc604a1f69edd4249d39a7391cda22a3ebf43cbb94"} 09:11:43 INFO - Running post-run listener: copy_logs_to_upload_dir 09:11:43 INFO - Copying logs to upload dir... 09:11:43 INFO - mkdir: /builds/slave/test/build/upload/logs 09:11:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.705325 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-10-05 09:11:43.129956) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 09:11:43.133121) ========= 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=652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577 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/97f9227cc5dd8cec91a75c156516e07e7b03c3ea0b837d3c57c32faf094d0536ce138297dd9f3079e490a3f0e77dcbc68fa62d1b07ad4e63746752b0ac2140f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b04297fb85e9e04db028b9f037e89953c952b6fa7890015089595508b62eda04ba95aa104104517fafcc43e7cdd3a86618fa0cdf8cf9514459c9c0efd638f5b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bce3dd997a74078ce15ddf29a1244c7f5743c392fc793b2ad74b879cf982e5baeef998ce51cd5c160f6f64dc604a1f69edd4249d39a7391cda22a3ebf43cbb94"} build_url:https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011279 build_url: 'https://queue.taskcluster.net/v1/task/UMm1iWijSqOuRJ6rllOF-Q/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/97f9227cc5dd8cec91a75c156516e07e7b03c3ea0b837d3c57c32faf094d0536ce138297dd9f3079e490a3f0e77dcbc68fa62d1b07ad4e63746752b0ac2140f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b04297fb85e9e04db028b9f037e89953c952b6fa7890015089595508b62eda04ba95aa104104517fafcc43e7cdd3a86618fa0cdf8cf9514459c9c0efd638f5b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bce3dd997a74078ce15ddf29a1244c7f5743c392fc793b2ad74b879cf982e5baeef998ce51cd5c160f6f64dc604a1f69edd4249d39a7391cda22a3ebf43cbb94"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 09:11:43.160448) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:11:43.161345) ========= 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=652eb03db935cfc1c15ebda0000001ad-1475681723.628551-1515006577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 09:11:43.246358) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 09:11:43.247087) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 09:11:43.247765) ========= ========= Total master_lag: 0.21 =========