builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1476975118.92 results: success (0) buildid: 20161020050549 builduid: 9a834c62dddb4b04a0dce382e7899f96 revision: 0895f2ef1268cb87b507dfa1e4d7633621215264 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:58.916245) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:58.916766) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:58.917188) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:58.932992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:58.933361) ========= 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=af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-20 07:51:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-10-20 07:51:59 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.178477 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:59.126076) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:59.126458) ========= 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=af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015509 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:59.176347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:59.176741) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0895f2ef1268cb87b507dfa1e4d7633621215264 --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 0895f2ef1268cb87b507dfa1e4d7633621215264 --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=af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-20 07:51:59,191 truncating revision to first 12 chars 2016-10-20 07:51:59,191 Setting DEBUG logging. 2016-10-20 07:51:59,191 attempt 1/10 2016-10-20 07:51:59,191 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0895f2ef1268?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 07:51:59,492 unpacking tar archive at: mozilla-aurora-0895f2ef1268/testing/mozharness/ program finished with exit code 0 elapsedTime=0.659920 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:59.850117) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:59.850462) ========= script_repo_revision: 0895f2ef1268cb87b507dfa1e4d7633621215264 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:59.850865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:59.851171) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 07:51:59.863431) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-20 07:51:59.863775) ========= /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=af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:51:59 INFO - MultiFileLogger online at 20161020 07:51:59 in /builds/slave/test 07:51:59 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 07:51:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:51:59 INFO - {'append_to_log': False, 07:51:59 INFO - 'base_work_dir': '/builds/slave/test', 07:51:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:51:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:51:59 INFO - 'branch': 'Mozilla-Aurora', 07:51:59 INFO - 'buildbot_json_path': 'buildprops.json', 07:51:59 INFO - 'config_files': ('talos/linux_config.py',), 07:51:59 INFO - 'default_actions': ('clobber', 07:51:59 INFO - 'read-buildbot-config', 07:51:59 INFO - 'download-and-extract', 07:51:59 INFO - 'populate-webroot', 07:51:59 INFO - 'create-virtualenv', 07:51:59 INFO - 'install', 07:51:59 INFO - 'run-tests'), 07:51:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:51:59 INFO - 'download_minidump_stackwalk': True, 07:51:59 INFO - 'download_symbols': 'ondemand', 07:51:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:51:59 INFO - 'tooltool.py': '/tools/tooltool.py', 07:51:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:51:59 INFO - '/tools/misc-python/virtualenv.py')}, 07:51:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:51:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:51:59 INFO - 'installer_path': 'installer.exe', 07:51:59 INFO - 'log_level': 'info', 07:51:59 INFO - 'log_name': 'talos', 07:51:59 INFO - 'log_to_console': True, 07:51:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:51:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:51:59 INFO - 'opt_config_files': (), 07:51:59 INFO - 'pip_index': False, 07:51:59 INFO - 'sps_profile': False, 07:51:59 INFO - 'sps_profile_interval': 0, 07:51:59 INFO - 'suite': 'dromaeojs', 07:51:59 INFO - 'system_bits': '32', 07:51:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:51:59 INFO - 'title': 'talos-linux64-ix-020', 07:51:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:51:59 INFO - 'use_talos_json': True, 07:51:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:51:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:51:59 INFO - 'work_dir': 'build'} 07:51:59 INFO - [mozharness: 2016-10-20 14:51:59.975218Z] Running clobber step. 07:51:59 INFO - Running pre-action listener: _resource_record_pre_action 07:51:59 INFO - Running main action method: clobber 07:51:59 INFO - rmtree: /builds/slave/test/build 07:51:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:52:00 INFO - Running post-action listener: _resource_record_post_action 07:52:00 INFO - [mozharness: 2016-10-20 14:52:00.432600Z] Finished clobber step (success) 07:52:00 INFO - [mozharness: 2016-10-20 14:52:00.432699Z] Running read-buildbot-config step. 07:52:00 INFO - Running pre-action listener: _resource_record_pre_action 07:52:00 INFO - Running main action method: read_buildbot_config 07:52:00 INFO - Using buildbot properties: 07:52:00 INFO - { 07:52:00 INFO - "project": "", 07:52:00 INFO - "product": "firefox", 07:52:00 INFO - "script_repo_revision": "production", 07:52:00 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:52:00 INFO - "repository": "", 07:52:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:52:00 INFO - "buildid": "20161020050549", 07:52:00 INFO - "pgo_build": "True", 07:52:00 INFO - "basedir": "/builds/slave/test", 07:52:00 INFO - "buildnumber": 534, 07:52:00 INFO - "slavename": "talos-linux64-ix-020", 07:52:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:52:00 INFO - "platform": "ubuntu64_hw", 07:52:00 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:52:00 INFO - "branch": "mozilla-aurora", 07:52:00 INFO - "repo_path": "releases/mozilla-aurora", 07:52:00 INFO - "revision": "0895f2ef1268cb87b507dfa1e4d7633621215264", 07:52:00 INFO - "stage_platform": "linux64-pgo", 07:52:00 INFO - "builduid": "9a834c62dddb4b04a0dce382e7899f96", 07:52:00 INFO - "slavebuilddir": "test" 07:52:00 INFO - } 07:52:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 07:52:00 INFO - Running post-action listener: _resource_record_post_action 07:52:00 INFO - [mozharness: 2016-10-20 14:52:00.435141Z] Finished read-buildbot-config step (success) 07:52:00 INFO - [mozharness: 2016-10-20 14:52:00.435231Z] Running download-and-extract step. 07:52:00 INFO - Running pre-action listener: _resource_record_pre_action 07:52:00 INFO - Running main action method: download_and_extract 07:52:00 INFO - mkdir: /builds/slave/test/build/tests 07:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:00 INFO - trying https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:52:00 INFO - Downloading https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/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 07:52:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/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 07:52:00 INFO - Downloaded 1361 bytes. 07:52:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:52:00 INFO - Using the following test package requirements: 07:52:00 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:52:00 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:52:00 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:52:00 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:52:00 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:52:00 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:52:00 INFO - u'jsshell-linux-x86_64.zip'], 07:52:00 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:52:00 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:52:00 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:52:00 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:52:00 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:52:00 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:52:00 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:52:00 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:52:00 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:52:00 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:52:00 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:52:00 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:52:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 07:52:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:52:00 INFO - Fetch https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 07:52:01 INFO - Expected file size: 21908324 07:52:05 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 07:52:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 07:52:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:52:05 INFO - Fetch https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 07:52:06 INFO - Expected file size: 21908324 07:52:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 07:52:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:52:11 INFO - Fetch https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 07:52:11 INFO - Expected file size: 14414081 07:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:12 INFO - trying https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:52:12 INFO - Downloading https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/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 07:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/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 07:52:14 INFO - Downloaded 64025075 bytes. 07:52:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:52:14 INFO - mkdir: /builds/slave/test/properties 07:52:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:52:14 INFO - Writing to file /builds/slave/test/properties/build_url 07:52:14 INFO - Contents: 07:52:14 INFO - build_url:https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:52:15 INFO - Running post-action listener: _resource_record_post_action 07:52:15 INFO - Running post-action listener: set_extra_try_arguments 07:52:15 INFO - [mozharness: 2016-10-20 14:52:15.497821Z] Finished download-and-extract step (success) 07:52:15 INFO - [mozharness: 2016-10-20 14:52:15.498047Z] Running populate-webroot step. 07:52:15 INFO - Running pre-action listener: _resource_record_pre_action 07:52:15 INFO - Running main action method: populate_webroot 07:52:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:52:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:52:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:52:15 INFO - '--tppagecycles', 07:52:15 INFO - '7'], 07:52:15 INFO - 'tests': ['tsvgm']}}, 07:52:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:52:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:52:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:52:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:52:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:15 INFO - 'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:15 INFO - 'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['damp', 'tps']}, 07:52:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:15 INFO - 'tests': ['damp', 'tps']}, 07:52:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['dromaeo_dom']}, 07:52:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:52:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['basic_compositor_video']}, 07:52:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:52:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['a11yr', 07:52:15 INFO - 'ts_paint', 07:52:15 INFO - 'tpaint', 07:52:15 INFO - 'sessionrestore', 07:52:15 INFO - 'sessionrestore_no_auto_restore', 07:52:15 INFO - 'tabpaint']}, 07:52:15 INFO - 'other-e10s': {'tests': ['a11yr', 07:52:15 INFO - 'ts_paint', 07:52:15 INFO - 'tpaint', 07:52:15 INFO - 'sessionrestore', 07:52:15 INFO - 'sessionrestore_no_auto_restore', 07:52:15 INFO - 'tabpaint']}, 07:52:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:52:15 INFO - 'ts_paint', 07:52:15 INFO - 'tpaint', 07:52:15 INFO - 'sessionrestore', 07:52:15 INFO - 'sessionrestore_no_auto_restore', 07:52:15 INFO - 'tabpaint']}, 07:52:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:52:15 INFO - 'ts_paint', 07:52:15 INFO - 'tpaint', 07:52:15 INFO - 'sessionrestore', 07:52:15 INFO - 'sessionrestore_no_auto_restore', 07:52:15 INFO - 'tabpaint']}, 07:52:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['a11yr', 07:52:15 INFO - 'ts_paint', 07:52:15 INFO - 'tpaint', 07:52:15 INFO - 'sessionrestore', 07:52:15 INFO - 'sessionrestore_no_auto_restore', 07:52:15 INFO - 'tabpaint']}, 07:52:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['a11yr', 07:52:15 INFO - 'ts_paint', 07:52:15 INFO - 'tpaint', 07:52:15 INFO - 'sessionrestore', 07:52:15 INFO - 'sessionrestore_no_auto_restore', 07:52:15 INFO - 'tabpaint']}, 07:52:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['tsvgx', 07:52:15 INFO - 'tsvgr_opacity', 07:52:15 INFO - 'tart', 07:52:15 INFO - 'tscrollx', 07:52:15 INFO - 'cart']}, 07:52:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:52:15 INFO - 'tsvgr_opacity', 07:52:15 INFO - 'tart', 07:52:15 INFO - 'tscrollx', 07:52:15 INFO - 'cart']}, 07:52:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:15 INFO - 'talos_options': ['--disable-e10s'], 07:52:15 INFO - 'tests': ['tp5o']}, 07:52:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:15 INFO - 'tests': ['tp5o']}, 07:52:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:15 INFO - 'talos_options': ['--disable-e10s', 07:52:15 INFO - '--xperf_path', 07:52:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:15 INFO - 'tests': ['tp5n']}, 07:52:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:52:15 INFO - 'talos_options': ['--xperf_path', 07:52:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:15 INFO - 'tests': ['tp5n']}}, 07:52:15 INFO - 'talos.zip': {'path': '', 07:52:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:52:15 INFO - Running post-action listener: _resource_record_post_action 07:52:15 INFO - [mozharness: 2016-10-20 14:52:15.513463Z] Finished populate-webroot step (success) 07:52:15 INFO - [mozharness: 2016-10-20 14:52:15.513551Z] Running create-virtualenv step. 07:52:15 INFO - Running pre-action listener: _resource_record_pre_action 07:52:15 INFO - Running main action method: create_virtualenv 07:52:15 INFO - Creating virtualenv /builds/slave/test/build/venv 07:52:15 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 07:52:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:52:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:52:15 INFO - Using real prefix '/usr' 07:52:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:52:16 INFO - Installing distribute.............................................................................................................................................................................................done. 07:52:18 INFO - Installing pip.................done. 07:52:18 INFO - Return code: 0 07:52:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:52:18 INFO - Using env: {'DISPLAY': ':0', 07:52:18 INFO - 'HOME': '/home/cltbld', 07:52:18 INFO - 'LANG': 'en_US.UTF-8', 07:52:18 INFO - 'LANGUAGE': 'en_US:en', 07:52:18 INFO - 'LOGNAME': 'cltbld', 07:52:18 INFO - 'MAIL': '/var/mail/cltbld', 07:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:18 INFO - 'MOZ_NO_REMOTE': '1', 07:52:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:18 INFO - 'NO_EM_RESTART': '1', 07:52:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:18 INFO - 'PWD': '/builds/slave/test', 07:52:18 INFO - 'SHELL': '/bin/bash', 07:52:18 INFO - 'SHLVL': '1', 07:52:18 INFO - 'TERM': 'linux', 07:52:18 INFO - 'TMOUT': '86400', 07:52:18 INFO - 'USER': 'cltbld', 07:52:18 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:18 INFO - '_': '/tools/buildbot/bin/python'} 07:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:18 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 07:52:18 INFO - Cleaning up... 07:52:18 INFO - Return code: 0 07:52:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:52:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:52:18 INFO - Using env: {'DISPLAY': ':0', 07:52:18 INFO - 'HOME': '/home/cltbld', 07:52:18 INFO - 'LANG': 'en_US.UTF-8', 07:52:18 INFO - 'LANGUAGE': 'en_US:en', 07:52:18 INFO - 'LOGNAME': 'cltbld', 07:52:18 INFO - 'MAIL': '/var/mail/cltbld', 07:52:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:18 INFO - 'MOZ_NO_REMOTE': '1', 07:52:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:18 INFO - 'NO_EM_RESTART': '1', 07:52:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:18 INFO - 'PWD': '/builds/slave/test', 07:52:18 INFO - 'SHELL': '/bin/bash', 07:52:18 INFO - 'SHLVL': '1', 07:52:18 INFO - 'TERM': 'linux', 07:52:18 INFO - 'TMOUT': '86400', 07:52:18 INFO - 'USER': 'cltbld', 07:52:18 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:18 INFO - '_': '/tools/buildbot/bin/python'} 07:52:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:18 INFO - Downloading/unpacking psutil>=3.1.1 07:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:52:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:20 INFO - Installing collected packages: psutil 07:52:20 INFO - Running setup.py install for psutil 07:52:20 INFO - building 'psutil._psutil_linux' extension 07:52:20 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 07:52:20 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 07:52:20 INFO - building 'psutil._psutil_posix' extension 07:52:20 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 07:52:20 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 07:52:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:21 INFO - Successfully installed psutil 07:52:21 INFO - Cleaning up... 07:52:21 INFO - Return code: 0 07:52:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:52:21 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')]} 07:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:21 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')]} 07:52:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:21 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 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:21 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 07:52:21 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 07:52:21 INFO - Using env: {'DISPLAY': ':0', 07:52:21 INFO - 'HOME': '/home/cltbld', 07:52:21 INFO - 'LANG': 'en_US.UTF-8', 07:52:21 INFO - 'LANGUAGE': 'en_US:en', 07:52:21 INFO - 'LOGNAME': 'cltbld', 07:52:21 INFO - 'MAIL': '/var/mail/cltbld', 07:52:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:21 INFO - 'MOZ_NO_REMOTE': '1', 07:52:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:21 INFO - 'NO_EM_RESTART': '1', 07:52:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:21 INFO - 'PWD': '/builds/slave/test', 07:52:21 INFO - 'SHELL': '/bin/bash', 07:52:21 INFO - 'SHLVL': '1', 07:52:21 INFO - 'TERM': 'linux', 07:52:21 INFO - 'TMOUT': '86400', 07:52:21 INFO - 'USER': 'cltbld', 07:52:21 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:21 INFO - '_': '/tools/buildbot/bin/python'} 07:52:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:52:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:52:23 INFO - Installing collected packages: mozsystemmonitor 07:52:23 INFO - Running setup.py install for mozsystemmonitor 07:52:23 INFO - Successfully installed mozsystemmonitor 07:52:23 INFO - Cleaning up... 07:52:23 INFO - Return code: 0 07:52:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:23 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 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:23 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 07:52:23 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 07:52:23 INFO - Using env: {'DISPLAY': ':0', 07:52:23 INFO - 'HOME': '/home/cltbld', 07:52:23 INFO - 'LANG': 'en_US.UTF-8', 07:52:23 INFO - 'LANGUAGE': 'en_US:en', 07:52:23 INFO - 'LOGNAME': 'cltbld', 07:52:23 INFO - 'MAIL': '/var/mail/cltbld', 07:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:23 INFO - 'MOZ_NO_REMOTE': '1', 07:52:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:23 INFO - 'NO_EM_RESTART': '1', 07:52:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:23 INFO - 'PWD': '/builds/slave/test', 07:52:23 INFO - 'SHELL': '/bin/bash', 07:52:23 INFO - 'SHLVL': '1', 07:52:23 INFO - 'TERM': 'linux', 07:52:23 INFO - 'TMOUT': '86400', 07:52:23 INFO - 'USER': 'cltbld', 07:52:23 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:23 INFO - '_': '/tools/buildbot/bin/python'} 07:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:23 INFO - Downloading/unpacking jsonschema==2.5.1 07:52:23 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 07:52:23 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 07:52:23 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 07:52:23 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 07:52:23 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 07:52:23 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 07:52:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:52:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:52:25 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 07:52:25 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 07:52:25 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 07:52:25 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 07:52:25 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 07:52:25 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 07:52:25 INFO - Downloading functools32-3.2.3-2.tar.gz 07:52:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:52:25 INFO - warning: no files found matching '*.txt' 07:52:25 INFO - no previously-included directories found matching 'build' 07:52:25 INFO - no previously-included directories found matching 'dist' 07:52:25 INFO - no previously-included directories found matching '.git*' 07:52:25 INFO - Installing collected packages: jsonschema, functools32 07:52:25 INFO - Running setup.py install for functools32 07:52:25 INFO - warning: no files found matching '*.txt' 07:52:25 INFO - no previously-included directories found matching 'build' 07:52:25 INFO - no previously-included directories found matching 'dist' 07:52:25 INFO - no previously-included directories found matching '.git*' 07:52:25 INFO - Successfully installed jsonschema functools32 07:52:25 INFO - Cleaning up... 07:52:26 INFO - Return code: 0 07:52:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:52:26 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')]} 07:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 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')]} 07:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 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 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:26 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 07:52:26 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 07:52:26 INFO - Using env: {'DISPLAY': ':0', 07:52:26 INFO - 'HOME': '/home/cltbld', 07:52:26 INFO - 'LANG': 'en_US.UTF-8', 07:52:26 INFO - 'LANGUAGE': 'en_US:en', 07:52:26 INFO - 'LOGNAME': 'cltbld', 07:52:26 INFO - 'MAIL': '/var/mail/cltbld', 07:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:26 INFO - 'MOZ_NO_REMOTE': '1', 07:52:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:26 INFO - 'NO_EM_RESTART': '1', 07:52:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:26 INFO - 'PWD': '/builds/slave/test', 07:52:26 INFO - 'SHELL': '/bin/bash', 07:52:26 INFO - 'SHLVL': '1', 07:52:26 INFO - 'TERM': 'linux', 07:52:26 INFO - 'TMOUT': '86400', 07:52:26 INFO - 'USER': 'cltbld', 07:52:26 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:26 INFO - '_': '/tools/buildbot/bin/python'} 07:52:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:52:26 INFO - Cleaning up... 07:52:26 INFO - Return code: 0 07:52:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:52:26 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')]} 07:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 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')]} 07:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 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 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:26 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 07:52:26 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 07:52:26 INFO - Using env: {'DISPLAY': ':0', 07:52:26 INFO - 'HOME': '/home/cltbld', 07:52:26 INFO - 'LANG': 'en_US.UTF-8', 07:52:26 INFO - 'LANGUAGE': 'en_US:en', 07:52:26 INFO - 'LOGNAME': 'cltbld', 07:52:26 INFO - 'MAIL': '/var/mail/cltbld', 07:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:26 INFO - 'MOZ_NO_REMOTE': '1', 07:52:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:26 INFO - 'NO_EM_RESTART': '1', 07:52:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:26 INFO - 'PWD': '/builds/slave/test', 07:52:26 INFO - 'SHELL': '/bin/bash', 07:52:26 INFO - 'SHLVL': '1', 07:52:26 INFO - 'TERM': 'linux', 07:52:26 INFO - 'TMOUT': '86400', 07:52:26 INFO - 'USER': 'cltbld', 07:52:26 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:26 INFO - '_': '/tools/buildbot/bin/python'} 07:52:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:26 INFO - Downloading/unpacking blobuploader==1.2.4 07:52:26 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 07:52:26 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 07:52:26 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 07:52:26 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 07:52:26 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 07:52:26 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 07:52:28 INFO - Downloading blobuploader-1.2.4.tar.gz 07:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:52:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:52:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52: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 07:52:29 INFO - Downloading docopt-0.6.1.tar.gz 07:52:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:52:29 INFO - Installing collected packages: blobuploader, requests, docopt 07:52:29 INFO - Running setup.py install for blobuploader 07:52:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:52:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:52:29 INFO - Running setup.py install for requests 07:52:29 INFO - Running setup.py install for docopt 07:52:29 INFO - Successfully installed blobuploader requests docopt 07:52:29 INFO - Cleaning up... 07:52:29 INFO - Return code: 0 07:52:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:29 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 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:29 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 07:52:29 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 07:52:29 INFO - Using env: {'DISPLAY': ':0', 07:52:29 INFO - 'HOME': '/home/cltbld', 07:52:29 INFO - 'LANG': 'en_US.UTF-8', 07:52:29 INFO - 'LANGUAGE': 'en_US:en', 07:52:29 INFO - 'LOGNAME': 'cltbld', 07:52:29 INFO - 'MAIL': '/var/mail/cltbld', 07:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:29 INFO - 'MOZ_NO_REMOTE': '1', 07:52:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:29 INFO - 'NO_EM_RESTART': '1', 07:52:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:29 INFO - 'PWD': '/builds/slave/test', 07:52:29 INFO - 'SHELL': '/bin/bash', 07:52:29 INFO - 'SHLVL': '1', 07:52:29 INFO - 'TERM': 'linux', 07:52:29 INFO - 'TMOUT': '86400', 07:52:29 INFO - 'USER': 'cltbld', 07:52:29 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:29 INFO - '_': '/tools/buildbot/bin/python'} 07:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:29 INFO - Running setup.py (path:/tmp/pip-BzDjUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:30 INFO - Running setup.py (path:/tmp/pip-p47L0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:30 INFO - Running setup.py (path:/tmp/pip-p6Cst8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:30 INFO - Running setup.py (path:/tmp/pip-q61MT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:30 INFO - Running setup.py (path:/tmp/pip-gYgHcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:30 INFO - Running setup.py (path:/tmp/pip-dYKztu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:30 INFO - Running setup.py (path:/tmp/pip-hJpqKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:30 INFO - Running setup.py (path:/tmp/pip-emCC1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:30 INFO - Running setup.py (path:/tmp/pip-16xOzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:30 INFO - Running setup.py (path:/tmp/pip-uYHyki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:30 INFO - Running setup.py (path:/tmp/pip-02mDox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:30 INFO - Running setup.py (path:/tmp/pip-YuHG9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:30 INFO - Running setup.py (path:/tmp/pip-AvLyiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:30 INFO - Running setup.py (path:/tmp/pip-dhq7yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:30 INFO - Running setup.py (path:/tmp/pip-3uH6hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:31 INFO - Running setup.py (path:/tmp/pip-i1Cf5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:31 INFO - Running setup.py (path:/tmp/pip-cHhv6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:52:31 INFO - Running setup.py install for manifestparser 07:52:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:52:31 INFO - Running setup.py install for mozcrash 07:52:31 INFO - Running setup.py install for mozdebug 07:52:31 INFO - Running setup.py install for mozdevice 07:52:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:52:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:52:31 INFO - Running setup.py install for mozfile 07:52:31 INFO - Running setup.py install for mozhttpd 07:52:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:52:31 INFO - Running setup.py install for mozinfo 07:52:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:52:31 INFO - Running setup.py install for mozInstall 07:52:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:52:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:52:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:52:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:52:32 INFO - Running setup.py install for mozleak 07:52:32 INFO - Running setup.py install for mozlog 07:52:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:52:32 INFO - Running setup.py install for moznetwork 07:52:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:52:32 INFO - Running setup.py install for mozprocess 07:52:32 INFO - Running setup.py install for mozprofile 07:52:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:52:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:52:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:52:32 INFO - Running setup.py install for mozrunner 07:52:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:52:32 INFO - Running setup.py install for mozscreenshot 07:52:32 INFO - Running setup.py install for moztest 07:52:32 INFO - Running setup.py install for mozversion 07:52:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:52:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:52:32 INFO - Cleaning up... 07:52:32 INFO - Return code: 0 07:52:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:32 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')]} 07:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:32 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')]} 07:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:32 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 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:32 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 07:52:32 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 07:52:33 INFO - Using env: {'DISPLAY': ':0', 07:52:33 INFO - 'HOME': '/home/cltbld', 07:52:33 INFO - 'LANG': 'en_US.UTF-8', 07:52:33 INFO - 'LANGUAGE': 'en_US:en', 07:52:33 INFO - 'LOGNAME': 'cltbld', 07:52:33 INFO - 'MAIL': '/var/mail/cltbld', 07:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:33 INFO - 'MOZ_NO_REMOTE': '1', 07:52:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:33 INFO - 'NO_EM_RESTART': '1', 07:52:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:33 INFO - 'PWD': '/builds/slave/test', 07:52:33 INFO - 'SHELL': '/bin/bash', 07:52:33 INFO - 'SHLVL': '1', 07:52:33 INFO - 'TERM': 'linux', 07:52:33 INFO - 'TMOUT': '86400', 07:52:33 INFO - 'USER': 'cltbld', 07:52:33 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:33 INFO - '_': '/tools/buildbot/bin/python'} 07:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:33 INFO - Running setup.py (path:/tmp/pip-ngegZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:33 INFO - Running setup.py (path:/tmp/pip-ZQL0iW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:33 INFO - Running setup.py (path:/tmp/pip-wmqGEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:33 INFO - Running setup.py (path:/tmp/pip-PiNVmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:33 INFO - Running setup.py (path:/tmp/pip-Sp5IgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:33 INFO - Running setup.py (path:/tmp/pip-0_xlcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:33 INFO - Running setup.py (path:/tmp/pip-gazGTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:33 INFO - Running setup.py (path:/tmp/pip-FVaHp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:33 INFO - Running setup.py (path:/tmp/pip-9RnMfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:33 INFO - Running setup.py (path:/tmp/pip-eVsCdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:33 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)) 07:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:33 INFO - Running setup.py (path:/tmp/pip-5ukQfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:34 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)) 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:34 INFO - Running setup.py (path:/tmp/pip-kShYCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:34 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)) 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:34 INFO - Running setup.py (path:/tmp/pip-eImcAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:34 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)) 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:34 INFO - Running setup.py (path:/tmp/pip-AFbwrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:34 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)) 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:34 INFO - Running setup.py (path:/tmp/pip-b_XUMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:34 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)) 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:34 INFO - Running setup.py (path:/tmp/pip-i1_nec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:34 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)) 07:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:34 INFO - Running setup.py (path:/tmp/pip-rIGZfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:34 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)) 07:52:34 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)) 07:52:34 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)) 07:52:34 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)) 07:52:34 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)) 07:52:34 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)) 07:52:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:36 INFO - Downloading blessings-1.6.tar.gz 07:52:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:52:36 INFO - Installing collected packages: blessings 07:52:36 INFO - Running setup.py install for blessings 07:52:36 INFO - Successfully installed blessings 07:52:36 INFO - Cleaning up... 07:52:36 INFO - Return code: 0 07:52:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:52:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:36 INFO - Reading from file tmpfile_stdout 07:52:36 INFO - Current package versions: 07:52:36 INFO - argparse == 1.2.1 07:52:36 INFO - blessings == 1.6 07:52:36 INFO - blobuploader == 1.2.4 07:52:36 INFO - docopt == 0.6.1 07:52:36 INFO - functools32 == 3.2.3-2 07:52:36 INFO - jsonschema == 2.5.1 07:52:36 INFO - manifestparser == 1.1 07:52:36 INFO - mozInstall == 1.12 07:52:36 INFO - mozcrash == 0.17 07:52:36 INFO - mozdebug == 0.1 07:52:36 INFO - mozdevice == 0.48 07:52:36 INFO - mozfile == 1.2 07:52:36 INFO - mozhttpd == 0.7 07:52:36 INFO - mozinfo == 0.9 07:52:36 INFO - mozleak == 0.1 07:52:36 INFO - mozlog == 3.4 07:52:36 INFO - moznetwork == 0.27 07:52:36 INFO - mozprocess == 0.23 07:52:36 INFO - mozprofile == 0.28 07:52:36 INFO - mozrunner == 6.12 07:52:36 INFO - mozscreenshot == 0.1 07:52:36 INFO - mozsystemmonitor == 0.3 07:52:36 INFO - moztest == 0.7 07:52:36 INFO - mozversion == 1.4 07:52:36 INFO - psutil == 3.1.1 07:52:36 INFO - requests == 1.2.3 07:52:36 INFO - wsgiref == 0.1.2 07:52:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52: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')]} 07:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52: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')]} 07:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:36 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 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:36 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 07:52:36 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 07:52:36 INFO - Using env: {'DISPLAY': ':0', 07:52:36 INFO - 'HOME': '/home/cltbld', 07:52:36 INFO - 'LANG': 'en_US.UTF-8', 07:52:36 INFO - 'LANGUAGE': 'en_US:en', 07:52:36 INFO - 'LOGNAME': 'cltbld', 07:52:36 INFO - 'MAIL': '/var/mail/cltbld', 07:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:36 INFO - 'MOZ_NO_REMOTE': '1', 07:52:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:36 INFO - 'NO_EM_RESTART': '1', 07:52:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:36 INFO - 'PWD': '/builds/slave/test', 07:52:36 INFO - 'SHELL': '/bin/bash', 07:52:36 INFO - 'SHLVL': '1', 07:52:36 INFO - 'TERM': 'linux', 07:52:36 INFO - 'TMOUT': '86400', 07:52:36 INFO - 'USER': 'cltbld', 07:52:36 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:36 INFO - '_': '/tools/buildbot/bin/python'} 07:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:37 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)) 07:52:37 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)) 07:52:37 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)) 07:52:37 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)) 07:52:37 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)) 07:52:37 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)) 07:52:37 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)) 07:52:37 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)) 07:52:37 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)) 07:52:37 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)) 07:52:37 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)) 07:52:37 INFO - Cleaning up... 07:52:37 INFO - Return code: 0 07:52:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2ffa8cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2513ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', '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 07:52:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:52:37 INFO - Using env: {'DISPLAY': ':0', 07:52:37 INFO - 'HOME': '/home/cltbld', 07:52:37 INFO - 'LANG': 'en_US.UTF-8', 07:52:37 INFO - 'LANGUAGE': 'en_US:en', 07:52:37 INFO - 'LOGNAME': 'cltbld', 07:52:37 INFO - 'MAIL': '/var/mail/cltbld', 07:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:37 INFO - 'MOZ_NO_REMOTE': '1', 07:52:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:37 INFO - 'NO_EM_RESTART': '1', 07:52:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:37 INFO - 'PWD': '/builds/slave/test', 07:52:37 INFO - 'SHELL': '/bin/bash', 07:52:37 INFO - 'SHLVL': '1', 07:52:37 INFO - 'TERM': 'linux', 07:52:37 INFO - 'TMOUT': '86400', 07:52:37 INFO - 'USER': 'cltbld', 07:52:37 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:37 INFO - '_': '/tools/buildbot/bin/python'} 07:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:52:37 INFO - Cleaning up... 07:52:37 INFO - Return code: 0 07:52:37 INFO - Running post-action listener: _resource_record_post_action 07:52:37 INFO - Running post-action listener: _start_resource_monitoring 07:52:37 INFO - Starting resource monitoring. 07:52:37 INFO - [mozharness: 2016-10-20 14:52:37.323732Z] Finished create-virtualenv step (success) 07:52:37 INFO - [mozharness: 2016-10-20 14:52:37.324224Z] Running install step. 07:52:37 INFO - Running pre-action listener: _resource_record_pre_action 07:52:37 INFO - Running main action method: install 07:52:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:37 INFO - Reading from file tmpfile_stdout 07:52:37 INFO - Detecting whether we're running mozinstall >=1.0... 07:52:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:52:37 INFO - Reading from file tmpfile_stdout 07:52:37 INFO - Output received: 07:52:37 INFO - Usage: mozinstall [options] installer 07:52:37 INFO - Options: 07:52:37 INFO - -h, --help show this help message and exit 07:52:37 INFO - -d DEST, --destination=DEST 07:52:37 INFO - Directory to install application into. [default: 07:52:37 INFO - "/builds/slave/test"] 07:52:37 INFO - --app=APP Application being installed. [default: firefox] 07:52:37 INFO - mkdir: /builds/slave/test/build/application 07:52:37 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'] 07:52:37 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 07:52:54 INFO - Reading from file tmpfile_stdout 07:52:54 INFO - Output received: 07:52:54 INFO - /builds/slave/test/build/application/firefox/firefox 07:52:54 INFO - Running post-action listener: _resource_record_post_action 07:52:54 INFO - [mozharness: 2016-10-20 14:52:54.818984Z] Finished install step (success) 07:52:54 INFO - [mozharness: 2016-10-20 14:52:54.819226Z] Running run-tests step. 07:52:54 INFO - Running pre-action listener: _resource_record_pre_action 07:52:54 INFO - Running main action method: run_tests 07:52:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:52:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:52:54 INFO - Python 2.7.3 07:52:54 INFO - Return code: 0 07:52:54 INFO - grabbing minidump binary from tooltool 07:52:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:54 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 0x2510f60>, '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 0x2511d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25121d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:52:54 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 07:52:54 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 07:52:54 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 07:52:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:52:54 INFO - Return code: 0 07:52:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:52:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:52:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:52:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:52:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:52:54 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/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 07:52:54 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/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 07:52:54 INFO - Using env: {'DISPLAY': ':0', 07:52:54 INFO - 'HOME': '/home/cltbld', 07:52:54 INFO - 'LANG': 'en_US.UTF-8', 07:52:54 INFO - 'LANGUAGE': 'en_US:en', 07:52:54 INFO - 'LOGNAME': 'cltbld', 07:52:54 INFO - 'MAIL': '/var/mail/cltbld', 07:52:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:52:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:54 INFO - 'MOZ_NO_REMOTE': '1', 07:52:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:52:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:52:54 INFO - 'NO_EM_RESTART': '1', 07:52:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:54 INFO - 'PWD': '/builds/slave/test', 07:52:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:52:54 INFO - 'SHELL': '/bin/bash', 07:52:54 INFO - 'SHLVL': '1', 07:52:54 INFO - 'TERM': 'linux', 07:52:54 INFO - 'TMOUT': '86400', 07:52:54 INFO - 'USER': 'cltbld', 07:52:54 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338', 07:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:54 INFO - '_': '/tools/buildbot/bin/python'} 07:52:54 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/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 07:52:55 INFO - mozversion application_buildid: 20161020050549 07:52:55 INFO - mozversion application_changeset: 0895f2ef1268cb87b507dfa1e4d7633621215264 07:52:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:52:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:52:55 INFO - mozversion application_name: Firefox 07:52:55 INFO - mozversion application_remotingname: firefox-dev 07:52:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:52:55 INFO - mozversion application_vendor: Mozilla 07:52:55 INFO - mozversion application_version: 51.0a2 07:52:55 INFO - mozversion platform_buildid: 20161020050549 07:52:55 INFO - mozversion platform_changeset: 0895f2ef1268cb87b507dfa1e4d7633621215264 07:52:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:52:55 INFO - mozversion platform_version: 51.0a2 07:52:55 INFO - using testdate: 1476975175 07:52:55 INFO - actual date: 1476975175 07:52:55 INFO - starting webserver on 'localhost:35695' 07:52:55 INFO - SUITE-START | Running 2 tests 07:52:55 INFO - TEST-START | dromaeo_css 07:52:55 INFO - operating with platform_type : linux_ 07:52:55 INFO - Initialising browser for dromaeo_css test... 07:52:55 INFO - TEST-INFO | started process 16633 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO_0CTe/profile http://localhost:35695/getInfo.html) 07:52:59 INFO - PROCESS | 16633 | __metrics Screen width/height:1600/1200 07:52:59 INFO - PROCESS | 16633 | colorDepth:24 07:52:59 INFO - PROCESS | 16633 | Browser inner width/height: 1024/697 07:52:59 INFO - PROCESS | 16633 | __metrics 07:53:00 INFO - PROCESS | 16633 | 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" 07:53:00 INFO - PROCESS | 16633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:53:00 INFO - PROCESS | 16633 | console.error: PushService: 07:53:00 INFO - PROCESS | 16633 | stateChangeProcessEnqueue: Error transitioning state 07:53:00 INFO - PROCESS | 16633 | UnknownError 07:53:01 INFO - TEST-INFO | 16633: exit 0 07:53:01 INFO - Browser initialized. 07:53:01 INFO - Running cycle 1/1 for dromaeo_css test... 07:53:01 INFO - TEST-INFO | started process 16727 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO_0CTe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:53:13 INFO - PROCESS | 16727 | RSS: Main: 144474112 07:53:13 INFO - PROCESS | 16727 | 07:54:04 INFO - PROCESS | 16727 | 1476975244290 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] 07:56:13 INFO - PROCESS | 16727 | Cycle 1(1): loaded http://localhost:35695/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35695/tests/dromaeo/cssquery-ext.html) 07:56:14 INFO - PROCESS | 16727 | RSS: Main: 301473792 07:56:14 INFO - PROCESS | 16727 | 07:59:17 INFO - PROCESS | 16727 | Cycle 1(1): loaded http://localhost:35695/tests/dromaeo/cssquery-ext.html (next: http://localhost:35695/tests/dromaeo/cssquery-jquery.html) 07:59:17 INFO - PROCESS | 16727 | RSS: Main: 228835328 07:59:17 INFO - PROCESS | 16727 | 08:02:19 INFO - PROCESS | 16727 | Cycle 1(1): loaded http://localhost:35695/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35695/tests/dromaeo/cssquery-mootools.html) 08:02:20 INFO - PROCESS | 16727 | RSS: Main: 173408256 08:02:20 INFO - PROCESS | 16727 | 08:05:22 INFO - PROCESS | 16727 | Cycle 1(1): loaded http://localhost:35695/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35695/tests/dromaeo/cssquery-prototype.html) 08:05:22 INFO - PROCESS | 16727 | RSS: Main: 222076928 08:05:22 INFO - PROCESS | 16727 | 08:08:25 INFO - PROCESS | 16727 | Cycle 1(1): loaded http://localhost:35695/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35695/tests/dromaeo/cssquery-yui.html) 08:08:26 INFO - PROCESS | 16727 | RSS: Main: 174817280 08:08:26 INFO - PROCESS | 16727 | 08:11:28 INFO - PROCESS | 16727 | Cycle 1(1): loaded http://localhost:35695/tests/dromaeo/cssquery-yui.html (next: http://localhost:35695/tests/dromaeo/cssquery-dojo.html) 08:11:28 INFO - PROCESS | 16727 | RSS: Main: 265306112 08:11:28 INFO - PROCESS | 16727 | 08:11:28 INFO - PROCESS | 16727 | __start_tp_report 08:11:28 INFO - PROCESS | 16727 | _x_x_mozilla_page_load 08:11:29 INFO - PROCESS | 16727 | _x_x_mozilla_page_load_details 08:11:29 INFO - PROCESS | 16727 | |i|pagename|runs| 08:11:29 INFO - PROCESS | 16727 | |0;dojo.html;3119.3137509747853;3141.5287706843965;3177.5392568077614;3208.631007434146;3216.630087539934;5578.107502799548;5659.66042037478;5662.263905692259;5688.317401911776;5721.084626459762;5745.224394706714;5795.275590551181;5799.362070172288;5802.970985145079;5849.7367618457165;5184.844454666347;5206.635535512516;5211.817586384477;5256.973715131419;5294.761735721882;5027;5029.3964724232965;5318.787248510055;5326.440723724011;5351.224072509483;5102.438731739506;5109.233614957749;5109.923351149736;5134.229865520165;5149.2791009258735;5179.922301165486;5187.766550505217;5240.580753539708;5260.6580572263065;5260.684358938438;4897.8285759998225;4994.975025124888;5137;5157;5232.058229518685;5523.502884740393;5531.778728850841;5553.083741182741;5573.303337082864;5581.274434323553;5972.790952316648;6074;6096.4817990470865;6117.418845209698;6125.908111378289;5892.558058145613;5894.528437724972;6167.691615419211;6169.691515424211;6170.444659980623;5631.380548139701;5692.345380281279;5696.943030569682;5750.971245143747;5756.827295181151;6249.096915311894;6296;6299.590526615755;6300.684965751786;6306.24325080984;5716.42835716425;6019.668918209453;6022.5784195105925;6022.728977196037;6053.243344581927;5824.388439213906;6005.729742161613;6058.182145410401;6068.029115341524;6117.398686497024;6092.45167934888;6093.146959425684;6096.603720758137;6099.146119543268;6130.570860039755;6062.272527296753;6090.512758979271;6123.295820980555;6125.203723515914;6126.846828829315;4484.730916145042;4519.751413672279;4554.863354099382;4566.931496027562;4593.035462552834;4075.836966521306;4085.55058943516;4092.631663150331;4106.568810274938;4125.257453658371;5676.233708449389;5690.004249256446;5698.601097923106;5702.8289151325525;5744.885102297931;5872.236609240771;5901.616394934315;6025.728842202111;6064.332923378425;6109.877802443926;5821.592488525847;5831.300243970751;5846.736896839652;5851.356350801409;5862.14998825168;5095.439501654815;5129.9230511542355;5136.9743151284;5153.665011774223;5199.090159222196;6017.819465416044;6115.541334399849;6125.938740612626;6290.748370065146;6307.4954003679595;6030.7889223876955;6112.777056696867;6118.663473509;6139.877202455837;6144.1398204250545;6201.627902325695;6230.657313847782;6238.532110091671;6239.906401403891;6255.155554000152;5934.910976335272;5981.1028345746745;5993.203583565022;6002.219711437485;6028.0355143176885;5740.741666625096;5800.709964501673;6002.969985150046;6067.24159480065;6100.75596976116;6005.579609427473;6009.459148676465;6016.2479690038745;6041;6060.818175454743;5963.224780778471;5990.820275391745;6008.279006519246;6015.969920150371;6018.668973206516;5718.714064296852;5762.4525670061275;5765.135229715576;5767.5674324425;5958.731857066356;5905.970470147622;5959.523238141025;5962.493188079062;5973.850653733692;5988.431099045583;5612.971935140298;5665.263515742927;5671.914921276101;5786.594938354275;5790.39200883918 08:11:29 INFO - PROCESS | 16727 | |1;ext.html;580.2022219448259;621.6736213487922;624.3319647976664;637.238499992509;640.6508452893173;34641;35657.64342356576;38048.61951380486;45137.77431112848;46082;47571.28643070357;48073.27890081651;48109.51890481094;48128;48138;9247.953760231207;9492.857607135898;9497.857532137023;9507.619695212183;9511.143997040264;8641.783955401103;8839.07189745077;10962.835557466604;11241.213115081948;11244.943775281154;11614.535418583246;11708.707282317968;11712.414379281045;11716.121290903217;11722.882771172264;11272.492737826735;11286.209965302432;11343.489542970587;11348.716282092932;11352.318860868332;11259.099272058216;11335.943320283428;11353.886461135366;11358.318500889973;11359.488823002985;44847.327290090674;45055.09889802218;45106;45157.77421112874;45227.773861130816;3584.4623306503972;3587.3542762302777;3635.4546817977252;3700.0749812546865;4208.684348673862;32468.675313247037;34687.30625387503;34902;35016.47475287873;35349.646503534896;4644.558766917171;4647.279671650899;4682.555157260055;4718.363020992157;4745.406824146981;396778;400383;402234.98882505693;404638;405271;68049;68302.65848670824;68487;68536.314636853;68770.65614671895;42261;43164.568354316056;75320.24679753243;75358.24641753512;75574;19677.803221967883;19771.802281977285;19796.90101549483;19797.901010495145;19800.504987375432;13120.147190432592;13257.469701212036;15767.842321576867;15882.523524294289;15888.205589720468;22011.779882201292;22617.095316187497;22652;23249.535009299718;23313.18403855891;7957;8206.179382061746;8348.165183481724;8354.282344356703;8397.328213743009;941.9481928493861;959.8512230604267;1016.3800081950004;1023.3194925374692;1070.250824422907;29312.267193319913;29344.360302909354;29575.112746617637;29654.406911862072;29707.702922970926;1092.2190633696946;1093.0435868614961;1107.3854011023798;1167.4046236419317;1176.0650283025016;15282;15298.464553740565;15322.463713769965;15556.844431555766;15593.844061559466;572785;573705;575822;578749;579017;62115;62518.687406562676;62536.687316563126;62645;62746.37253627497;1319.459021801057;1383.3429121167567;1406.556934565629;1443.9205843678699;1470.4485817818318;26826.731732682423;29388;29610.407791844475;30474;30525.542116867822;52557.21164182464;52858.471415285356;53038.46961530489;53122.2031669533;53190;402366;403191;406066;409225;409463;32248.35503289968;43501.78249108734;47200.29199562078;49519;53927.730361347945;49691.254631179836;50271.74864125656;50730;53996.73001634967;55079.44920550743;44825;45150.77424612856;46241.76879115718;46765;48258.5174148254;45107;46426.30360544527;46504;47243;47793;40002.39996399998;40084.79957600193;41071.7946410266;41118.79440602778;41197.58802411937;37473;40283.99290017773;40285.798571006955;40539;41215.381769272884;30652.693473064985;30768.84615576908;30831.2292192688;30946;31293.217669557533 08:11:29 INFO - PROCESS | 16727 | |2;jquery.html;396.17793079360337;434.3983582737908;436.50238727850257;439.9362092496587;450.06386715631487;17252.568685782862;19519;21736.673949890726;21886;22129.225477108284;19071.71392429111;19555.41333759986;19639.705404418946;20066.297679581217;20301.0864511097;5514.91727624086;5585.74864131113;5708.4291570843;5710.885782284352;5712.514436272901;5985.6408615483;5998.460138587527;6041.063635136539;6043.486303664194;6074.908876366836;5582.078956972095;5648.915266271009;6009.87980240395;6020.879582408349;6024.0662697282;5583.637063590874;5652.5195358394585;5656.91514627281;5659.4340565943485;5660.377358490563;5310.92033619496;5470.152126420411;5515.255440515519;5520.861978450531;5523.143912693498;15430.305636246396;15443;15500.302486388027;15563.766543501742;15569.844301556952;2293.4610366563843;2458.791002764767;2522.798176145904;2540.4411029573466;2586.237060067285;11667.94166029173;11765.705857353634;12447.93776031114;13136.949044076451;16317;1996.610660921121;2096.874187548752;2105.2526353144717;2131.893405329727;2203.7025001624734;88277.55861220717;89538;90575.09424905732;90744.09255907488;96760.51619741856;10569.365838049742;11874.821877671842;11886.702832429259;11982.34097124649;12066.155369124077;15686.352932941876;15847.762283565755;16758.91620541914;16770.91614541944;17282.567935801453;8657.610407531676;8729.650813967497;8819.955900220459;8848.363052835246;9441.150296473214;8932.955335223282;8950.418222815497;8975.102489751103;9224.8616270756;9237.214836738818;5148.639595228373;5222.216667499905;5238.816641417532;5246.422893481714;5389.461053894579;2115.925942592002;2172.3374370816737;2188.3763127508582;2189.0696454006984;2205.2502149269326;956.8325543029941;1024.36489376587;1096.714854137914;1117.7485065860117;1127.0476447401932;20046.09792559338;20273.08771105304;20274.59450811005;21753.78246217549;21843.453913652287;1029.4492446541065;1029.7683021320142;1035.4822588705647;1095.0691911874842;1107.7507560798917;10504.42225677595;11036;11091.500882460308;11174.553017879356;11227.550897964153;470255;492534;518290;534688;538959;98855;102004.48997754963;104569.47715261528;106357;106836;1040.6409788622914;1103.7295862513574;1108.2353176308322;1134.359087115777;1164.0512981919708;17591.82408175902;17687.82312176862;18112.72830907511;18261.178246978652;18325.99207043572;46384.7680761594;46684.766576166905;46948.7652561735;47351;47707.52292477031;63722.36277637164;64311.356886430534;64614.35385646083;65801.67099164473;66943.33056669371;9131.452112873249;9306.767330816783;9368.297377696565;9582.952085239529;9729.173020293072;13243.735125297633;13338.93330533341;14438.77270432025;14499.202543860189;14788.63028424298;12457.626271211879;12562.12065155467;12579.371031448574;12602.180858244184;12782.872171278541;11721.12091593151;11768.176227663982;11883.346415947206;12218.389080546114;12226.144169908377;12149.939250303692;12478.625641230778;12569.93715031419;12777.23336599807;12802.67993300175;9506.90493095088;9548.904510954802;9595.904040959502;9664.323497355117;9833.16418104469;11756.823647645464;12725.109242352946;12826.294553799631;12859.614211573667;12887.355632218165 08:11:29 INFO - PROCESS | 16727 | |3;mootools.html;1453.4840131753233;1534.2022148482786;1572.9056256624604;1734.8612111031118;1843.751093602364;8295.170482951702;9126.133017363356;9507.667231646894;9545.522723863796;9956.303058785888;9822.116009559139;9894.257930655212;9896.059874311946;9896.703098907026;9900.554475048622;2523.028633975914;2551.6045013023013;2559.104313490273;2559.9360015999564;2560.7183209846994;2443.022790883643;2540.720520742726;2545.4654522550286;2549.3244290263096;2550.1711943618307;2485.2792690119845;2509.3475696318906;2556.2970183199677;2559.7440255974348;2564.6281289213052;2439.9634005489934;2460.4463995601027;2465.494573612405;2467.9136230231948;2474.6659201007815;2452.1540068676277;2479.938001549958;2487.315988103268;2488.0172331928966;2489.4149874779414;9384.718458446258;9592.520373981273;9594.280428967855;9681.031896810333;9694.806103877883;5658.575606829463;5893.528517718572;5897.292324920994;5900.97049514754;5904.143899134601;3816.713746469026;3841.9231615367817;3877.7479463835043;3901.7854018028997;3924.4113382992496;1354.4852955876802;1396.958091257264;1428.250168661461;1455.7889106079558;1459.6277949123012;13530.661733456644;13554.186748795104;13554.254516001616;13649.658758531017;13667.043306968517;4669.2062349400685;4991.326170966946;5011.373578302712;5014.07239660664;5021.221710634857;10651.414172220602;10653.414062226497;10659.413732244646;10670.679879603624;10674.73313167177;5651;5659.518940889988;5661.858453538694;5663.490285874291;5666.263385759908;4517.841875534406;4538.546145385501;5233.633645644844;5256.369235691742;5335.253064570963;4164.937525937114;4171.1032128092;4190.832366705358;4244.87265382039;4246.8725938221905;1214.504193881564;1237.7215126596443;1238.1675120821055;1239.318374893823;1249.3440943504734;888.7778055486128;931.5668214280257;952.4047470331043;990.4693508086907;1044.1803184500131;6197.628142311476;6375.904361434582;6569.605823650596;6580.499360683527;6730.596164230162;1024.4416792847917;1065.1372388365448;1092.6885837536422;1109.4966320635583;1112.4938153140301;4223.303154979455;4258.190943720683;4274.123804620045;4329.328954012133;4441.222786012435;12072.517099316105;12486.500539978299;12515.374231288399;12516.249025058525;12545.184563003375;12197;12227.14409991292;12455.564055258204;12458.501659933683;12509.311987840574;9036.548172591343;9047.773805654911;9189.218916392181;9206.493642849708;9208.585613647268;6326.746930122744;6347.460465860454;6440.710168042543;6467.870642587028;6821.2155602106395;11064;11134.554617815196;11178.888211117785;11182.944085279521;11219.49512271966;11309.095272378354;11402.087832973177;11444.141689373164;11530.538778449103;11552.711182220512;8021;8251.95874020626;8296.253337199681;8319.417640765088;8345.374096942875;8740.388172827841;8988.685396011108;9027;9052.456852588866;9066.09339066088;8007.199280072179;8084.191580841869;8108.432409731262;8129.634166462642;8312.25189732927;7973.322267607318;7983.5209887406945;8012.399070069885;8025.678972841254;8126.634301456567;7062.929370706227;7118.679659415442;7129.572225666477;7133.2153463117975;7151.892721609076;5596.188552659923;5646.802361917313;5673.8865222694485;5683.857903552279;5863.706814659336;5777.422257774189;5820.534357251495;5883.294004719461;5991.670458124845;6016.308124565731 08:11:29 INFO - PROCESS | 16727 | |4;prototype.html;332.34362134783805;341.19706610197227;345.1423213314912;352.4853713578176;357.6012745788446;5415.131779536058;5498.395176530578;5509.283793106895;5530.225768392428;5531.032069387861;5436.456354364562;5452.6455780374245;5458.85364073544;5466.863328416792;5481.780728770845;4061.7562946223175;4334.891627709301;4337.154254920292;4344.196323680114;4355.691483513743;3797.6012518685557;4143.461350024508;4158.586080732551;4306.61240488356;4332.913341733163;4269.743815371087;4277;4284.914301713964;4286;4290.163418133465;4291.806868690901;4296.957030429687;4305.246581848195;4310.913781724379;4312.145777315769;3619.457081437779;3722.6091260417807;3731.589525152231;3852.8844134676006;3871.1676989447406;5303.337082864642;5333.546648534879;5346.786128554853;5349.170878513836;5355.8752661941035;4943.382077240345;4943.6539442239055;4946.307516947631;4968.950310496885;4969.0807200668;5571.4150014248735;5666.5183459406;5713.771449142029;5731.885362292773;5739;4207.768572728499;4224.514180869209;4225.302825033866;4236.957630423687;4238.957610423918;5493.670379777186;5679.432056794329;5937.526546969984;5957.987142185839;5979.252593425822;4296.334068219431;4326.956730432718;4335.696501244883;4343.609075183249;4361.585649363369;4412.823487060546;4422.646188304928;4427.697597249412;4433.667474939392;4440.755758433253;4851.234796166222;4957.050554919817;4961.950380496149;4977.975110124498;4986.376702912136;5720.370759216483;5756.294541565458;5764.884702305931;5765.827025189251;5773.364929857712;1505.284989629917;1519.475780855603;1527.1676936069646;1529.258309719785;1531.5175719648275;944.593824655391;987.53585814667;990.2869933647762;991.3952488981715;1001.0790073132736;4917.950820491821;4968.652194346361;4971.825986090469;4974.228994505857;4987;5122.544490118013;5133.589312854962;5135.563477104488;5142.562882155059;5145.665531740425;4699.553542413465;4977.228529577921;4983.651144419929;4986.675866068694;5014.874628134253;2754.628125203112;2783.5963785251024;2786.972130278671;2798.818076825;2809.845458499761;5300.681959082391;5328.333958255218;5340.198970154508;5375.892482150335;5381.246625472437;5120.436751957282;5321.308229930084;5335.33308336458;5360.6247562671015;5361.490658387447;5349.839504814861;5351.558206688439;5355.349102194438;5357.758900849393;5357.785688572491;5230.555402790653;5244.737763111752;5249.842504724865;5251.610517214582;5270;4856.490068542752;5090;5123.513266239701;5137.229415587692;5146.970605878914;5255.3693556771905;5754.8849023018465;5883.911741323969;5891.469767720926;6090.543209259235;4392.4289842320295;4398.208322501981;4404.537523560109;4409.7795110243715;4414.602685758297;5706.4864162225595;5955.880882382241;5961.463468287875;5966.4487233320515;5967.850803729768;5758.395368486249;5763.510101641407;5778.508826749605;5794.348610645946;5815.156802263733;5622.522085622604;5769.826905192851;5784.132380142844;5792.3355256582445;5794.391588883275;5467.305135408036;5731.7420716067045;5740.971295143498;5769.653820770767;5774.220480235283;5843.7954671587995;5851.736671849862;5855.648661080349;5859.267591551056;5870.561712380323;5526.336839579276;5720.771169153188;5724.771009159587;5745.569082318759;5753.252077229933 08:11:29 INFO - PROCESS | 16727 | |5;yui.html;827.2678679368761;828.1842385250529;870.5125129927242;903.439867282285;948.4641177734582;16019.118948457844;16776.748348774778;16788.076655783912;16815;16939.068351240676;15541.922290388533;15784;15794.368225271004;15813.683726325467;15830.366785328573;701.2706784943653;719.6293908637056;837.4640230252643;846.3483117999152;858.0475672004102;855.2260204514924;857.6869442653418;858.5406807358065;858.5578427110044;858.6436628799041;909.9909000910005;910.635745701718;910.8223896340216;911.3802614222326;911.7583840282331;753.3445902065183;775.3061010395693;874.422880898607;901.3104974694364;904.7014485219928;661.9106420633249;662.5064327076311;662.9237637671633;662.9834254143636;663.2107791727828;15054.247287635575;15085.849141508554;15089.170095644735;15101.395944162221;15106.924465377713;15475.535733928;15534.61163470911;15653.060816350942;15709.528714138594;16066.437674681447;13510;13864.722705545933;14233.715325693533;14362.64093397663;14564.344604492717;4741.383620129395;4781.139394908915;4781.593564547018;4792.400949881265;4933.358663373774;88191.55904220502;88253.55873220657;90524.99999999935;92455;92563.53718231434;11742;11775.823362649568;12141.482314710662;12556.937215314048;12635.555866507962;21444.678329825067;22296.219632313114;23414.765852341257;23481;23512;9549.665761698307;9700.611975521042;9817.509124543887;9977.201823854219;10017.248706347053;5014.398272207359;5435.673859568438;5439.918401223985;5464.016477034172;5692.686902220335;3643.2713457308646;3736.5702944161226;3792.58281589025;3797.9430308544843;3798.164403831153;632.181325183888;638.3106245255113;638.776428250118;639.6418005916702;644.1432894250636;703.9894401583878;747.3423387419036;779.3258831111161;790.1900551934199;838.8364268967615;10513.684589462175;11166.330020198815;11199.776004480027;11412.31526108436;11694.649160525198;756.7427074794597;766.4673052228759;777.5334799120483;788.4520258420342;842.616609442702;5114.535443840376;5408.486193811582;5423.810166644149;5582.33012038558;5610.607257491936;69732.65133674299;70169;90266;119988;122057.38971305087;107036.39445408325;107179.46410267899;108426;109645.45177274062;110672;91259.54370228107;93077;93772.53113734387;93823.0617693828;93963;10213.642522511676;10446;10534.736631584272;10604.681859544226;10762.73093172677;37171.628283717895;47971.04057918892;48768;49629.503704963936;52244.216336754216;75243;75625;75992;77532;80113;13819.792703109662;14223.845655089579;14400.783988239975;14413;14628.634284142981;15870.2064896757;15915.124668143364;16530.66938661196;17143.828561714225;17231.3969011084;15232.543023709306;15249.237538122827;15582.376704932129;15637.374505020127;16160.353585856434;15213.163276019925;15366.84633153654;15559.922200388926;15839;16601;14824.703505929605;14975;15069.849301506845;15933.840661593236;16221.513354599381;12464.252144871336;12477.812832807334;12911.741765164832;13228.470861165446;13324.600261992156;13113.344332783514;13566.186028838301;13636.250006249355;13876.72246555043;13998.090124141898 08:11:29 INFO - PROCESS | 16727 | __end_tp_report 08:11:29 INFO - PROCESS | 16727 | __start_cc_report 08:11:29 INFO - PROCESS | 16727 | _x_x_mozilla_cycle_collect,1298 08:11:29 INFO - PROCESS | 16727 | __end_cc_report 08:11:29 INFO - PROCESS | 16727 | __startTimestamp1476976288997__endTimestamp 08:11:29 INFO - PROCESS | 16727 | 08:11:29 INFO - PROCESS | 16727 | ------- Summary: start ------- 08:11:29 INFO - PROCESS | 16727 | Number of tests: 6 08:11:29 INFO - PROCESS | 16727 | 08:11:29 INFO - PROCESS | 16727 | [#0] dojo.html Cycles:165 Average:5625.22 Median:5824.39 stddev:660.75 (11.3%) stddev-sans-first:632.86 08:11:29 INFO - PROCESS | 16727 | Values: 3119.3 3141.5 3177.5 3208.6 3216.6 5578.1 5659.7 5662.3 5688.3 5721.1 5745.2 5795.3 5799.4 5803.0 5849.7 5184.8 5206.6 5211.8 5257.0 5294.8 5027.0 5029.4 5318.8 5326.4 5351.2 5102.4 5109.2 5109.9 5134.2 5149.3 5179.9 5187.8 5240.6 5260.7 5260.7 4897.8 4995.0 5137.0 5157.0 5232.1 5523.5 5531.8 5553.1 5573.3 5581.3 5972.8 6074.0 6096.5 6117.4 6125.9 5892.6 5894.5 6167.7 6169.7 6170.4 5631.4 5692.3 5696.9 5751.0 5756.8 6249.1 6296.0 6299.6 6300.7 6306.2 5716.4 6019.7 6022.6 6022.7 6053.2 5824.4 6005.7 6058.2 6068.0 6117.4 6092.5 6093.1 6096.6 6099.1 6130.6 6062.3 6090.5 6123.3 6125.2 6126.8 4484.7 4519.8 4554.9 4566.9 4593.0 4075.8 4085.6 4092.6 4106.6 4125.3 5676.2 5690.0 5698.6 5702.8 5744.9 5872.2 5901.6 6025.7 6064.3 6109.9 5821.6 5831.3 5846.7 5851.4 5862.1 5095.4 5129.9 5137.0 5153.7 5199.1 6017.8 6115.5 6125.9 6290.7 6307.5 6030.8 6112.8 6118.7 6139.9 6144.1 6201.6 6230.7 6238.5 6239.9 6255.2 5934.9 5981.1 5993.2 6002.2 6028.0 5740.7 5800.7 6003.0 6067.2 6100.8 6005.6 6009.5 6016.2 6041.0 6060.8 5963.2 5990.8 6008.3 6016.0 6018.7 5718.7 5762.5 5765.1 5767.6 5958.7 5906.0 5959.5 5962.5 5973.9 5988.4 5613.0 5665.3 5671.9 5786.6 5790.4 08:11:29 INFO - PROCESS | 16727 | 08:11:29 INFO - PROCESS | 16727 | [#1] ext.html Cycles:180 Average:64048.32 Median:222905.00 stddev:123985.23 (55.6%) stddev-sans-first:124241.46 08:11:29 INFO - PROCESS | 16727 | Values: 580.2 621.7 624.3 637.2 640.7 34641.0 35657.6 38048.6 45137.8 46082.0 47571.3 48073.3 48109.5 48128.0 48138.0 9248.0 9492.9 9497.9 9507.6 9511.1 8641.8 8839.1 10962.8 11241.2 11244.9 11614.5 11708.7 11712.4 11716.1 11722.9 11272.5 11286.2 11343.5 11348.7 11352.3 11259.1 11335.9 11353.9 11358.3 11359.5 44847.3 45055.1 45106.0 45157.8 45227.8 3584.5 3587.4 3635.5 3700.1 4208.7 32468.7 34687.3 34902.0 35016.5 35349.6 4644.6 4647.3 4682.6 4718.4 4745.4 396778.0 400383.0 402235.0 404638.0 405271.0 68049.0 68302.7 68487.0 68536.3 68770.7 42261.0 43164.6 75320.2 75358.2 75574.0 19677.8 19771.8 19796.9 19797.9 19800.5 13120.1 13257.5 15767.8 15882.5 15888.2 22011.8 22617.1 22652.0 23249.5 23313.2 7957.0 8206.2 8348.2 8354.3 8397.3 941.9 959.9 1016.4 1023.3 1070.3 29312.3 29344.4 29575.1 29654.4 29707.7 1092.2 1093.0 1107.4 1167.4 1176.1 15282.0 15298.5 15322.5 15556.8 15593.8 572785.0 573705.0 575822.0 578749.0 579017.0 62115.0 62518.7 62536.7 62645.0 62746.4 1319.5 1383.3 1406.6 1443.9 1470.4 26826.7 29388.0 29610.4 30474.0 30525.5 52557.2 52858.5 53038.5 53122.2 53190.0 402366.0 403191.0 406066.0 409225.0 409463.0 32248.4 43501.8 47200.3 49519.0 53927.7 49691.3 50271.7 50730.0 53996.7 55079.4 44825.0 45150.8 46241.8 46765.0 48258.5 45107.0 46426.3 46504.0 47243.0 47793.0 40002.4 40084.8 41071.8 41118.8 41197.6 37473.0 40284.0 40285.8 40539.0 41215.4 30652.7 30768.8 30831.2 30946.0 31293.2 08:11:29 INFO - PROCESS | 16727 | 08:11:29 INFO - PROCESS | 16727 | [#2] jquery.html Cycles:180 Average:30829.27 Median:21798.62 stddev:84719.68 (388.6%) stddev-sans-first:84926.52 08:11:29 INFO - PROCESS | 16727 | Values: 396.2 434.4 436.5 439.9 450.1 17252.6 19519.0 21736.7 21886.0 22129.2 19071.7 19555.4 19639.7 20066.3 20301.1 5514.9 5585.7 5708.4 5710.9 5712.5 5985.6 5998.5 6041.1 6043.5 6074.9 5582.1 5648.9 6009.9 6020.9 6024.1 5583.6 5652.5 5656.9 5659.4 5660.4 5310.9 5470.2 5515.3 5520.9 5523.1 15430.3 15443.0 15500.3 15563.8 15569.8 2293.5 2458.8 2522.8 2540.4 2586.2 11667.9 11765.7 12447.9 13136.9 16317.0 1996.6 2096.9 2105.3 2131.9 2203.7 88277.6 89538.0 90575.1 90744.1 96760.5 10569.4 11874.8 11886.7 11982.3 12066.2 15686.4 15847.8 16758.9 16770.9 17282.6 8657.6 8729.7 8820.0 8848.4 9441.2 8933.0 8950.4 8975.1 9224.9 9237.2 5148.6 5222.2 5238.8 5246.4 5389.5 2115.9 2172.3 2188.4 2189.1 2205.3 956.8 1024.4 1096.7 1117.7 1127.0 20046.1 20273.1 20274.6 21753.8 21843.5 1029.4 1029.8 1035.5 1095.1 1107.8 10504.4 11036.0 11091.5 11174.6 11227.6 470255.0 492534.0 518290.0 534688.0 538959.0 98855.0 102004.5 104569.5 106357.0 106836.0 1040.6 1103.7 1108.2 1134.4 1164.1 17591.8 17687.8 18112.7 18261.2 18326.0 46384.8 46684.8 46948.8 47351.0 47707.5 63722.4 64311.4 64614.4 65801.7 66943.3 9131.5 9306.8 9368.3 9583.0 9729.2 13243.7 13338.9 14438.8 14499.2 14788.6 12457.6 12562.1 12579.4 12602.2 12782.9 11721.1 11768.2 11883.3 12218.4 12226.1 12149.9 12478.6 12569.9 12777.2 12802.7 9506.9 9548.9 9595.9 9664.3 9833.2 11756.8 12725.1 12826.3 12859.6 12887.4 08:11:29 INFO - PROCESS | 16727 | 08:11:29 INFO - PROCESS | 16727 | [#3] mootools.html Cycles:180 Average:6313.70 Median:4479.53 stddev:3638.08 (81.2%) stddev-sans-first:3629.95 08:11:29 INFO - PROCESS | 16727 | Values: 1453.5 1534.2 1572.9 1734.9 1843.8 8295.2 9126.1 9507.7 9545.5 9956.3 9822.1 9894.3 9896.1 9896.7 9900.6 2523.0 2551.6 2559.1 2559.9 2560.7 2443.0 2540.7 2545.5 2549.3 2550.2 2485.3 2509.3 2556.3 2559.7 2564.6 2440.0 2460.4 2465.5 2467.9 2474.7 2452.2 2479.9 2487.3 2488.0 2489.4 9384.7 9592.5 9594.3 9681.0 9694.8 5658.6 5893.5 5897.3 5901.0 5904.1 3816.7 3841.9 3877.7 3901.8 3924.4 1354.5 1397.0 1428.3 1455.8 1459.6 13530.7 13554.2 13554.3 13649.7 13667.0 4669.2 4991.3 5011.4 5014.1 5021.2 10651.4 10653.4 10659.4 10670.7 10674.7 5651.0 5659.5 5661.9 5663.5 5666.3 4517.8 4538.5 5233.6 5256.4 5335.3 4164.9 4171.1 4190.8 4244.9 4246.9 1214.5 1237.7 1238.2 1239.3 1249.3 888.8 931.6 952.4 990.5 1044.2 6197.6 6375.9 6569.6 6580.5 6730.6 1024.4 1065.1 1092.7 1109.5 1112.5 4223.3 4258.2 4274.1 4329.3 4441.2 12072.5 12486.5 12515.4 12516.2 12545.2 12197.0 12227.1 12455.6 12458.5 12509.3 9036.5 9047.8 9189.2 9206.5 9208.6 6326.7 6347.5 6440.7 6467.9 6821.2 11064.0 11134.6 11178.9 11182.9 11219.5 11309.1 11402.1 11444.1 11530.5 11552.7 8021.0 8252.0 8296.3 8319.4 8345.4 8740.4 8988.7 9027.0 9052.5 9066.1 8007.2 8084.2 8108.4 8129.6 8312.3 7973.3 7983.5 8012.4 8025.7 8126.6 7062.9 7118.7 7129.6 7133.2 7151.9 5596.2 5646.8 5673.9 5683.9 5863.7 5777.4 5820.5 5883.3 5991.7 6016.3 08:11:29 INFO - PROCESS | 16727 | 08:11:29 INFO - PROCESS | 16727 | [#4] prototype.html Cycles:180 Average:4696.50 Median:5146.32 stddev:1339.56 (26.0%) stddev-sans-first:1302.65 08:11:29 INFO - PROCESS | 16727 | Values: 332.3 341.2 345.1 352.5 357.6 5415.1 5498.4 5509.3 5530.2 5531.0 5436.5 5452.6 5458.9 5466.9 5481.8 4061.8 4334.9 4337.2 4344.2 4355.7 3797.6 4143.5 4158.6 4306.6 4332.9 4269.7 4277.0 4284.9 4286.0 4290.2 4291.8 4297.0 4305.2 4310.9 4312.1 3619.5 3722.6 3731.6 3852.9 3871.2 5303.3 5333.5 5346.8 5349.2 5355.9 4943.4 4943.7 4946.3 4969.0 4969.1 5571.4 5666.5 5713.8 5731.9 5739.0 4207.8 4224.5 4225.3 4237.0 4239.0 5493.7 5679.4 5937.5 5958.0 5979.3 4296.3 4327.0 4335.7 4343.6 4361.6 4412.8 4422.6 4427.7 4433.7 4440.8 4851.2 4957.1 4962.0 4978.0 4986.4 5720.4 5756.3 5764.9 5765.8 5773.4 1505.3 1519.5 1527.2 1529.3 1531.5 944.6 987.5 990.3 991.4 1001.1 4918.0 4968.7 4971.8 4974.2 4987.0 5122.5 5133.6 5135.6 5142.6 5145.7 4699.6 4977.2 4983.7 4986.7 5014.9 2754.6 2783.6 2787.0 2798.8 2809.8 5300.7 5328.3 5340.2 5375.9 5381.2 5120.4 5321.3 5335.3 5360.6 5361.5 5349.8 5351.6 5355.3 5357.8 5357.8 5230.6 5244.7 5249.8 5251.6 5270.0 4856.5 5090.0 5123.5 5137.2 5147.0 5255.4 5754.9 5883.9 5891.5 6090.5 4392.4 4398.2 4404.5 4409.8 4414.6 5706.5 5955.9 5961.5 5966.4 5967.9 5758.4 5763.5 5778.5 5794.3 5815.2 5622.5 5769.8 5784.1 5792.3 5794.4 5467.3 5731.7 5741.0 5769.7 5774.2 5843.8 5851.7 5855.6 5859.3 5870.6 5526.3 5720.8 5724.8 5745.6 5753.3 08:11:29 INFO - PROCESS | 16727 | 08:11:29 INFO - PROCESS | 16727 | [#5] yui.html Cycles:180 Average:21807.72 Median:3764.58 stddev:30364.19 (806.6%) stddev-sans-first:30408.51 08:11:29 INFO - PROCESS | 16727 | Values: 827.3 828.2 870.5 903.4 948.5 16019.1 16776.7 16788.1 16815.0 16939.1 15541.9 15784.0 15794.4 15813.7 15830.4 701.3 719.6 837.5 846.3 858.0 855.2 857.7 858.5 858.6 858.6 910.0 910.6 910.8 911.4 911.8 753.3 775.3 874.4 901.3 904.7 661.9 662.5 662.9 663.0 663.2 15054.2 15085.8 15089.2 15101.4 15106.9 15475.5 15534.6 15653.1 15709.5 16066.4 13510.0 13864.7 14233.7 14362.6 14564.3 4741.4 4781.1 4781.6 4792.4 4933.4 88191.6 88253.6 90525.0 92455.0 92563.5 11742.0 11775.8 12141.5 12556.9 12635.6 21444.7 22296.2 23414.8 23481.0 23512.0 9549.7 9700.6 9817.5 9977.2 10017.2 5014.4 5435.7 5439.9 5464.0 5692.7 3643.3 3736.6 3792.6 3797.9 3798.2 632.2 638.3 638.8 639.6 644.1 704.0 747.3 779.3 790.2 838.8 10513.7 11166.3 11199.8 11412.3 11694.6 756.7 766.5 777.5 788.5 842.6 5114.5 5408.5 5423.8 5582.3 5610.6 69732.7 70169.0 90266.0 119988.0 122057.4 107036.4 107179.5 108426.0 109645.5 110672.0 91259.5 93077.0 93772.5 93823.1 93963.0 10213.6 10446.0 10534.7 10604.7 10762.7 37171.6 47971.0 48768.0 49629.5 52244.2 75243.0 75625.0 75992.0 77532.0 80113.0 13819.8 14223.8 14400.8 14413.0 14628.6 15870.2 15915.1 16530.7 17143.8 17231.4 15232.5 15249.2 15582.4 15637.4 16160.4 15213.2 15366.8 15559.9 15839.0 16601.0 14824.7 14975.0 15069.8 15933.8 16221.5 12464.3 12477.8 12911.7 13228.5 13324.6 13113.3 13566.2 13636.3 13876.7 13998.1 08:11:29 INFO - PROCESS | 16727 | -------- Summary: end -------- 08:11:29 INFO - PROCESS | 16727 | 08:11:29 INFO - TEST-INFO | 16727: exit 0 08:11:29 INFO - TEST-OK | dromaeo_css | took 1114710ms 08:11:29 INFO - TEST-START | kraken 08:11:29 INFO - operating with platform_type : linux_ 08:11:29 INFO - Initialising browser for kraken test... 08:11:29 INFO - TEST-INFO | started process 17045 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfyHgN3/profile http://localhost:35695/getInfo.html) 08:11:34 INFO - PROCESS | 17045 | __metrics Screen width/height:1600/1200 08:11:34 INFO - PROCESS | 17045 | colorDepth:24 08:11:34 INFO - PROCESS | 17045 | Browser inner width/height: 1024/697 08:11:34 INFO - PROCESS | 17045 | __metrics 08:11:34 INFO - PROCESS | 17045 | 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:11:34 INFO - PROCESS | 17045 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:11:35 INFO - PROCESS | 17045 | console.error: PushService: 08:11:35 INFO - PROCESS | 17045 | stateChangeProcessEnqueue: Error transitioning state 08:11:35 INFO - PROCESS | 17045 | UnknownError 08:11:35 INFO - TEST-INFO | 17045: exit 0 08:11:35 INFO - Browser initialized. 08:11:35 INFO - Running cycle 1/1 for kraken test... 08:11:35 INFO - TEST-INFO | started process 17135 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfyHgN3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:11:47 INFO - PROCESS | 17135 | RSS: Main: 145014784 08:11:47 INFO - PROCESS | 17135 | 08:11:50 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35695/tests/kraken/driver.html?testName=audio-beat-detection) 08:11:51 INFO - PROCESS | 17135 | RSS: Main: 186662912 08:11:51 INFO - PROCESS | 17135 | 08:11:53 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35695/tests/kraken/driver.html?testName=audio-dft) 08:11:54 INFO - PROCESS | 17135 | RSS: Main: 175136768 08:11:54 INFO - PROCESS | 17135 | 08:11:57 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35695/tests/kraken/driver.html?testName=audio-fft) 08:11:57 INFO - PROCESS | 17135 | RSS: Main: 267534336 08:11:57 INFO - PROCESS | 17135 | 08:11:59 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35695/tests/kraken/driver.html?testName=audio-oscillator) 08:12:00 INFO - PROCESS | 17135 | RSS: Main: 173547520 08:12:00 INFO - PROCESS | 17135 | 08:12:02 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35695/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:12:02 INFO - PROCESS | 17135 | RSS: Main: 175550464 08:12:02 INFO - PROCESS | 17135 | 08:12:05 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35695/tests/kraken/driver.html?testName=imaging-darkroom) 08:12:06 INFO - PROCESS | 17135 | RSS: Main: 211415040 08:12:06 INFO - PROCESS | 17135 | 08:12:09 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35695/tests/kraken/driver.html?testName=imaging-desaturate) 08:12:10 INFO - PROCESS | 17135 | RSS: Main: 209596416 08:12:10 INFO - PROCESS | 17135 | 08:12:13 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35695/tests/kraken/driver.html?testName=json-parse-financial) 08:12:13 INFO - PROCESS | 17135 | RSS: Main: 210186240 08:12:13 INFO - PROCESS | 17135 | 08:12:15 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35695/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:12:15 INFO - PROCESS | 17135 | RSS: Main: 172302336 08:12:15 INFO - PROCESS | 17135 | 08:12:17 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35695/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:12:18 INFO - PROCESS | 17135 | RSS: Main: 178544640 08:12:18 INFO - PROCESS | 17135 | 08:12:20 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35695/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:12:20 INFO - PROCESS | 17135 | RSS: Main: 188379136 08:12:20 INFO - PROCESS | 17135 | 08:12:23 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35695/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:12:24 INFO - PROCESS | 17135 | RSS: Main: 196890624 08:12:24 INFO - PROCESS | 17135 | 08:12:26 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35695/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:12:27 INFO - PROCESS | 17135 | RSS: Main: 194064384 08:12:27 INFO - PROCESS | 17135 | 08:12:28 INFO - PROCESS | 17135 | Cycle 1(1): loaded http://localhost:35695/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35695/tests/kraken/driver.html?testName=ai-astar) 08:12:29 INFO - PROCESS | 17135 | RSS: Main: 245342208 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | __start_tp_report 08:12:29 INFO - PROCESS | 17135 | _x_x_mozilla_page_load 08:12:29 INFO - PROCESS | 17135 | _x_x_mozilla_page_load_details 08:12:29 INFO - PROCESS | 17135 | |i|pagename|runs| 08:12:29 INFO - PROCESS | 17135 | |0;ai-astar;109;110;112;110;111;112;110;109;111;109 08:12:29 INFO - PROCESS | 17135 | |1;audio-beat-detection;141;117;116;154;122;120;121;122;150;122 08:12:29 INFO - PROCESS | 17135 | |2;audio-dft;160;161;160;160;160;159;161;162;159;167 08:12:29 INFO - PROCESS | 17135 | |3;audio-fft;76;75;76;108;75;75;75;76;98;75 08:12:29 INFO - PROCESS | 17135 | |4;audio-oscillator;82;81;119;82;82;80;103;80;81;113 08:12:29 INFO - PROCESS | 17135 | |5;imaging-gaussian-blur;117;116;117;117;116;117;118;117;117;117 08:12:29 INFO - PROCESS | 17135 | |6;imaging-darkroom;145;146;147;146;146;145;146;146;147;147 08:12:29 INFO - PROCESS | 17135 | |7;imaging-desaturate;96;95;96;95;95;95;95;96;95;95 08:12:29 INFO - PROCESS | 17135 | |8;json-parse-financial;51;50;50;50;51;51;49;50;52;50 08:12:29 INFO - PROCESS | 17135 | |9;json-stringify-tinderbox;60;61;63;61;62;61;60;64;61;61 08:12:29 INFO - PROCESS | 17135 | |10;stanford-crypto-aes;74;74;72;73;73;72;73;80;72;73 08:12:29 INFO - PROCESS | 17135 | |11;stanford-crypto-ccm;125;128;122;128;127;127;124;120;155;129 08:12:29 INFO - PROCESS | 17135 | |12;stanford-crypto-pbkdf2;152;155;150;156;155;152;151;153;150;150 08:12:29 INFO - PROCESS | 17135 | |13;stanford-crypto-sha256-iterative;62;67;64;66;62;62;61;61;61;68 08:12:29 INFO - PROCESS | 17135 | __end_tp_report 08:12:29 INFO - PROCESS | 17135 | __start_cc_report 08:12:29 INFO - PROCESS | 17135 | _x_x_mozilla_cycle_collect,1608 08:12:29 INFO - PROCESS | 17135 | __end_cc_report 08:12:29 INFO - PROCESS | 17135 | __startTimestamp1476976349112__endTimestamp 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | ------- Summary: start ------- 08:12:29 INFO - PROCESS | 17135 | Number of tests: 14 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.50 stddev:1.16 (1.0%) stddev-sans-first:1.13 08:12:29 INFO - PROCESS | 17135 | Values: 109.0 110.0 112.0 110.0 111.0 112.0 110.0 109.0 111.0 109.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:122.00 stddev:14.19 (11.6%) stddev-sans-first:14.31 08:12:29 INFO - PROCESS | 17135 | Values: 141.0 117.0 116.0 154.0 122.0 120.0 121.0 122.0 150.0 122.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#2] audio-dft Cycles:10 Average:160.90 Median:160.50 stddev:2.33 (1.5%) stddev-sans-first:2.45 08:12:29 INFO - PROCESS | 17135 | Values: 160.0 161.0 160.0 160.0 160.0 159.0 161.0 162.0 159.0 167.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.50 stddev:11.89 (15.8%) stddev-sans-first:12.48 08:12:29 INFO - PROCESS | 17135 | Values: 76.0 75.0 76.0 108.0 75.0 75.0 75.0 76.0 98.0 75.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:81.00 stddev:15.25 (18.8%) stddev-sans-first:15.87 08:12:29 INFO - PROCESS | 17135 | Values: 82.0 81.0 119.0 82.0 82.0 80.0 103.0 80.0 81.0 113.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 08:12:29 INFO - PROCESS | 17135 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 118.0 117.0 117.0 117.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 08:12:29 INFO - PROCESS | 17135 | Values: 145.0 146.0 147.0 146.0 146.0 145.0 146.0 146.0 147.0 147.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 08:12:29 INFO - PROCESS | 17135 | Values: 96.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.50 stddev:0.84 (1.7%) stddev-sans-first:0.87 08:12:29 INFO - PROCESS | 17135 | Values: 51.0 50.0 50.0 50.0 51.0 51.0 49.0 50.0 52.0 50.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:61.00 stddev:1.26 (2.1%) stddev-sans-first:1.24 08:12:29 INFO - PROCESS | 17135 | Values: 60.0 61.0 63.0 61.0 62.0 61.0 60.0 64.0 61.0 61.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:73.00 stddev:2.37 (3.2%) stddev-sans-first:2.51 08:12:29 INFO - PROCESS | 17135 | Values: 74.0 74.0 72.0 73.0 73.0 72.0 73.0 80.0 72.0 73.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:127.50 stddev:9.74 (7.6%) stddev-sans-first:10.25 08:12:29 INFO - PROCESS | 17135 | Values: 125.0 128.0 122.0 128.0 127.0 127.0 124.0 120.0 155.0 129.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:152.50 stddev:2.27 (1.5%) stddev-sans-first:2.40 08:12:29 INFO - PROCESS | 17135 | Values: 152.0 155.0 150.0 156.0 155.0 152.0 151.0 153.0 150.0 150.0 08:12:29 INFO - PROCESS | 17135 | 08:12:29 INFO - PROCESS | 17135 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.00 stddev:2.67 (4.2%) stddev-sans-first:2.79 08:12:29 INFO - PROCESS | 17135 | Values: 62.0 67.0 64.0 66.0 62.0 62.0 61.0 61.0 61.0 68.0 08:12:29 INFO - PROCESS | 17135 | -------- Summary: end -------- 08:12:29 INFO - PROCESS | 17135 | 08:12:30 INFO - TEST-INFO | 17135: exit 0 08:12:30 INFO - TEST-OK | kraken | took 60300ms 08:12:30 INFO - SUITE-END | took 1175s 08:12:30 INFO - Completed test suite (00:19:35) 08:12:30 INFO - javascript benchmark 08:12:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3119.3137509747853, 3141.5287706843965, 3177.5392568077614, 3208.631007434146, 3216.630087539934, 5578.107502799548, 5659.66042037478, 5662.263905692259, 5688.317401911776, 5721.084626459762, 5745.224394706714, 5795.275590551181, 5799.362070172288, 5802.970985145079, 5849.7367618457165, 5184.844454666347, 5206.635535512516, 5211.817586384477, 5256.973715131419, 5294.761735721882, 5027.0, 5029.3964724232965, 5318.787248510055, 5326.440723724011, 5351.224072509483, 5102.438731739506, 5109.233614957749, 5109.923351149736, 5134.229865520165, 5149.2791009258735, 5179.922301165486, 5187.766550505217, 5240.580753539708, 5260.6580572263065, 5260.684358938438, 4897.8285759998225, 4994.975025124888, 5137.0, 5157.0, 5232.058229518685, 5523.502884740393, 5531.778728850841, 5553.083741182741, 5573.303337082864, 5581.274434323553, 5972.790952316648, 6074.0, 6096.4817990470865, 6117.418845209698, 6125.908111378289, 5892.558058145613, 5894.528437724972, 6167.691615419211, 6169.691515424211, 6170.444659980623, 5631.380548139701, 5692.345380281279, 5696.943030569682, 5750.971245143747, 5756.827295181151, 6249.096915311894, 6296.0, 6299.590526615755, 6300.684965751786, 6306.24325080984, 5716.42835716425, 6019.668918209453, 6022.5784195105925, 6022.728977196037, 6053.243344581927, 5824.388439213906, 6005.729742161613, 6058.182145410401, 6068.029115341524, 6117.398686497024, 6092.45167934888, 6093.146959425684, 6096.603720758137, 6099.146119543268, 6130.570860039755, 6062.272527296753, 6090.512758979271, 6123.295820980555, 6125.203723515914, 6126.846828829315, 4484.730916145042, 4519.751413672279, 4554.863354099382, 4566.931496027562, 4593.035462552834, 4075.836966521306, 4085.55058943516, 4092.631663150331, 4106.568810274938, 4125.257453658371, 5676.233708449389, 5690.004249256446, 5698.601097923106, 5702.8289151325525, 5744.885102297931, 5872.236609240771, 5901.616394934315, 6025.728842202111, 6064.332923378425, 6109.877802443926, 5821.592488525847, 5831.300243970751, 5846.736896839652, 5851.356350801409, 5862.14998825168, 5095.439501654815, 5129.9230511542355, 5136.9743151284, 5153.665011774223, 5199.090159222196, 6017.819465416044, 6115.541334399849, 6125.938740612626, 6290.748370065146, 6307.4954003679595, 6030.7889223876955, 6112.777056696867, 6118.663473509, 6139.877202455837, 6144.1398204250545, 6201.627902325695, 6230.657313847782, 6238.532110091671, 6239.906401403891, 6255.155554000152, 5934.910976335272, 5981.1028345746745, 5993.203583565022, 6002.219711437485, 6028.0355143176885, 5740.741666625096, 5800.709964501673, 6002.969985150046, 6067.24159480065, 6100.75596976116, 6005.579609427473, 6009.459148676465, 6016.2479690038745, 6041.0, 6060.818175454743, 5963.224780778471, 5990.820275391745, 6008.279006519246, 6015.969920150371, 6018.668973206516, 5718.714064296852, 5762.4525670061275, 5765.135229715576, 5767.5674324425, 5958.731857066356, 5905.970470147622, 5959.523238141025, 5962.493188079062, 5973.850653733692, 5988.431099045583, 5612.971935140298, 5665.263515742927, 5671.914921276101, 5786.594938354275, 5790.39200883918], "value": 5578.546790367781, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.2022219448259, 621.6736213487922, 624.3319647976664, 637.238499992509, 640.6508452893173, 34641.0, 35657.64342356576, 38048.61951380486, 45137.77431112848, 46082.0, 47571.28643070357, 48073.27890081651, 48109.51890481094, 48128.0, 48138.0, 9247.953760231207, 9492.857607135898, 9497.857532137023, 9507.619695212183, 9511.143997040264, 8641.783955401103, 8839.07189745077, 10962.835557466604, 11241.213115081948, 11244.943775281154, 11614.535418583246, 11708.707282317968, 11712.414379281045, 11716.121290903217, 11722.882771172264, 11272.492737826735, 11286.209965302432, 11343.489542970587, 11348.716282092932, 11352.318860868332, 11259.099272058216, 11335.943320283428, 11353.886461135366, 11358.318500889973, 11359.488823002985, 44847.327290090674, 45055.09889802218, 45106.0, 45157.77421112874, 45227.773861130816, 3584.4623306503972, 3587.3542762302777, 3635.4546817977252, 3700.0749812546865, 4208.684348673862, 32468.675313247037, 34687.30625387503, 34902.0, 35016.47475287873, 35349.646503534896, 4644.558766917171, 4647.279671650899, 4682.555157260055, 4718.363020992157, 4745.406824146981, 396778.0, 400383.0, 402234.98882505693, 404638.0, 405271.0, 68049.0, 68302.65848670824, 68487.0, 68536.314636853, 68770.65614671895, 42261.0, 43164.568354316056, 75320.24679753243, 75358.24641753512, 75574.0, 19677.803221967883, 19771.802281977285, 19796.90101549483, 19797.901010495145, 19800.504987375432, 13120.147190432592, 13257.469701212036, 15767.842321576867, 15882.523524294289, 15888.205589720468, 22011.779882201292, 22617.095316187497, 22652.0, 23249.535009299718, 23313.18403855891, 7957.0, 8206.179382061746, 8348.165183481724, 8354.282344356703, 8397.328213743009, 941.9481928493861, 959.8512230604267, 1016.3800081950004, 1023.3194925374692, 1070.250824422907, 29312.267193319913, 29344.360302909354, 29575.112746617637, 29654.406911862072, 29707.702922970926, 1092.2190633696946, 1093.0435868614961, 1107.3854011023798, 1167.4046236419317, 1176.0650283025016, 15282.0, 15298.464553740565, 15322.463713769965, 15556.844431555766, 15593.844061559466, 572785.0, 573705.0, 575822.0, 578749.0, 579017.0, 62115.0, 62518.687406562676, 62536.687316563126, 62645.0, 62746.37253627497, 1319.459021801057, 1383.3429121167567, 1406.556934565629, 1443.9205843678699, 1470.4485817818318, 26826.731732682423, 29388.0, 29610.407791844475, 30474.0, 30525.542116867822, 52557.21164182464, 52858.471415285356, 53038.46961530489, 53122.2031669533, 53190.0, 402366.0, 403191.0, 406066.0, 409225.0, 409463.0, 32248.35503289968, 43501.78249108734, 47200.29199562078, 49519.0, 53927.730361347945, 49691.254631179836, 50271.74864125656, 50730.0, 53996.73001634967, 55079.44920550743, 44825.0, 45150.77424612856, 46241.76879115718, 46765.0, 48258.5174148254, 45107.0, 46426.30360544527, 46504.0, 47243.0, 47793.0, 40002.39996399998, 40084.79957600193, 41071.7946410266, 41118.79440602778, 41197.58802411937, 37473.0, 40283.99290017773, 40285.798571006955, 40539.0, 41215.381769272884, 30652.693473064985, 30768.84615576908, 30831.2292192688, 30946.0, 31293.217669557533], "value": 21916.79161753748, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.17793079360337, 434.3983582737908, 436.50238727850257, 439.9362092496587, 450.06386715631487, 17252.568685782862, 19519.0, 21736.673949890726, 21886.0, 22129.225477108284, 19071.71392429111, 19555.41333759986, 19639.705404418946, 20066.297679581217, 20301.0864511097, 5514.91727624086, 5585.74864131113, 5708.4291570843, 5710.885782284352, 5712.514436272901, 5985.6408615483, 5998.460138587527, 6041.063635136539, 6043.486303664194, 6074.908876366836, 5582.078956972095, 5648.915266271009, 6009.87980240395, 6020.879582408349, 6024.0662697282, 5583.637063590874, 5652.5195358394585, 5656.91514627281, 5659.4340565943485, 5660.377358490563, 5310.92033619496, 5470.152126420411, 5515.255440515519, 5520.861978450531, 5523.143912693498, 15430.305636246396, 15443.0, 15500.302486388027, 15563.766543501742, 15569.844301556952, 2293.4610366563843, 2458.791002764767, 2522.798176145904, 2540.4411029573466, 2586.237060067285, 11667.94166029173, 11765.705857353634, 12447.93776031114, 13136.949044076451, 16317.0, 1996.610660921121, 2096.874187548752, 2105.2526353144717, 2131.893405329727, 2203.7025001624734, 88277.55861220717, 89538.0, 90575.09424905732, 90744.09255907488, 96760.51619741856, 10569.365838049742, 11874.821877671842, 11886.702832429259, 11982.34097124649, 12066.155369124077, 15686.352932941876, 15847.762283565755, 16758.91620541914, 16770.91614541944, 17282.567935801453, 8657.610407531676, 8729.650813967497, 8819.955900220459, 8848.363052835246, 9441.150296473214, 8932.955335223282, 8950.418222815497, 8975.102489751103, 9224.8616270756, 9237.214836738818, 5148.639595228373, 5222.216667499905, 5238.816641417532, 5246.422893481714, 5389.461053894579, 2115.925942592002, 2172.3374370816737, 2188.3763127508582, 2189.0696454006984, 2205.2502149269326, 956.8325543029941, 1024.36489376587, 1096.714854137914, 1117.7485065860117, 1127.0476447401932, 20046.09792559338, 20273.08771105304, 20274.59450811005, 21753.78246217549, 21843.453913652287, 1029.4492446541065, 1029.7683021320142, 1035.4822588705647, 1095.0691911874842, 1107.7507560798917, 10504.42225677595, 11036.0, 11091.500882460308, 11174.553017879356, 11227.550897964153, 470255.0, 492534.0, 518290.0, 534688.0, 538959.0, 98855.0, 102004.48997754963, 104569.47715261528, 106357.0, 106836.0, 1040.6409788622914, 1103.7295862513574, 1108.2353176308322, 1134.359087115777, 1164.0512981919708, 17591.82408175902, 17687.82312176862, 18112.72830907511, 18261.178246978652, 18325.99207043572, 46384.7680761594, 46684.766576166905, 46948.7652561735, 47351.0, 47707.52292477031, 63722.36277637164, 64311.356886430534, 64614.35385646083, 65801.67099164473, 66943.33056669371, 9131.452112873249, 9306.767330816783, 9368.297377696565, 9582.952085239529, 9729.173020293072, 13243.735125297633, 13338.93330533341, 14438.77270432025, 14499.202543860189, 14788.63028424298, 12457.626271211879, 12562.12065155467, 12579.371031448574, 12602.180858244184, 12782.872171278541, 11721.12091593151, 11768.176227663982, 11883.346415947206, 12218.389080546114, 12226.144169908377, 12149.939250303692, 12478.625641230778, 12569.93715031419, 12777.23336599807, 12802.67993300175, 9506.90493095088, 9548.904510954802, 9595.904040959502, 9664.323497355117, 9833.16418104469, 11756.823647645464, 12725.109242352946, 12826.294553799631, 12859.614211573667, 12887.355632218165], "value": 9792.78486199584, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1453.4840131753233, 1534.2022148482786, 1572.9056256624604, 1734.8612111031118, 1843.751093602364, 8295.170482951702, 9126.133017363356, 9507.667231646894, 9545.522723863796, 9956.303058785888, 9822.116009559139, 9894.257930655212, 9896.059874311946, 9896.703098907026, 9900.554475048622, 2523.028633975914, 2551.6045013023013, 2559.104313490273, 2559.9360015999564, 2560.7183209846994, 2443.022790883643, 2540.720520742726, 2545.4654522550286, 2549.3244290263096, 2550.1711943618307, 2485.2792690119845, 2509.3475696318906, 2556.2970183199677, 2559.7440255974348, 2564.6281289213052, 2439.9634005489934, 2460.4463995601027, 2465.494573612405, 2467.9136230231948, 2474.6659201007815, 2452.1540068676277, 2479.938001549958, 2487.315988103268, 2488.0172331928966, 2489.4149874779414, 9384.718458446258, 9592.520373981273, 9594.280428967855, 9681.031896810333, 9694.806103877883, 5658.575606829463, 5893.528517718572, 5897.292324920994, 5900.97049514754, 5904.143899134601, 3816.713746469026, 3841.9231615367817, 3877.7479463835043, 3901.7854018028997, 3924.4113382992496, 1354.4852955876802, 1396.958091257264, 1428.250168661461, 1455.7889106079558, 1459.6277949123012, 13530.661733456644, 13554.186748795104, 13554.254516001616, 13649.658758531017, 13667.043306968517, 4669.2062349400685, 4991.326170966946, 5011.373578302712, 5014.07239660664, 5021.221710634857, 10651.414172220602, 10653.414062226497, 10659.413732244646, 10670.679879603624, 10674.73313167177, 5651.0, 5659.518940889988, 5661.858453538694, 5663.490285874291, 5666.263385759908, 4517.841875534406, 4538.546145385501, 5233.633645644844, 5256.369235691742, 5335.253064570963, 4164.937525937114, 4171.1032128092, 4190.832366705358, 4244.87265382039, 4246.8725938221905, 1214.504193881564, 1237.7215126596443, 1238.1675120821055, 1239.318374893823, 1249.3440943504734, 888.7778055486128, 931.5668214280257, 952.4047470331043, 990.4693508086907, 1044.1803184500131, 6197.628142311476, 6375.904361434582, 6569.605823650596, 6580.499360683527, 6730.596164230162, 1024.4416792847917, 1065.1372388365448, 1092.6885837536422, 1109.4966320635583, 1112.4938153140301, 4223.303154979455, 4258.190943720683, 4274.123804620045, 4329.328954012133, 4441.222786012435, 12072.517099316105, 12486.500539978299, 12515.374231288399, 12516.249025058525, 12545.184563003375, 12197.0, 12227.14409991292, 12455.564055258204, 12458.501659933683, 12509.311987840574, 9036.548172591343, 9047.773805654911, 9189.218916392181, 9206.493642849708, 9208.585613647268, 6326.746930122744, 6347.460465860454, 6440.710168042543, 6467.870642587028, 6821.2155602106395, 11064.0, 11134.554617815196, 11178.888211117785, 11182.944085279521, 11219.49512271966, 11309.095272378354, 11402.087832973177, 11444.141689373164, 11530.538778449103, 11552.711182220512, 8021.0, 8251.95874020626, 8296.253337199681, 8319.417640765088, 8345.374096942875, 8740.388172827841, 8988.685396011108, 9027.0, 9052.456852588866, 9066.09339066088, 8007.199280072179, 8084.191580841869, 8108.432409731262, 8129.634166462642, 8312.25189732927, 7973.322267607318, 7983.5209887406945, 8012.399070069885, 8025.678972841254, 8126.634301456567, 7062.929370706227, 7118.679659415442, 7129.572225666477, 7133.2153463117975, 7151.892721609076, 5596.188552659923, 5646.802361917313, 5673.8865222694485, 5683.857903552279, 5863.706814659336, 5777.422257774189, 5820.534357251495, 5883.294004719461, 5991.670458124845, 6016.308124565731], "value": 5035.639836245892, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.34362134783805, 341.19706610197227, 345.1423213314912, 352.4853713578176, 357.6012745788446, 5415.131779536058, 5498.395176530578, 5509.283793106895, 5530.225768392428, 5531.032069387861, 5436.456354364562, 5452.6455780374245, 5458.85364073544, 5466.863328416792, 5481.780728770845, 4061.7562946223175, 4334.891627709301, 4337.154254920292, 4344.196323680114, 4355.691483513743, 3797.6012518685557, 4143.461350024508, 4158.586080732551, 4306.61240488356, 4332.913341733163, 4269.743815371087, 4277.0, 4284.914301713964, 4286.0, 4290.163418133465, 4291.806868690901, 4296.957030429687, 4305.246581848195, 4310.913781724379, 4312.145777315769, 3619.457081437779, 3722.6091260417807, 3731.589525152231, 3852.8844134676006, 3871.1676989447406, 5303.337082864642, 5333.546648534879, 5346.786128554853, 5349.170878513836, 5355.8752661941035, 4943.382077240345, 4943.6539442239055, 4946.307516947631, 4968.950310496885, 4969.0807200668, 5571.4150014248735, 5666.5183459406, 5713.771449142029, 5731.885362292773, 5739.0, 4207.768572728499, 4224.514180869209, 4225.302825033866, 4236.957630423687, 4238.957610423918, 5493.670379777186, 5679.432056794329, 5937.526546969984, 5957.987142185839, 5979.252593425822, 4296.334068219431, 4326.956730432718, 4335.696501244883, 4343.609075183249, 4361.585649363369, 4412.823487060546, 4422.646188304928, 4427.697597249412, 4433.667474939392, 4440.755758433253, 4851.234796166222, 4957.050554919817, 4961.950380496149, 4977.975110124498, 4986.376702912136, 5720.370759216483, 5756.294541565458, 5764.884702305931, 5765.827025189251, 5773.364929857712, 1505.284989629917, 1519.475780855603, 1527.1676936069646, 1529.258309719785, 1531.5175719648275, 944.593824655391, 987.53585814667, 990.2869933647762, 991.3952488981715, 1001.0790073132736, 4917.950820491821, 4968.652194346361, 4971.825986090469, 4974.228994505857, 4987.0, 5122.544490118013, 5133.589312854962, 5135.563477104488, 5142.562882155059, 5145.665531740425, 4699.553542413465, 4977.228529577921, 4983.651144419929, 4986.675866068694, 5014.874628134253, 2754.628125203112, 2783.5963785251024, 2786.972130278671, 2798.818076825, 2809.845458499761, 5300.681959082391, 5328.333958255218, 5340.198970154508, 5375.892482150335, 5381.246625472437, 5120.436751957282, 5321.308229930084, 5335.33308336458, 5360.6247562671015, 5361.490658387447, 5349.839504814861, 5351.558206688439, 5355.349102194438, 5357.758900849393, 5357.785688572491, 5230.555402790653, 5244.737763111752, 5249.842504724865, 5251.610517214582, 5270.0, 4856.490068542752, 5090.0, 5123.513266239701, 5137.229415587692, 5146.970605878914, 5255.3693556771905, 5754.8849023018465, 5883.911741323969, 5891.469767720926, 6090.543209259235, 4392.4289842320295, 4398.208322501981, 4404.537523560109, 4409.7795110243715, 4414.602685758297, 5706.4864162225595, 5955.880882382241, 5961.463468287875, 5966.4487233320515, 5967.850803729768, 5758.395368486249, 5763.510101641407, 5778.508826749605, 5794.348610645946, 5815.156802263733, 5622.522085622604, 5769.826905192851, 5784.132380142844, 5792.3355256582445, 5794.391588883275, 5467.305135408036, 5731.7420716067045, 5740.971295143498, 5769.653820770767, 5774.220480235283, 5843.7954671587995, 5851.736671849862, 5855.648661080349, 5859.267591551056, 5870.561712380323, 5526.336839579276, 5720.771169153188, 5724.771009159587, 5745.569082318759, 5753.252077229933], "value": 4275.60826870637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.2678679368761, 828.1842385250529, 870.5125129927242, 903.439867282285, 948.4641177734582, 16019.118948457844, 16776.748348774778, 16788.076655783912, 16815.0, 16939.068351240676, 15541.922290388533, 15784.0, 15794.368225271004, 15813.683726325467, 15830.366785328573, 701.2706784943653, 719.6293908637056, 837.4640230252643, 846.3483117999152, 858.0475672004102, 855.2260204514924, 857.6869442653418, 858.5406807358065, 858.5578427110044, 858.6436628799041, 909.9909000910005, 910.635745701718, 910.8223896340216, 911.3802614222326, 911.7583840282331, 753.3445902065183, 775.3061010395693, 874.422880898607, 901.3104974694364, 904.7014485219928, 661.9106420633249, 662.5064327076311, 662.9237637671633, 662.9834254143636, 663.2107791727828, 15054.247287635575, 15085.849141508554, 15089.170095644735, 15101.395944162221, 15106.924465377713, 15475.535733928, 15534.61163470911, 15653.060816350942, 15709.528714138594, 16066.437674681447, 13510.0, 13864.722705545933, 14233.715325693533, 14362.64093397663, 14564.344604492717, 4741.383620129395, 4781.139394908915, 4781.593564547018, 4792.400949881265, 4933.358663373774, 88191.55904220502, 88253.55873220657, 90524.99999999935, 92455.0, 92563.53718231434, 11742.0, 11775.823362649568, 12141.482314710662, 12556.937215314048, 12635.555866507962, 21444.678329825067, 22296.219632313114, 23414.765852341257, 23481.0, 23512.0, 9549.665761698307, 9700.611975521042, 9817.509124543887, 9977.201823854219, 10017.248706347053, 5014.398272207359, 5435.673859568438, 5439.918401223985, 5464.016477034172, 5692.686902220335, 3643.2713457308646, 3736.5702944161226, 3792.58281589025, 3797.9430308544843, 3798.164403831153, 632.181325183888, 638.3106245255113, 638.776428250118, 639.6418005916702, 644.1432894250636, 703.9894401583878, 747.3423387419036, 779.3258831111161, 790.1900551934199, 838.8364268967615, 10513.684589462175, 11166.330020198815, 11199.776004480027, 11412.31526108436, 11694.649160525198, 756.7427074794597, 766.4673052228759, 777.5334799120483, 788.4520258420342, 842.616609442702, 5114.535443840376, 5408.486193811582, 5423.810166644149, 5582.33012038558, 5610.607257491936, 69732.65133674299, 70169.0, 90266.0, 119988.0, 122057.38971305087, 107036.39445408325, 107179.46410267899, 108426.0, 109645.45177274062, 110672.0, 91259.54370228107, 93077.0, 93772.53113734387, 93823.0617693828, 93963.0, 10213.642522511676, 10446.0, 10534.736631584272, 10604.681859544226, 10762.73093172677, 37171.628283717895, 47971.04057918892, 48768.0, 49629.503704963936, 52244.216336754216, 75243.0, 75625.0, 75992.0, 77532.0, 80113.0, 13819.792703109662, 14223.845655089579, 14400.783988239975, 14413.0, 14628.634284142981, 15870.2064896757, 15915.124668143364, 16530.66938661196, 17143.828561714225, 17231.3969011084, 15232.543023709306, 15249.237538122827, 15582.376704932129, 15637.374505020127, 16160.353585856434, 15213.163276019925, 15366.84633153654, 15559.922200388926, 15839.0, 16601.0, 14824.703505929605, 14975.0, 15069.849301506845, 15933.840661593236, 16221.513354599381, 12464.252144871336, 12477.812832807334, 12911.741765164832, 13228.470861165446, 13324.600261992156, 13113.344332783514, 13566.186028838301, 13636.250006249355, 13876.72246555043, 13998.090124141898], "value": 8220.92637298445, "unit": "score"}], "name": "dromaeo_css", "value": 7721.53771577367, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 112.0, 110.0, 111.0, 112.0, 110.0, 109.0, 111.0, 109.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 116.0, 154.0, 122.0, 120.0, 121.0, 122.0, 150.0, 122.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 160.0, 159.0, 161.0, 162.0, 159.0, 167.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 108.0, 75.0, 75.0, 75.0, 76.0, 98.0, 75.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 119.0, 82.0, 82.0, 80.0, 103.0, 80.0, 81.0, 113.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 50.0, 51.0, 51.0, 49.0, 50.0, 52.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 63.0, 61.0, 62.0, 61.0, 60.0, 64.0, 61.0, 61.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 72.0, 73.0, 73.0, 72.0, 73.0, 80.0, 72.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 128.0, 122.0, 128.0, 127.0, 127.0, 124.0, 120.0, 155.0, 129.0], "value": 128.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 155.0, 150.0, 156.0, 155.0, 152.0, 151.0, 153.0, 150.0, 150.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 64.0, 66.0, 62.0, 62.0, 61.0, 61.0, 61.0, 68.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1458.9, "alertThreshold": 2.0}]} 08:12:30 INFO - Return code: 0 08:12:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:12:30 INFO - # TBPL SUCCESS # 08:12:30 INFO - Running post-action listener: _resource_record_post_action 08:12:30 INFO - [mozharness: 2016-10-20 15:12:30.410179Z] Finished run-tests step (success) 08:12:30 INFO - Running post-run listener: _resource_record_post_run 08:12:30 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 2129920; Write bytes: 355733504; Read time: 364; Write time: 405952 08:12:30 INFO - TinderboxPrint: CPU usage
13.0% 08:12:30 INFO - TinderboxPrint: I/O read bytes / time
2,129,920 / 364 08:12:30 INFO - TinderboxPrint: I/O write bytes / time
355,733,504 / 405,952 08:12:30 INFO - TinderboxPrint: CPU idle
8,281.6 (87.0%) 08:12:30 INFO - TinderboxPrint: CPU user
1,190.1 (12.5%) 08:12:30 INFO - TinderboxPrint: Swap in / out
0 / 0 08:12:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 108281856; Read time: 0; Write time: 172660 08:12:30 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 262144; Write bytes: 247451648; Read time: 24; Write time: 233292 08:12:31 INFO - Running post-run listener: _upload_blobber_files 08:12:31 INFO - Blob upload gear active. 08:12:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:12:31 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 08:12:31 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'] 08:12:31 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 08:12:31 INFO - (blobuploader) - INFO - Open directory for files ... 08:12:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:12:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:12:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:12:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:12:37 INFO - (blobuploader) - INFO - Done attempting. 08:12:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:12:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:12:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:12:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:12:37 INFO - (blobuploader) - INFO - Done attempting. 08:12:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:12:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:12:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:12:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:12:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:12:38 INFO - (blobuploader) - INFO - Done attempting. 08:12:38 INFO - (blobuploader) - INFO - Iteration through files over. 08:12:38 INFO - Return code: 0 08:12:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:12:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:12:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc203bd0eccbbae77beaf7f1b23fa5342401a975cef1d5d14da73d87f9ce5481a9f4fec0b4db882efbb9b4a00a3b05d86677ea44353235199c11954aefcb84c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c42b34fd428cba936e212626c1c3886c5b51b071a4239654bff54c979a1e25c22b8148b2a0affb2a437772eaea7c0587719a312b6f2b2c773e3fdcfd4d4c085", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fb1519a4120963e80bce11913a3dbb643524c4af27467f7ecda94ed979136b327745d98dadea8e330144af2afa787b2bffe9203043a0ed14f2e369764ef24fe"} 08:12:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:12:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:12:38 INFO - Contents: 08:12:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc203bd0eccbbae77beaf7f1b23fa5342401a975cef1d5d14da73d87f9ce5481a9f4fec0b4db882efbb9b4a00a3b05d86677ea44353235199c11954aefcb84c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c42b34fd428cba936e212626c1c3886c5b51b071a4239654bff54c979a1e25c22b8148b2a0affb2a437772eaea7c0587719a312b6f2b2c773e3fdcfd4d4c085", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fb1519a4120963e80bce11913a3dbb643524c4af27467f7ecda94ed979136b327745d98dadea8e330144af2afa787b2bffe9203043a0ed14f2e369764ef24fe"} 08:12:38 INFO - Running post-run listener: copy_logs_to_upload_dir 08:12:38 INFO - Copying logs to upload dir... 08:12:38 INFO - mkdir: /builds/slave/test/build/upload/logs 08:12:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.151441 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-20 08:12:39.031497) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 08:12:39.034614) ========= 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=af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338 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/fc203bd0eccbbae77beaf7f1b23fa5342401a975cef1d5d14da73d87f9ce5481a9f4fec0b4db882efbb9b4a00a3b05d86677ea44353235199c11954aefcb84c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c42b34fd428cba936e212626c1c3886c5b51b071a4239654bff54c979a1e25c22b8148b2a0affb2a437772eaea7c0587719a312b6f2b2c773e3fdcfd4d4c085", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fb1519a4120963e80bce11913a3dbb643524c4af27467f7ecda94ed979136b327745d98dadea8e330144af2afa787b2bffe9203043a0ed14f2e369764ef24fe"} build_url:https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011374 build_url: 'https://queue.taskcluster.net/v1/task/CM3OcPCTT9Oe3aIovikyIg/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/fc203bd0eccbbae77beaf7f1b23fa5342401a975cef1d5d14da73d87f9ce5481a9f4fec0b4db882efbb9b4a00a3b05d86677ea44353235199c11954aefcb84c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c42b34fd428cba936e212626c1c3886c5b51b071a4239654bff54c979a1e25c22b8148b2a0affb2a437772eaea7c0587719a312b6f2b2c773e3fdcfd4d4c085", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fb1519a4120963e80bce11913a3dbb643524c4af27467f7ecda94ed979136b327745d98dadea8e330144af2afa787b2bffe9203043a0ed14f2e369764ef24fe"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 08:12:39.059677) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 08:12:39.060012) ========= 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=af724c981e2a0f424dbabf99000001e7-1476975098.768852-455807338 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004603 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 08:12:39.146435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 08:12:39.146763) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 08:12:39.147093) ========= ========= Total master_lag: 0.17 =========