builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1479650235.77 results: success (0) buildid: 20161120030721 builduid: 20e3adea1c97430d8015cf4f8cff1451 revision: 499c1152aebeb550ade64a98aca00b42b1cd3eb2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:15.773755) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:15.774153) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:15.774429) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:15.827412) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:15.827743) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-20 05:57:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.6M=0s 2016-11-20 05:57:16 (42.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.175306 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:16.028442) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:16.028766) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.068036 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:16.122736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:16.123285) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 499c1152aebeb550ade64a98aca00b42b1cd3eb2 --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-beta --rev 499c1152aebeb550ade64a98aca00b42b1cd3eb2 --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=5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-20 05:57:16,254 truncating revision to first 12 chars 2016-11-20 05:57:16,254 Setting DEBUG logging. 2016-11-20 05:57:16,254 attempt 1/10 2016-11-20 05:57:16,254 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/499c1152aebe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-20 05:57:16,557 unpacking tar archive at: mozilla-beta-499c1152aebe/testing/mozharness/ program finished with exit code 0 elapsedTime=0.682606 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:16.820658) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:16.820972) ========= script_repo_revision: 499c1152aebeb550ade64a98aca00b42b1cd3eb2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:16.821381) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:16.821644) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-20 05:57:16.832124) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-20 05:57:16.832510) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:57:17 INFO - MultiFileLogger online at 20161120 05:57:17 in /builds/slave/test 05:57:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:57:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:57:17 INFO - {'append_to_log': False, 05:57:17 INFO - 'base_work_dir': '/builds/slave/test', 05:57:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:57:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:57:17 INFO - 'branch': 'Mozilla-Beta', 05:57:17 INFO - 'buildbot_json_path': 'buildprops.json', 05:57:17 INFO - 'config_files': ('talos/linux_config.py',), 05:57:17 INFO - 'default_actions': ('clobber', 05:57:17 INFO - 'read-buildbot-config', 05:57:17 INFO - 'download-and-extract', 05:57:17 INFO - 'populate-webroot', 05:57:17 INFO - 'create-virtualenv', 05:57:17 INFO - 'install', 05:57:17 INFO - 'run-tests'), 05:57:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:57:17 INFO - 'download_minidump_stackwalk': True, 05:57:17 INFO - 'download_symbols': 'ondemand', 05:57:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:57:17 INFO - 'tooltool.py': '/tools/tooltool.py', 05:57:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:57:17 INFO - '/tools/misc-python/virtualenv.py')}, 05:57:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:57:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:57:17 INFO - 'installer_path': 'installer.exe', 05:57:17 INFO - 'log_level': 'info', 05:57:17 INFO - 'log_name': 'talos', 05:57:17 INFO - 'log_to_console': True, 05:57:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:57:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:57:17 INFO - 'opt_config_files': (), 05:57:17 INFO - 'pip_index': False, 05:57:17 INFO - 'sps_profile': False, 05:57:17 INFO - 'sps_profile_interval': 0, 05:57:17 INFO - 'suite': 'dromaeojs', 05:57:17 INFO - 'system_bits': '32', 05:57:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:57:17 INFO - 'title': 'talos-linux64-ix-051', 05:57:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:57:17 INFO - 'use_talos_json': True, 05:57:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:57:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:57:17 INFO - 'work_dir': 'build'} 05:57:17 INFO - [mozharness: 2016-11-20 13:57:17.194805Z] Running clobber step. 05:57:17 INFO - Running pre-action listener: _resource_record_pre_action 05:57:17 INFO - Running main action method: clobber 05:57:17 INFO - rmtree: /builds/slave/test/build 05:57:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:57:18 INFO - Running post-action listener: _resource_record_post_action 05:57:18 INFO - [mozharness: 2016-11-20 13:57:18.145641Z] Finished clobber step (success) 05:57:18 INFO - [mozharness: 2016-11-20 13:57:18.145787Z] Running read-buildbot-config step. 05:57:18 INFO - Running pre-action listener: _resource_record_pre_action 05:57:18 INFO - Running main action method: read_buildbot_config 05:57:18 INFO - Using buildbot properties: 05:57:18 INFO - { 05:57:18 INFO - "project": "", 05:57:18 INFO - "product": "firefox", 05:57:18 INFO - "script_repo_revision": "production", 05:57:18 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:57:18 INFO - "repository": "", 05:57:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:57:18 INFO - "buildid": "20161120030721", 05:57:18 INFO - "pgo_build": "True", 05:57:18 INFO - "basedir": "/builds/slave/test", 05:57:18 INFO - "buildnumber": 21, 05:57:18 INFO - "slavename": "talos-linux64-ix-051", 05:57:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:57:18 INFO - "platform": "ubuntu64_hw", 05:57:18 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:57:18 INFO - "branch": "mozilla-beta", 05:57:18 INFO - "repo_path": "releases/mozilla-beta", 05:57:18 INFO - "revision": "499c1152aebeb550ade64a98aca00b42b1cd3eb2", 05:57:18 INFO - "stage_platform": "linux64-pgo", 05:57:18 INFO - "builduid": "20e3adea1c97430d8015cf4f8cff1451", 05:57:18 INFO - "slavebuilddir": "test" 05:57:18 INFO - } 05:57:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 05:57:18 INFO - Running post-action listener: _resource_record_post_action 05:57:18 INFO - [mozharness: 2016-11-20 13:57:18.149451Z] Finished read-buildbot-config step (success) 05:57:18 INFO - [mozharness: 2016-11-20 13:57:18.149584Z] Running download-and-extract step. 05:57:18 INFO - Running pre-action listener: _resource_record_pre_action 05:57:18 INFO - Running main action method: download_and_extract 05:57:18 INFO - mkdir: /builds/slave/test/build/tests 05:57: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')]} 05:57:18 INFO - trying https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 05:57:18 INFO - Downloading https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 05:57:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:57:18 INFO - Downloaded 1327 bytes. 05:57:18 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 05:57:18 INFO - Using the following test package requirements: 05:57:18 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 05:57:18 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:57:18 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:57:18 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:57:18 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 05:57:18 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:57:18 INFO - u'jsshell-linux-x86_64.zip'], 05:57:18 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:57:18 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:57:18 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 05:57:18 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:57:18 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 05:57:18 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:57:18 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 05:57:18 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:57:18 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:57:18 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:57:18 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:57:18 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:57:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 05:57:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:57:18 INFO - Fetch https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 05:57:19 INFO - Expected file size: 21962321 05:57:23 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:57:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 05:57:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:57:23 INFO - Fetch https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 05:57:24 INFO - Expected file size: 21962321 05:57:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 05:57:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:57:29 INFO - Fetch https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 05:57:29 INFO - Expected file size: 14414089 05:57:30 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')]} 05:57:30 INFO - trying https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 05:57:30 INFO - Downloading https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 05:57:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:57:31 INFO - Downloaded 56952433 bytes. 05:57:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 05:57:31 INFO - mkdir: /builds/slave/test/properties 05:57:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:57:31 INFO - Writing to file /builds/slave/test/properties/build_url 05:57:31 INFO - Contents: 05:57:31 INFO - build_url:https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 05:57:32 INFO - Running post-action listener: _resource_record_post_action 05:57:32 INFO - Running post-action listener: set_extra_try_arguments 05:57:32 INFO - [mozharness: 2016-11-20 13:57:32.146369Z] Finished download-and-extract step (success) 05:57:32 INFO - [mozharness: 2016-11-20 13:57:32.146598Z] Running populate-webroot step. 05:57:32 INFO - Running pre-action listener: _resource_record_pre_action 05:57:32 INFO - Running main action method: populate_webroot 05:57:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:57:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:57:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:57:32 INFO - '--tppagecycles', 05:57:32 INFO - '7'], 05:57:32 INFO - 'tests': ['tsvgm']}}, 05:57:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:57:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:57:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:57:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:57:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:32 INFO - 'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:32 INFO - 'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['damp', 'tps']}, 05:57:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:32 INFO - 'tests': ['damp', 'tps']}, 05:57:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['dromaeo_dom']}, 05:57:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:57:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['basic_compositor_video']}, 05:57:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:57:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['a11yr', 05:57:32 INFO - 'ts_paint', 05:57:32 INFO - 'tpaint', 05:57:32 INFO - 'sessionrestore', 05:57:32 INFO - 'sessionrestore_no_auto_restore', 05:57:32 INFO - 'tabpaint']}, 05:57:32 INFO - 'other-e10s': {'tests': ['a11yr', 05:57:32 INFO - 'ts_paint', 05:57:32 INFO - 'tpaint', 05:57:32 INFO - 'sessionrestore', 05:57:32 INFO - 'sessionrestore_no_auto_restore', 05:57:32 INFO - 'tabpaint']}, 05:57:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:57:32 INFO - 'ts_paint', 05:57:32 INFO - 'tpaint', 05:57:32 INFO - 'sessionrestore', 05:57:32 INFO - 'sessionrestore_no_auto_restore', 05:57:32 INFO - 'tabpaint']}, 05:57:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:57:32 INFO - 'ts_paint', 05:57:32 INFO - 'tpaint', 05:57:32 INFO - 'sessionrestore', 05:57:32 INFO - 'sessionrestore_no_auto_restore', 05:57:32 INFO - 'tabpaint']}, 05:57:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['a11yr', 05:57:32 INFO - 'ts_paint', 05:57:32 INFO - 'tpaint', 05:57:32 INFO - 'sessionrestore', 05:57:32 INFO - 'sessionrestore_no_auto_restore', 05:57:32 INFO - 'tabpaint']}, 05:57:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['a11yr', 05:57:32 INFO - 'ts_paint', 05:57:32 INFO - 'tpaint', 05:57:32 INFO - 'sessionrestore', 05:57:32 INFO - 'sessionrestore_no_auto_restore', 05:57:32 INFO - 'tabpaint']}, 05:57:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['tsvgx', 05:57:32 INFO - 'tsvgr_opacity', 05:57:32 INFO - 'tart', 05:57:32 INFO - 'tscrollx', 05:57:32 INFO - 'cart']}, 05:57:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:57:32 INFO - 'tsvgr_opacity', 05:57:32 INFO - 'tart', 05:57:32 INFO - 'tscrollx', 05:57:32 INFO - 'cart']}, 05:57:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:32 INFO - 'talos_options': ['--disable-e10s'], 05:57:32 INFO - 'tests': ['tp5o']}, 05:57:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:32 INFO - 'tests': ['tp5o']}, 05:57:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:32 INFO - 'talos_options': ['--disable-e10s', 05:57:32 INFO - '--xperf_path', 05:57:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:32 INFO - 'tests': ['tp5n']}, 05:57:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:32 INFO - 'talos_options': ['--xperf_path', 05:57:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:32 INFO - 'tests': ['tp5n']}}, 05:57:32 INFO - 'talos.zip': {'path': '', 05:57:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:57:32 INFO - Running post-action listener: _resource_record_post_action 05:57:32 INFO - [mozharness: 2016-11-20 13:57:32.164146Z] Finished populate-webroot step (success) 05:57:32 INFO - [mozharness: 2016-11-20 13:57:32.164240Z] Running create-virtualenv step. 05:57:32 INFO - Running pre-action listener: _resource_record_pre_action 05:57:32 INFO - Running main action method: create_virtualenv 05:57:32 INFO - Creating virtualenv /builds/slave/test/build/venv 05:57:32 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 05:57:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:57:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:57:32 INFO - Using real prefix '/usr' 05:57:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:57:32 INFO - Installing distribute.............................................................................................................................................................................................done. 05:57:34 INFO - Installing pip.................done. 05:57:34 INFO - Return code: 0 05:57:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:57:34 INFO - Using env: {'DISPLAY': ':0', 05:57:34 INFO - 'HOME': '/home/cltbld', 05:57:34 INFO - 'LANG': 'en_US.UTF-8', 05:57:34 INFO - 'LANGUAGE': 'en_US:en', 05:57:34 INFO - 'LOGNAME': 'cltbld', 05:57:34 INFO - 'MAIL': '/var/mail/cltbld', 05:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:34 INFO - 'MOZ_NO_REMOTE': '1', 05:57:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:34 INFO - 'NO_EM_RESTART': '1', 05:57:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:34 INFO - 'PWD': '/builds/slave/test', 05:57:34 INFO - 'SHELL': '/bin/bash', 05:57:34 INFO - 'SHLVL': '1', 05:57:34 INFO - 'TERM': 'linux', 05:57:34 INFO - 'TMOUT': '86400', 05:57:34 INFO - 'USER': 'cltbld', 05:57:34 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:34 INFO - '_': '/tools/buildbot/bin/python'} 05:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:35 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 05:57:35 INFO - Cleaning up... 05:57:35 INFO - Return code: 0 05:57:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:35 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 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:35 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 05:57:35 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 05:57:35 INFO - Using env: {'DISPLAY': ':0', 05:57:35 INFO - 'HOME': '/home/cltbld', 05:57:35 INFO - 'LANG': 'en_US.UTF-8', 05:57:35 INFO - 'LANGUAGE': 'en_US:en', 05:57:35 INFO - 'LOGNAME': 'cltbld', 05:57:35 INFO - 'MAIL': '/var/mail/cltbld', 05:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:35 INFO - 'MOZ_NO_REMOTE': '1', 05:57:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:35 INFO - 'NO_EM_RESTART': '1', 05:57:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:35 INFO - 'PWD': '/builds/slave/test', 05:57:35 INFO - 'SHELL': '/bin/bash', 05:57:35 INFO - 'SHLVL': '1', 05:57:35 INFO - 'TERM': 'linux', 05:57:35 INFO - 'TMOUT': '86400', 05:57:35 INFO - 'USER': 'cltbld', 05:57:35 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:35 INFO - '_': '/tools/buildbot/bin/python'} 05:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:35 INFO - Downloading/unpacking psutil>=3.1.1 05:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:57:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:37 INFO - Installing collected packages: psutil 05:57:37 INFO - Running setup.py install for psutil 05:57:37 INFO - building 'psutil._psutil_linux' extension 05:57:37 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 05:57:38 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 05:57:38 INFO - building 'psutil._psutil_posix' extension 05:57:38 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 05:57:38 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 05:57:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:39 INFO - Successfully installed psutil 05:57:39 INFO - Cleaning up... 05:57:39 INFO - Return code: 0 05:57:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:57:39 INFO - Using env: {'DISPLAY': ':0', 05:57:39 INFO - 'HOME': '/home/cltbld', 05:57:39 INFO - 'LANG': 'en_US.UTF-8', 05:57:39 INFO - 'LANGUAGE': 'en_US:en', 05:57:39 INFO - 'LOGNAME': 'cltbld', 05:57:39 INFO - 'MAIL': '/var/mail/cltbld', 05:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:39 INFO - 'MOZ_NO_REMOTE': '1', 05:57:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:39 INFO - 'NO_EM_RESTART': '1', 05:57:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:39 INFO - 'PWD': '/builds/slave/test', 05:57:39 INFO - 'SHELL': '/bin/bash', 05:57:39 INFO - 'SHLVL': '1', 05:57:39 INFO - 'TERM': 'linux', 05:57:39 INFO - 'TMOUT': '86400', 05:57:39 INFO - 'USER': 'cltbld', 05:57:39 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:39 INFO - '_': '/tools/buildbot/bin/python'} 05:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:57:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:57:41 INFO - Installing collected packages: mozsystemmonitor 05:57:41 INFO - Running setup.py install for mozsystemmonitor 05:57:41 INFO - Successfully installed mozsystemmonitor 05:57:41 INFO - Cleaning up... 05:57:41 INFO - Return code: 0 05:57:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:57:41 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')]} 05:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:41 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')]} 05:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:41 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 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:41 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 05:57:41 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 05:57:41 INFO - Using env: {'DISPLAY': ':0', 05:57:41 INFO - 'HOME': '/home/cltbld', 05:57:41 INFO - 'LANG': 'en_US.UTF-8', 05:57:41 INFO - 'LANGUAGE': 'en_US:en', 05:57:41 INFO - 'LOGNAME': 'cltbld', 05:57:41 INFO - 'MAIL': '/var/mail/cltbld', 05:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:41 INFO - 'MOZ_NO_REMOTE': '1', 05:57:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:41 INFO - 'NO_EM_RESTART': '1', 05:57:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:41 INFO - 'PWD': '/builds/slave/test', 05:57:41 INFO - 'SHELL': '/bin/bash', 05:57:41 INFO - 'SHLVL': '1', 05:57:41 INFO - 'TERM': 'linux', 05:57:41 INFO - 'TMOUT': '86400', 05:57:41 INFO - 'USER': 'cltbld', 05:57:41 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:41 INFO - '_': '/tools/buildbot/bin/python'} 05:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:41 INFO - Downloading/unpacking jsonschema==2.5.1 05:57:41 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 05:57:41 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 05:57:41 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 05:57:41 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 05:57:41 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 05:57:41 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 05:57:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:57:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:43 INFO - Downloading functools32-3.2.3-2.tar.gz 05:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:57:43 INFO - warning: no files found matching '*.txt' 05:57:43 INFO - no previously-included directories found matching 'build' 05:57:43 INFO - no previously-included directories found matching 'dist' 05:57:43 INFO - no previously-included directories found matching '.git*' 05:57:43 INFO - Installing collected packages: jsonschema, functools32 05:57:44 INFO - Running setup.py install for functools32 05:57:44 INFO - warning: no files found matching '*.txt' 05:57:44 INFO - no previously-included directories found matching 'build' 05:57:44 INFO - no previously-included directories found matching 'dist' 05:57:44 INFO - no previously-included directories found matching '.git*' 05:57:44 INFO - Successfully installed jsonschema functools32 05:57:44 INFO - Cleaning up... 05:57:44 INFO - Return code: 0 05:57:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:57:44 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')]} 05:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:44 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')]} 05:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:44 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 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:44 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 05:57:44 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 05:57:44 INFO - Using env: {'DISPLAY': ':0', 05:57:44 INFO - 'HOME': '/home/cltbld', 05:57:44 INFO - 'LANG': 'en_US.UTF-8', 05:57:44 INFO - 'LANGUAGE': 'en_US:en', 05:57:44 INFO - 'LOGNAME': 'cltbld', 05:57:44 INFO - 'MAIL': '/var/mail/cltbld', 05:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:44 INFO - 'MOZ_NO_REMOTE': '1', 05:57:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:44 INFO - 'NO_EM_RESTART': '1', 05:57:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:44 INFO - 'PWD': '/builds/slave/test', 05:57:44 INFO - 'SHELL': '/bin/bash', 05:57:44 INFO - 'SHLVL': '1', 05:57:44 INFO - 'TERM': 'linux', 05:57:44 INFO - 'TMOUT': '86400', 05:57:44 INFO - 'USER': 'cltbld', 05:57:44 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:44 INFO - '_': '/tools/buildbot/bin/python'} 05:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:57:44 INFO - Cleaning up... 05:57:44 INFO - Return code: 0 05:57:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:57:44 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')]} 05:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:44 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')]} 05:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:44 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 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:44 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 05:57:44 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 05:57:44 INFO - Using env: {'DISPLAY': ':0', 05:57:44 INFO - 'HOME': '/home/cltbld', 05:57:44 INFO - 'LANG': 'en_US.UTF-8', 05:57:44 INFO - 'LANGUAGE': 'en_US:en', 05:57:44 INFO - 'LOGNAME': 'cltbld', 05:57:44 INFO - 'MAIL': '/var/mail/cltbld', 05:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:44 INFO - 'MOZ_NO_REMOTE': '1', 05:57:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:44 INFO - 'NO_EM_RESTART': '1', 05:57:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:44 INFO - 'PWD': '/builds/slave/test', 05:57:44 INFO - 'SHELL': '/bin/bash', 05:57:44 INFO - 'SHLVL': '1', 05:57:44 INFO - 'TERM': 'linux', 05:57:44 INFO - 'TMOUT': '86400', 05:57:44 INFO - 'USER': 'cltbld', 05:57:44 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:44 INFO - '_': '/tools/buildbot/bin/python'} 05:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:44 INFO - Downloading/unpacking blobuploader==1.2.4 05:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:46 INFO - Downloading blobuploader-1.2.4.tar.gz 05:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:57:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:57:46 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 05:57:46 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 05:57:46 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 05:57:46 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 05:57:46 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 05:57:46 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 05:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:57:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:47 INFO - Downloading docopt-0.6.1.tar.gz 05:57:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:57:47 INFO - Installing collected packages: blobuploader, requests, docopt 05:57:47 INFO - Running setup.py install for blobuploader 05:57:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:57:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:57:47 INFO - Running setup.py install for requests 05:57:47 INFO - Running setup.py install for docopt 05:57:47 INFO - Successfully installed blobuploader requests docopt 05:57:47 INFO - Cleaning up... 05:57:47 INFO - Return code: 0 05:57:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:47 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 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:47 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 05:57:47 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 05:57:47 INFO - Using env: {'DISPLAY': ':0', 05:57:47 INFO - 'HOME': '/home/cltbld', 05:57:47 INFO - 'LANG': 'en_US.UTF-8', 05:57:47 INFO - 'LANGUAGE': 'en_US:en', 05:57:47 INFO - 'LOGNAME': 'cltbld', 05:57:47 INFO - 'MAIL': '/var/mail/cltbld', 05:57:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:47 INFO - 'MOZ_NO_REMOTE': '1', 05:57:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:47 INFO - 'NO_EM_RESTART': '1', 05:57:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:47 INFO - 'PWD': '/builds/slave/test', 05:57:47 INFO - 'SHELL': '/bin/bash', 05:57:47 INFO - 'SHLVL': '1', 05:57:47 INFO - 'TERM': 'linux', 05:57:47 INFO - 'TMOUT': '86400', 05:57:47 INFO - 'USER': 'cltbld', 05:57:47 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:47 INFO - '_': '/tools/buildbot/bin/python'} 05:57:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:48 INFO - Running setup.py (path:/tmp/pip-ZUef2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:48 INFO - Running setup.py (path:/tmp/pip-rPXgAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:48 INFO - Running setup.py (path:/tmp/pip-lQAuah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:48 INFO - Running setup.py (path:/tmp/pip-2A7H4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:48 INFO - Running setup.py (path:/tmp/pip-_oEn_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:48 INFO - Running setup.py (path:/tmp/pip-YT7wdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:48 INFO - Running setup.py (path:/tmp/pip-JPeN6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:48 INFO - Running setup.py (path:/tmp/pip-OXsxPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:48 INFO - Running setup.py (path:/tmp/pip-VKZWzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:48 INFO - Running setup.py (path:/tmp/pip-uzS_Jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:48 INFO - Running setup.py (path:/tmp/pip-JjFqC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:48 INFO - Running setup.py (path:/tmp/pip-j2Z5NU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:49 INFO - Running setup.py (path:/tmp/pip-JSm5jF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:49 INFO - Running setup.py (path:/tmp/pip-AIWfd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:49 INFO - Running setup.py (path:/tmp/pip-1giXw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:49 INFO - Running setup.py (path:/tmp/pip-LyXBN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:49 INFO - Running setup.py (path:/tmp/pip-3QOYBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:57:49 INFO - Running setup.py install for manifestparser 05:57:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:57:49 INFO - Running setup.py install for mozcrash 05:57:49 INFO - Running setup.py install for mozdebug 05:57:49 INFO - Running setup.py install for mozdevice 05:57:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:57:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:57:49 INFO - Running setup.py install for mozfile 05:57:49 INFO - Running setup.py install for mozhttpd 05:57:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Running setup.py install for mozinfo 05:57:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Running setup.py install for mozInstall 05:57:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Running setup.py install for mozleak 05:57:50 INFO - Running setup.py install for mozlog 05:57:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Running setup.py install for moznetwork 05:57:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Running setup.py install for mozprocess 05:57:50 INFO - Running setup.py install for mozprofile 05:57:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Running setup.py install for mozrunner 05:57:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:57:50 INFO - Running setup.py install for mozscreenshot 05:57:50 INFO - Running setup.py install for moztest 05:57:51 INFO - Running setup.py install for mozversion 05:57:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:57:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:57:51 INFO - Cleaning up... 05:57:51 INFO - Return code: 0 05:57:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:51 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')]} 05:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:51 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')]} 05:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:51 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 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:51 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 05:57:51 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 05:57:51 INFO - Using env: {'DISPLAY': ':0', 05:57:51 INFO - 'HOME': '/home/cltbld', 05:57:51 INFO - 'LANG': 'en_US.UTF-8', 05:57:51 INFO - 'LANGUAGE': 'en_US:en', 05:57:51 INFO - 'LOGNAME': 'cltbld', 05:57:51 INFO - 'MAIL': '/var/mail/cltbld', 05:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:51 INFO - 'MOZ_NO_REMOTE': '1', 05:57:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:51 INFO - 'NO_EM_RESTART': '1', 05:57:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:51 INFO - 'PWD': '/builds/slave/test', 05:57:51 INFO - 'SHELL': '/bin/bash', 05:57:51 INFO - 'SHLVL': '1', 05:57:51 INFO - 'TERM': 'linux', 05:57:51 INFO - 'TMOUT': '86400', 05:57:51 INFO - 'USER': 'cltbld', 05:57:51 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:51 INFO - '_': '/tools/buildbot/bin/python'} 05:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:51 INFO - Running setup.py (path:/tmp/pip-cu4O3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:51 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)) 05:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:51 INFO - Running setup.py (path:/tmp/pip-S5TZYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:51 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)) 05:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:51 INFO - Running setup.py (path:/tmp/pip-gkdlTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:51 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)) 05:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:51 INFO - Running setup.py (path:/tmp/pip-Jky6bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:51 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)) 05:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:51 INFO - Running setup.py (path:/tmp/pip-74FJUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:51 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)) 05:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:51 INFO - Running setup.py (path:/tmp/pip-kFd55O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:51 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)) 05:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:51 INFO - Running setup.py (path:/tmp/pip-cRCZ29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:51 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)) 05:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:51 INFO - Running setup.py (path:/tmp/pip-_uktrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:51 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)) 05:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:52 INFO - Running setup.py (path:/tmp/pip-5jn4yW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:52 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)) 05:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:52 INFO - Running setup.py (path:/tmp/pip-tzMFF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:52 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)) 05:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:52 INFO - Running setup.py (path:/tmp/pip-XeUGlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:52 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)) 05:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:52 INFO - Running setup.py (path:/tmp/pip-NI0H5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:52 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)) 05:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:52 INFO - Running setup.py (path:/tmp/pip-LGxW20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:52 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)) 05:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:52 INFO - Running setup.py (path:/tmp/pip-Yj8hdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:52 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)) 05:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:52 INFO - Running setup.py (path:/tmp/pip-giiMV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:52 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)) 05:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:52 INFO - Running setup.py (path:/tmp/pip-JOowJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:52 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)) 05:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:52 INFO - Running setup.py (path:/tmp/pip-z3hwN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:52 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)) 05:57:52 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)) 05:57:52 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)) 05:57:52 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)) 05:57:52 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)) 05:57:52 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)) 05:57:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:57:52 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 05:57:52 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 05:57:52 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 05:57:52 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 05:57:52 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 05:57:52 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 05:57:54 INFO - Downloading blessings-1.6.tar.gz 05:57:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:57:54 INFO - Installing collected packages: blessings 05:57:54 INFO - Running setup.py install for blessings 05:57:54 INFO - Successfully installed blessings 05:57:54 INFO - Cleaning up... 05:57:54 INFO - Return code: 0 05:57:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:57:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:55 INFO - Reading from file tmpfile_stdout 05:57:55 INFO - Current package versions: 05:57:55 INFO - argparse == 1.2.1 05:57:55 INFO - blessings == 1.6 05:57:55 INFO - blobuploader == 1.2.4 05:57:55 INFO - docopt == 0.6.1 05:57:55 INFO - functools32 == 3.2.3-2 05:57:55 INFO - jsonschema == 2.5.1 05:57:55 INFO - manifestparser == 1.1 05:57:55 INFO - mozInstall == 1.12 05:57:55 INFO - mozcrash == 0.17 05:57:55 INFO - mozdebug == 0.1 05:57:55 INFO - mozdevice == 0.48 05:57:55 INFO - mozfile == 1.2 05:57:55 INFO - mozhttpd == 0.7 05:57:55 INFO - mozinfo == 0.9 05:57:55 INFO - mozleak == 0.1 05:57:55 INFO - mozlog == 3.4 05:57:55 INFO - moznetwork == 0.27 05:57:55 INFO - mozprocess == 0.23 05:57:55 INFO - mozprofile == 0.28 05:57:55 INFO - mozrunner == 6.12 05:57:55 INFO - mozscreenshot == 0.1 05:57:55 INFO - mozsystemmonitor == 0.3 05:57:55 INFO - moztest == 0.7 05:57:55 INFO - mozversion == 1.4 05:57:55 INFO - psutil == 3.1.1 05:57:55 INFO - requests == 1.2.3 05:57:55 INFO - wsgiref == 0.1.2 05:57:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:55 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 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:55 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 05:57:55 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 05:57:55 INFO - Using env: {'DISPLAY': ':0', 05:57:55 INFO - 'HOME': '/home/cltbld', 05:57:55 INFO - 'LANG': 'en_US.UTF-8', 05:57:55 INFO - 'LANGUAGE': 'en_US:en', 05:57:55 INFO - 'LOGNAME': 'cltbld', 05:57:55 INFO - 'MAIL': '/var/mail/cltbld', 05:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:55 INFO - 'MOZ_NO_REMOTE': '1', 05:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:55 INFO - 'NO_EM_RESTART': '1', 05:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:55 INFO - 'PWD': '/builds/slave/test', 05:57:55 INFO - 'SHELL': '/bin/bash', 05:57:55 INFO - 'SHLVL': '1', 05:57:55 INFO - 'TERM': 'linux', 05:57:55 INFO - 'TMOUT': '86400', 05:57:55 INFO - 'USER': 'cltbld', 05:57:55 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:55 INFO - '_': '/tools/buildbot/bin/python'} 05:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:55 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)) 05:57:55 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)) 05:57:55 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)) 05:57:55 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)) 05:57:55 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)) 05:57:55 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)) 05:57:55 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)) 05:57:55 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)) 05:57:55 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)) 05:57:55 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)) 05:57:55 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)) 05:57:55 INFO - Cleaning up... 05:57:55 INFO - Return code: 0 05:57:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:55 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 0x7ffd2c8c3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xee38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb0ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', '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 05:57:55 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 05:57:55 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 05:57:55 INFO - Using env: {'DISPLAY': ':0', 05:57:55 INFO - 'HOME': '/home/cltbld', 05:57:55 INFO - 'LANG': 'en_US.UTF-8', 05:57:55 INFO - 'LANGUAGE': 'en_US:en', 05:57:55 INFO - 'LOGNAME': 'cltbld', 05:57:55 INFO - 'MAIL': '/var/mail/cltbld', 05:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:55 INFO - 'MOZ_NO_REMOTE': '1', 05:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:57:55 INFO - 'NO_EM_RESTART': '1', 05:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:55 INFO - 'PWD': '/builds/slave/test', 05:57:55 INFO - 'SHELL': '/bin/bash', 05:57:55 INFO - 'SHLVL': '1', 05:57:55 INFO - 'TERM': 'linux', 05:57:55 INFO - 'TMOUT': '86400', 05:57:55 INFO - 'USER': 'cltbld', 05:57:55 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:55 INFO - '_': '/tools/buildbot/bin/python'} 05:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:57:55 INFO - Cleaning up... 05:57:55 INFO - Return code: 0 05:57:55 INFO - Running post-action listener: _resource_record_post_action 05:57:55 INFO - Running post-action listener: _start_resource_monitoring 05:57:55 INFO - Starting resource monitoring. 05:57:55 INFO - [mozharness: 2016-11-20 13:57:55.560748Z] Finished create-virtualenv step (success) 05:57:55 INFO - [mozharness: 2016-11-20 13:57:55.561812Z] Running install step. 05:57:55 INFO - Running pre-action listener: _resource_record_pre_action 05:57:55 INFO - Running main action method: install 05:57:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:55 INFO - Reading from file tmpfile_stdout 05:57:55 INFO - Detecting whether we're running mozinstall >=1.0... 05:57:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:57:55 INFO - Reading from file tmpfile_stdout 05:57:55 INFO - Output received: 05:57:55 INFO - Usage: mozinstall [options] installer 05:57:55 INFO - Options: 05:57:55 INFO - -h, --help show this help message and exit 05:57:55 INFO - -d DEST, --destination=DEST 05:57:55 INFO - Directory to install application into. [default: 05:57:55 INFO - "/builds/slave/test"] 05:57:55 INFO - --app=APP Application being installed. [default: firefox] 05:57:55 INFO - mkdir: /builds/slave/test/build/application 05:57:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:58:09 INFO - Reading from file tmpfile_stdout 05:58:09 INFO - Output received: 05:58:09 INFO - /builds/slave/test/build/application/firefox/firefox 05:58:09 INFO - Running post-action listener: _resource_record_post_action 05:58:09 INFO - [mozharness: 2016-11-20 13:58:09.346770Z] Finished install step (success) 05:58:09 INFO - [mozharness: 2016-11-20 13:58:09.347013Z] Running run-tests step. 05:58:09 INFO - Running pre-action listener: _resource_record_pre_action 05:58:09 INFO - Running main action method: run_tests 05:58:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:58:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:58:09 INFO - Python 2.7.3 05:58:09 INFO - Return code: 0 05:58:09 INFO - grabbing minidump binary from tooltool 05:58:09 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')]} 05:58:09 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 0xfadf60>, '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 0xfaed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfaf1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:58:09 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 05:58:09 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 05:58:09 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 05:58:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:58:09 INFO - Return code: 0 05:58:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:58:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:58:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:58:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:58:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:58:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 05:58:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 05:58:09 INFO - Using env: {'DISPLAY': ':0', 05:58:09 INFO - 'HOME': '/home/cltbld', 05:58:09 INFO - 'LANG': 'en_US.UTF-8', 05:58:09 INFO - 'LANGUAGE': 'en_US:en', 05:58:09 INFO - 'LOGNAME': 'cltbld', 05:58:09 INFO - 'MAIL': '/var/mail/cltbld', 05:58:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:58:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:09 INFO - 'MOZ_NO_REMOTE': '1', 05:58:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:58:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:58:09 INFO - 'NO_EM_RESTART': '1', 05:58:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:09 INFO - 'PWD': '/builds/slave/test', 05:58:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:58:09 INFO - 'SHELL': '/bin/bash', 05:58:09 INFO - 'SHLVL': '1', 05:58:09 INFO - 'TERM': 'linux', 05:58:09 INFO - 'TMOUT': '86400', 05:58:09 INFO - 'USER': 'cltbld', 05:58:09 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078', 05:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:09 INFO - '_': '/tools/buildbot/bin/python'} 05:58:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 05:58:09 INFO - mozversion application_buildid: 20161120030721 05:58:09 INFO - mozversion application_changeset: 499c1152aebeb550ade64a98aca00b42b1cd3eb2 05:58:09 INFO - mozversion application_display_name: Firefox 05:58:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:58:09 INFO - mozversion application_name: Firefox 05:58:09 INFO - mozversion application_remotingname: firefox 05:58:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:58:09 INFO - mozversion application_vendor: Mozilla 05:58:09 INFO - mozversion application_version: 51.0 05:58:09 INFO - mozversion platform_buildid: 20161120030721 05:58:09 INFO - mozversion platform_changeset: 499c1152aebeb550ade64a98aca00b42b1cd3eb2 05:58:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:58:09 INFO - mozversion platform_version: 51.0 05:58:09 INFO - using testdate: 1479650289 05:58:09 INFO - actual date: 1479650289 05:58:09 INFO - starting webserver on 'localhost:40109' 05:58:09 INFO - SUITE-START | Running 2 tests 05:58:09 INFO - TEST-START | dromaeo_css 05:58:09 INFO - operating with platform_type : linux_ 05:58:09 INFO - Initialising browser for dromaeo_css test... 05:58:09 INFO - TEST-INFO | started process 3438 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjhDQTe/profile http://localhost:40109/getInfo.html) 05:58:15 INFO - PROCESS | 3438 | __metrics Screen width/height:1600/1200 05:58:15 INFO - PROCESS | 3438 | colorDepth:24 05:58:15 INFO - PROCESS | 3438 | Browser inner width/height: 1024/697 05:58:15 INFO - PROCESS | 3438 | __metrics 05:58:16 INFO - PROCESS | 3438 | 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" 05:58:16 INFO - PROCESS | 3438 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:58:16 INFO - PROCESS | 3438 | console.error: PushService: 05:58:16 INFO - PROCESS | 3438 | stateChangeProcessEnqueue: Error transitioning state 05:58:16 INFO - PROCESS | 3438 | UnknownError 05:58:17 INFO - TEST-INFO | 3438: exit 0 05:58:17 INFO - Browser initialized. 05:58:17 INFO - Running cycle 1/1 for dromaeo_css test... 05:58:17 INFO - TEST-INFO | started process 3533 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjhDQTe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:28 INFO - PROCESS | 3533 | RSS: Main: 142540800 05:58:28 INFO - PROCESS | 3533 | 05:59:20 INFO - PROCESS | 3533 | 1479650360087 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] 06:01:29 INFO - PROCESS | 3533 | Cycle 1(1): loaded http://localhost:40109/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40109/tests/dromaeo/cssquery-ext.html) 06:01:30 INFO - PROCESS | 3533 | RSS: Main: 237703168 06:01:30 INFO - PROCESS | 3533 | 06:04:32 INFO - PROCESS | 3533 | Cycle 1(1): loaded http://localhost:40109/tests/dromaeo/cssquery-ext.html (next: http://localhost:40109/tests/dromaeo/cssquery-jquery.html) 06:04:33 INFO - PROCESS | 3533 | RSS: Main: 235307008 06:04:33 INFO - PROCESS | 3533 | 06:07:35 INFO - PROCESS | 3533 | Cycle 1(1): loaded http://localhost:40109/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40109/tests/dromaeo/cssquery-mootools.html) 06:07:35 INFO - PROCESS | 3533 | RSS: Main: 176173056 06:07:35 INFO - PROCESS | 3533 | 06:10:37 INFO - PROCESS | 3533 | Cycle 1(1): loaded http://localhost:40109/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40109/tests/dromaeo/cssquery-prototype.html) 06:10:38 INFO - PROCESS | 3533 | RSS: Main: 176312320 06:10:38 INFO - PROCESS | 3533 | 06:13:40 INFO - PROCESS | 3533 | Cycle 1(1): loaded http://localhost:40109/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40109/tests/dromaeo/cssquery-yui.html) 06:13:41 INFO - PROCESS | 3533 | RSS: Main: 176037888 06:13:41 INFO - PROCESS | 3533 | 06:16:44 INFO - PROCESS | 3533 | Cycle 1(1): loaded http://localhost:40109/tests/dromaeo/cssquery-yui.html (next: http://localhost:40109/tests/dromaeo/cssquery-dojo.html) 06:16:44 INFO - PROCESS | 3533 | RSS: Main: 258940928 06:16:44 INFO - PROCESS | 3533 | 06:16:44 INFO - PROCESS | 3533 | __start_tp_report 06:16:44 INFO - PROCESS | 3533 | _x_x_mozilla_page_load 06:16:44 INFO - PROCESS | 3533 | _x_x_mozilla_page_load_details 06:16:44 INFO - PROCESS | 3533 | |i|pagename|runs| 06:16:44 INFO - PROCESS | 3533 | |0;dojo.html;3122.7657925655576;3138.2154461384666;3156.873725050999;3186.330870517191;3204.695553922378;5549.528290095342;5643.266375371201;5656.349519805223;5661.0376236039865;5665.376808551061;5533.889322213553;5550.27846379971;5556.583256255777;5567.526760225375;5575.080111781551;4980.701157930518;4985.476524964881;4985.651004429682;5014.172661510846;5015.949840501603;5047.596192304607;5072.594192464594;5075.974620126913;5078.517540833634;5078.949210507903;4966.553010229079;4994.175960966436;5051.898962020758;5056.241563765428;5107.69353838769;5120.718360490171;5134.306868572732;5188.066148093341;5195.324607801;5222.738863056832;4946.109700253953;5074.0612986597225;5092.905025419553;5128.358955130609;5154.046501397216;5553.416891226444;5560.110382338847;5569.192467092289;5578;5616.157576363537;5972.283326000865;5983.730732117066;6072.332043475214;6075.574709770318;6076.453119219292;5799.623024503436;5846.298444186725;6101.328853826076;6122.448979591813;6130.448259656652;5515.145152501369;5568.498835104779;5570.2480165177585;5571.359293681238;5600.887982240414;6127.662978536223;6128.142060111588;6132.938670613326;6135.263768347827;6140.3552626974415;5789.710514474259;5829.154772557956;5893.469587737124;5899.321578018583;5900.144479050599;5917.201177840936;5965.164876917235;5966;6024.367441418588;6032.306284777219;5966.343702192756;5979.222701048836;5981.551383646157;5987.311459182162;6007.609505382136;5931;5933;5975.641461512237;5984.491318237999;6016.939830601726;4423.4912985006495;4450.710703804242;4474.149911516802;4509;4519.751413672214;4101.9950112222095;4122.3404255319;4129.256733787947;4137.606927341898;4151.211269858717;5729.828105156852;5730.942690573041;5732.828015159468;5742.885142297131;5746.13807928814;5934.82195534126;5959.404059594006;6039.305479869783;6108.755649774048;6114.2357205349335;5679.375268720438;5740.512056475163;5742.16738572913;5776.3068431787615;5780.855478613068;5067.797288108434;5091.1854103343285;5106.540411362995;5116.948830511722;5134.178531434953;6037.15479832815;6294.559380843389;6312.0531920211415;6314.557980941382;6322.810315690536;5907.084401917709;6034.487068599043;6089.939100608937;6112.602680825732;6135.04893764628;6263.279722831932;6292.905606415816;6306.716197771019;6307.96846015767;6310.6529140895855;5936.851578710567;6001.549883758823;6003.429674180946;6005.012481377613;6005.789797357072;5971;5981.910271345846;5986;6005.939940600538;6055.091736239599;5915.378885217162;5929.466348028698;5936.317323507852;5937.5249980000635;5954.76180952757;5919.230500034968;5920.970395147997;5926.851828704317;5930.229070220844;5936.3470018297085;5593.916091258553;5683.261176047087;5831.591788574759;5866.911996319973;5898.970505147447;5977.133315669291;5987.790427335022;5993.6403815769445;6010.128531363015;6023.126646636126;5736.4550367715;5797.797077102281;5860.09168578871;5860.443257890493;5861.501772349398 06:16:44 INFO - PROCESS | 3533 | |1;ext.html;588.4497994375257;601.0263373335197;625.3402660193494;630.1713247080089;633.7465013994401;35481.64518354816;38576;46749;47427.52572474274;47635.047299054;48357.51642483574;48837.75581122089;48849;48875.266870996966;48990.26514602284;9451.291153163505;9640.517974101302;9641.662541811038;9650.324477286593;9650.758731031745;7999.160088190743;8187.590620468983;11051.834222486628;11262;11278.323300601991;11446.08431325492;11493.540258389654;11497.942510287437;11514.884851151464;11547.30716157029;11193.888061119407;11249.156313276537;11273.32360058395;11274.887251127466;11278.887211127947;11181.105511559055;11244.606438774603;11254.718632034183;11261.605843795509;11261.774764504746;44878.102437951384;45136;45169.32246016312;45227.773861130816;45262;3791.696664266852;3891.5913829047827;3900.843966241347;3949.7037722170953;3949.881503554898;33431.665683343344;35089;35670.46494302587;36549.90350289496;36757.63242367569;4677.766111694402;4721.484093674708;4756.453007904066;4822.590079843218;4927.408710954708;350059;352460;353238;355033;356466;40571.59428405737;70161;71059.2894071063;71102.2889771106;71116.64441677759;41013.79493102516;42516.36225456621;42971.78514107409;43112.78443607762;43137.13725725468;16998.915005424893;17160.22778974949;17173.57066073358;17175.141242937803;17178.828211717722;12581.182223155465;12878.291693956922;13959;14100.858991409954;14104.576862694135;9604.471754053455;9793.657221997197;9910.900890990997;9964.551595178234;10020.64927727526;3795.203007368428;3799.7340186186702;3810.1808111256214;3870.574236833946;3905.33609286422;994.4132961552718;1139.880312567185;1146.1232157399595;1218.3725381428571;1228.3919459867423;32246.516302255015;34208.82895585506;35436.64563354385;35604.821975890474;35682.64317356845;1143.0912424622445;1193.254216114928;1212.9332886691316;1258.5028913579085;1262.4634530324574;17210.741838872425;18186.727199092024;18244.726329105073;18297.26810927548;18298.359557415428;602289;610746;617174;619789;622909;60122.39877601255;60331.69834150801;60473;60519.69740151271;60540.39459605348;1336.8596297388829;1399.4192410149878;1414.7877818327333;1437.4106616287272;1475.4614565683448;30031.849840750656;33597.32805343831;33868.3226335467;33873.3225335487;34271.828640856635;53344.4665553355;53904.19143712926;53946.460535394144;53977.19034214569;54142.18786718281;447018;449453;449468;450292;450936;27592.724072759014;28314.2921426966;29821.552676709434;30375.24061898382;30975.69024309728;48059.27911081267;48841.511584883694;48853.51146488632;49203.26195107148;49210.01579968452;41732;41829.58170418379;42329.78835105805;43065.78467107644;43252;41556;42289.154216916104;42463.57536424596;42509.31221077622;42833.94819290848;42033;42329.365059523516;42780.144397112505;42871.7856410716;43808.78095609502;34231;38397.23205535818;38615.80692096522;39364.21271574495;39384.60615393924;28724;29820.701792981792;29874;35990.28019439649;36835 06:16:44 INFO - PROCESS | 3533 | |2;jquery.html;436.4260996789224;437.10611798871304;443.4634092747775;448.9371487991681;454.69081024903073;19397.41807745768;21036.263730769428;21159.57680846384;21262.14951401946;21494.677579836316;18481.168347424365;19635.312764053262;20378.388648340537;20573.074211660474;20574.177032918666;5485.61600687952;5673.063944449161;5673.8581535461535;5677.687727175004;5683.829485115453;5420.403755586902;5565.916511252334;5572.721363931808;5610.523105536014;5612.719364031803;5605.2713147291;5615.157726341041;5618.269624948751;5618.410066942974;5624.803131890385;5486.890262194754;5955.553333499983;5996.760129594811;6012.068129439966;6012.789552365646;5300.549453296474;5480.534154596864;5482.698451585161;5483.8080667176455;5489.780408783644;15541.14523701196;15741.606459838482;22544.09823607054;24092.036318547238;24571.63142552863;2383.5828729972186;2418.8585607940445;2560.884760185796;2961.363306889029;3013.623297087864;10966.945165274203;11007.944960275227;11290.774184516264;11460.255083419552;11689.883101168965;2030.7360043194437;2071.7099606055162;2130.6164890319737;2155.3426205007468;2160.3518944316797;62829.99999999954;66735;77492.61253693752;81491.59254203692;91618.54190729071;12165.391730413445;12185.573504927463;12211.877881221251;12295;12460.439280232411;17624.590032797347;17798.733019004725;17799.911000444914;17808.198631061634;17826.37607683725;8934.73195804127;8970.641174353083;9160.954195228982;9195.218406435393;9256.305777066747;8489.872651910226;8567.957160214159;8618.956905215558;8781.912180878237;8822.51476168804;5220.686758794409;5347.385050719139;5351.785928562892;5365.9731701342025;5505.834824955257;2139.575171370086;2173.9021744021557;2189.9562008759735;2207.631268613438;2244.7979681828715;912.926965842731;969.2052516936186;993.9254555908336;1036.7459972306833;1069.9251052426412;20886.895565522074;21325.68011479829;21413.785862141493;21455.249066282606;21677.241296554545;1000.5497526113278;1048.8636477257908;1058.878229003659;1062.665260442958;1114.8216285394299;10967;11855.110866685032;11857.762844743056;12061.638150855488;12098.516059357704;504370;508248;511163;565317;571171;101794.49102754438;105677.47161264144;106631;107874.46062769636;109867.45066274618;1034.234666346913;1053.8735351757684;1078.9514471848786;1142.3260276436777;1143.1426430177366;18422.263109476007;18530.258789648262;19014.42956711301;19215.32746353871;19478.220871165562;44878.77560612176;45174;47852.7607361961;47900.28149577829;48284;57561;61084.69457652683;62647.6867615659;63653.363466364746;64345.35654643393;13090.738185236432;13431.664208394477;13488.258145802076;13615.455381785014;13653.658658533615;10046.246531510313;15089.924550377616;15162.469313573973;15173.24133793328;15180.772288415903;9171.128742769426;9337.626494940127;9434.95282523583;9533;9594.616215351307;9388.95305523468;9466.384684995453;9550.092741189575;9552.761180970532;9655.517224138905;9441.858372124287;9649.855252171363;9658.323917326;9708.029197080235;9798.65704700332;12701.87298127007;13380.130291531019;13380.330983450984;13440.798388023992;13663.726725465634;12745.10784245131;12805.487780488676;12895.290759007969;12927.870721293042;13520.6619834501 06:16:44 INFO - PROCESS | 3533 | |3;mootools.html;1437.8418373978866;1539.2996186735033;1574.0241050548661;1735.5661084728818;1849.963000739986;8129.3902957278215;9040.954795226024;9368.782058332425;9369.390989585672;9641.084097010791;9396.577154028068;9485.814273215849;9490.145886870181;9490.57292421841;9491.857622135674;2489.589217779069;2522.1424715596695;2522.735112813156;2526.60837570176;2529.152733834168;2496.7752902238794;2548.732383099766;2550.5154020736095;2550.923472295825;2551.2346296111186;2494.6881639795024;2497.7627125423146;2562.128876182098;2563.1541591274927;2565.320190149612;2494.276659768674;2498.5252801967567;2499.575072237724;2533.5312967101145;2536.074332868508;2436.1473484280536;2480.2931164618176;2484.838485498437;2487.7761001509953;2488.688913885764;9172.770680732969;9345.532723363873;9397.812043759155;9435.481048542328;9458.527073646359;5468.589855760833;5598.468145526169;5601.691906945116;5603.10350343943;5605.047141985872;3637.563492380931;3652.853885844563;3676.1544844685873;3690.5571331440133;3693.519842420495;1336.6524703577043;1338.130215360014;1347.7102422979008;1365.938532766028;1373.176094343396;12773;12911.418986145645;12918.741625167444;12930.28883411412;12939.935300323437;4790.449098353664;4800.95199048012;4804.8798780030775;4859.3682821233015;5015.699058056528;10249.692509224735;10262.794744105076;10266.691999240034;10274.691759247235;10277.280590358603;5527.2538207342295;5528.889422211533;5531;5532.889342213133;5534.225208470817;4625;4766.7854946526795;4986.102501549756;5104.69371837691;5161.4322424533275;4156.087460250931;4184.497860256789;4240.681948853849;4275.0594869128745;4284.464441944757;1281.3208999230424;1320.584016034946;1325.1982550556968;1329.4682127149217;1342.9462821487032;965.290511474066;967.8016006718606;983.9570055740937;989.6585677941099;990.1286867556504;5897.557683173779;6040.275166979991;6047.365026672224;6104.236970378703;6168.8766224675255;483.1158979068297;486.59126333879004;489.9853004409873;511.613731632615;524.960627952905;3877.127646279621;4062.309407400749;4117.505899292044;4142.937855932163;4219.472565929259;11257.943710281395;11594.072474202212;11658.6502404928;11683.532658693557;11693.590724324778;11612.941935290439;11616.128790340757;11628.88371116295;11630.302181869114;11631.418429078512;8265.173482651686;8487.151284871463;8607.311415086779;8734.781630459416;8749.693760718344;6035.486983606443;6051.152838602511;6102.6643534604245;6122.755089796358;6137.079438084323;10201.336913100624;10691.839622405514;10692.091172250446;10717.89282107169;10823.67528974132;10686.519106640375;10901.291416057931;10921.399323037309;10958.616448424267;10962.287451315638;7013.754518591825;7550.622468876644;7929.048514178336;8095.311898488459;8347.95826020866;9046.638134474548;9148.405353651991;9163.450192988443;9169;9236;7493.325600695964;7640.159582445815;7835.725749598964;7866.646000930086;8129.593520324079;7562.697492100255;7692.9615351922885;7712.1516633169185;7741.961290193513;7837.568933708701;7158.2841715828;7261.745838895825;7270.854582908418;7272.527285726411;7274.054372931485;5030.346055012825;5041.798328066836;5071.670341427795;5090.847274581768;5298.94701053;5160.845174644766;5259.079661059376;5293.788248470018;5300.655457395257;5393.7033463159905 06:16:44 INFO - PROCESS | 3533 | |4;prototype.html;353.5351013417356;353.9663731945464;356.16657022567193;359.931612993531;368.2138634016374;5670.1778242154915;5691.772329106836;5705.4294570542925;5714.828555143347;5736.942630573693;5311.548518375933;5590.86022849429;5616.494515493605;5642.266505354298;5647.717614119288;3848.9807550962278;4294.763787991659;4295.52749197588;4387.451568553931;4403.36151258069;4393.07745373472;4399.956000440003;4400.581944715247;4409.757463339515;4429.446319210098;4359.782010899442;4382.948092457803;4399.38408622793;4400.317950717644;4412.316091005883;4268.957310426903;4337.240982828024;4343.370211319341;4375.343698445243;4381.890452738675;3853.0560012796764;3889.533256009297;3892.2020985697864;3899.805009749501;3908.4332771748223;5453.918191227135;5470.753816078286;5481.780728770845;5484.972575137139;5492.956338295751;4852.708837469763;5004.84985450437;5007.148784706608;5020.569137795711;5047.536214497792;5859.648421094706;5871.5596330274975;5893.675847828367;5902.734376953047;5908.704564771744;4079.1637714268504;4257.19113368459;4260.829566817323;4271.551487093872;4273.017206042628;5809.692819115665;5962.761489540457;5981.8803623927715;6090.786822461237;6112.480439162677;4242.5757424257945;4340.175366680321;4342.196693611692;4343.218220720238;4348.2825333819565;4382.780860956939;4385.627221686129;4395.516493185747;4418.756968366706;4457.977710111493;4949;5046.26829109777;5051.004853083085;5069.822556210515;5102.33669622954;5840.29916410025;5864.830012895256;5884.499817515473;5928.525717942554;5929.940700593025;1501.6846462242834;1518.4533567915541;1525.9160746159068;1533.8619524242872;1535.0022485384588;926.1849572376266;965.3821554205174;971.8299297622888;982.7051884434641;990.2721499697716;4952.331435256267;4959.628027897922;4969.27945447908;4972.8508144755715;4977.855093328555;5274.340707411574;5282.603804714662;5285.920711189336;5294.179402192589;5317.308749862493;4720.787564559603;4956.677815942025;4967.950320496749;4991.6522538915015;4997.9750101249265;2825.5479123340165;2825.7598104160966;2828.250513613894;2835.2770043639152;2835.9506982416224;5411.891762164734;5425.484578964992;5426.972865135649;5432.320959880015;5467.890642187134;5058.494150584986;5340.572754179734;5368.785248590013;5372.838814835562;5393.730313484389;5369.436209197978;5411.377691565521;5432.91850622241;5459.689674478097;5464.9180262296095;5277.788888444573;5279.366476022902;5279.894402112013;5300.5494532963585;5305.867353316044;5535.778568857361;5998.370171131968;5998.700064996646;6007.438066102757;6022.90965635507;5944.227250457413;6100.481459076028;6118;6128.662923539248;6271.184745982993;4229.661627069889;4254.723442976197;4342.457192850894;4348.304271316574;4365.585269399402;5823.70881455934;5985.25184351956;5992.221011268507;6017.18767966319;6024.156618073561;5869.589128760946;5881.089075764568;5889.322727886348;5892.35184129754;5900.852478688067;5849.9872565629585;5856.853578660568;5857.209276747755;5878.207146820261;5878.559108066997;5647.5199608032135;5845.41545845429;5866.032104702676;5900.616459930091;5915.822525324247;5962.850928726816;5967.8209653710455;5968.507873031742;5970.791022314198;5980.461758441761;5610.49505544503;5826.213461182686;5833.883322333445;5840.737166827418;5849.883002340014 06:16:44 INFO - PROCESS | 3533 | |5;yui.html;791.548817174211;795.9442839001272;809.4819315637989;832.4089896173715;934.7569631895707;16618.086005269706;17851.821481785177;18089.81910180898;18102.366417175403;18146.364877229298;16009.119498427583;16170;16198.514044578682;16204.83795162048;16495.422660206896;864.6022829498422;893.0043002052729;895.3374502867896;895.5164211325877;896.0143841774029;893.2898345815092;894.2577660541767;894.8031433084743;899.1772528136747;899.8965119011299;946.981060378792;947.8862536495631;949.1599934058373;949.6248981652243;950.2445555783135;934.2619330728717;940.6472572785206;941.0448394879203;947.2232769129316;947.6825263536723;691.6887400669718;692.0726226856037;692.3837784371913;692.4114502672738;692.8233300508389;17363.3054677814;17401.390951316644;17401.825981740272;17403.216855241546;17474.563135921577;15797.21013949298;15869.603259918478;15876.603084922854;16056.11691356975;16577.336906523724;12801.231926084372;12919.354032298348;13250.488690309965;13302.201867887958;13649.317534123353;3996.7402118862183;4041.373587093976;4049.1901619675887;4111.71218014739;4141.97929010353;75552;78507;79019;82835.5858220705;83168;12238.755224895453;12488.937555312346;12568.560100396626;12639.304838233986;12784.296863672587;22089.779102209093;22297.3310800673;22960.88519557425;23235.651465228035;23321.183758568706;8520.57397130141;8661.826763464822;8734.86897696535;8781.2535934445;8806.515641639771;4802.27965805132;4901.411830580353;4927.236278376786;5110.872228194251;5219.138842091088;3815.256025075084;3953.1303113315025;3953.3872249801325;3955.149642826806;3958.7030972677167;659.9934000659932;660.2671035150978;671.4292851076643;672.9899051514232;675.1223409567542;649.0621052578978;670.6579644381401;727.2400341642996;730.3244498838553;773.0568706178453;9507.619695212114;9933.503324833873;10114.443705596263;10403.375797452178;10631.149508039338;673.4074014866886;759.369723129812;767.8464307138595;805.3516918880357;810.6068556749972;5036.118679231193;5038.823641172615;5085.720285384339;5101.489851014869;5167.276581278624;75038;81507;123170.76829231772;134977;135950;73947.63026184942;96936.51531742438;97705.51147244219;99627.50186249167;116192;94229.5288523553;98719;99341.50329248307;100334;102307.4884625587;9765.755856103655;10281.177505799667;10318.638847640597;10399.480025998519;10420.947895260779;54890.725546372014;55470;55642.44357556535;55646.72176639091;56504;76582.2341776575;82383.5880820592;82922;84260.57869710612;84794;13876;13972.161670299409;14036.087654302437;14164.575062748134;14369.281535923372;14624.780628290371;14851.925740371229;15237.390504380144;15583.29875155598;17180;14670.413183472541;14765.557033289018;15328.693426131638;15749.842501574838;15802.525924222291;13899.861001389856;14369.712605748036;14462.783058253925;15445.459408920633;15467.303971321542;13924.930375348058;13974.860251397356;14012.579622611338;14581.854181458473;15065.924670376578;12354.630867128182;12485.93757031209;12525.937370313455;12602.558910438458;12907.741845163231;11435;12350.505979761068;12864.439136040874;13028.218306901616;13084.168216449894 06:16:44 INFO - PROCESS | 3533 | __end_tp_report 06:16:44 INFO - PROCESS | 3533 | __start_cc_report 06:16:44 INFO - PROCESS | 3533 | _x_x_mozilla_cycle_collect,860 06:16:44 INFO - PROCESS | 3533 | __end_cc_report 06:16:44 INFO - PROCESS | 3533 | __startTimestamp1479651404310__endTimestamp 06:16:44 INFO - PROCESS | 3533 | 06:16:44 INFO - PROCESS | 3533 | ------- Summary: start ------- 06:16:44 INFO - PROCESS | 3533 | Number of tests: 6 06:16:44 INFO - PROCESS | 3533 | 06:16:44 INFO - PROCESS | 3533 | [#0] dojo.html Cycles:165 Average:5578.44 Median:5831.59 stddev:661.85 (11.3%) stddev-sans-first:635.23 06:16:44 INFO - PROCESS | 3533 | Values: 3122.8 3138.2 3156.9 3186.3 3204.7 5549.5 5643.3 5656.3 5661.0 5665.4 5533.9 5550.3 5556.6 5567.5 5575.1 4980.7 4985.5 4985.7 5014.2 5015.9 5047.6 5072.6 5076.0 5078.5 5078.9 4966.6 4994.2 5051.9 5056.2 5107.7 5120.7 5134.3 5188.1 5195.3 5222.7 4946.1 5074.1 5092.9 5128.4 5154.0 5553.4 5560.1 5569.2 5578.0 5616.2 5972.3 5983.7 6072.3 6075.6 6076.5 5799.6 5846.3 6101.3 6122.4 6130.4 5515.1 5568.5 5570.2 5571.4 5600.9 6127.7 6128.1 6132.9 6135.3 6140.4 5789.7 5829.2 5893.5 5899.3 5900.1 5917.2 5965.2 5966.0 6024.4 6032.3 5966.3 5979.2 5981.6 5987.3 6007.6 5931.0 5933.0 5975.6 5984.5 6016.9 4423.5 4450.7 4474.1 4509.0 4519.8 4102.0 4122.3 4129.3 4137.6 4151.2 5729.8 5730.9 5732.8 5742.9 5746.1 5934.8 5959.4 6039.3 6108.8 6114.2 5679.4 5740.5 5742.2 5776.3 5780.9 5067.8 5091.2 5106.5 5116.9 5134.2 6037.2 6294.6 6312.1 6314.6 6322.8 5907.1 6034.5 6089.9 6112.6 6135.0 6263.3 6292.9 6306.7 6308.0 6310.7 5936.9 6001.5 6003.4 6005.0 6005.8 5971.0 5981.9 5986.0 6005.9 6055.1 5915.4 5929.5 5936.3 5937.5 5954.8 5919.2 5921.0 5926.9 5930.2 5936.3 5593.9 5683.3 5831.6 5866.9 5899.0 5977.1 5987.8 5993.6 6010.1 6023.1 5736.5 5797.8 5860.1 5860.4 5861.5 06:16:44 INFO - PROCESS | 3533 | 06:16:44 INFO - PROCESS | 3533 | [#1] ext.html Cycles:180 Average:63458.20 Median:21634.77 stddev:128919.74 (595.9%) stddev-sans-first:129194.97 06:16:44 INFO - PROCESS | 3533 | Values: 588.4 601.0 625.3 630.2 633.7 35481.6 38576.0 46749.0 47427.5 47635.0 48357.5 48837.8 48849.0 48875.3 48990.3 9451.3 9640.5 9641.7 9650.3 9650.8 7999.2 8187.6 11051.8 11262.0 11278.3 11446.1 11493.5 11497.9 11514.9 11547.3 11193.9 11249.2 11273.3 11274.9 11278.9 11181.1 11244.6 11254.7 11261.6 11261.8 44878.1 45136.0 45169.3 45227.8 45262.0 3791.7 3891.6 3900.8 3949.7 3949.9 33431.7 35089.0 35670.5 36549.9 36757.6 4677.8 4721.5 4756.5 4822.6 4927.4 350059.0 352460.0 353238.0 355033.0 356466.0 40571.6 70161.0 71059.3 71102.3 71116.6 41013.8 42516.4 42971.8 43112.8 43137.1 16998.9 17160.2 17173.6 17175.1 17178.8 12581.2 12878.3 13959.0 14100.9 14104.6 9604.5 9793.7 9910.9 9964.6 10020.6 3795.2 3799.7 3810.2 3870.6 3905.3 994.4 1139.9 1146.1 1218.4 1228.4 32246.5 34208.8 35436.6 35604.8 35682.6 1143.1 1193.3 1212.9 1258.5 1262.5 17210.7 18186.7 18244.7 18297.3 18298.4 602289.0 610746.0 617174.0 619789.0 622909.0 60122.4 60331.7 60473.0 60519.7 60540.4 1336.9 1399.4 1414.8 1437.4 1475.5 30031.8 33597.3 33868.3 33873.3 34271.8 53344.5 53904.2 53946.5 53977.2 54142.2 447018.0 449453.0 449468.0 450292.0 450936.0 27592.7 28314.3 29821.6 30375.2 30975.7 48059.3 48841.5 48853.5 49203.3 49210.0 41732.0 41829.6 42329.8 43065.8 43252.0 41556.0 42289.2 42463.6 42509.3 42833.9 42033.0 42329.4 42780.1 42871.8 43808.8 34231.0 38397.2 38615.8 39364.2 39384.6 28724.0 29820.7 29874.0 35990.3 36835.0 06:16:44 INFO - PROCESS | 3533 | 06:16:44 INFO - PROCESS | 3533 | [#2] jquery.html Cycles:180 Average:31169.27 Median:13237.81 stddev:87868.84 (663.8%) stddev-sans-first:88085.03 06:16:44 INFO - PROCESS | 3533 | Values: 436.4 437.1 443.5 448.9 454.7 19397.4 21036.3 21159.6 21262.1 21494.7 18481.2 19635.3 20378.4 20573.1 20574.2 5485.6 5673.1 5673.9 5677.7 5683.8 5420.4 5565.9 5572.7 5610.5 5612.7 5605.3 5615.2 5618.3 5618.4 5624.8 5486.9 5955.6 5996.8 6012.1 6012.8 5300.5 5480.5 5482.7 5483.8 5489.8 15541.1 15741.6 22544.1 24092.0 24571.6 2383.6 2418.9 2560.9 2961.4 3013.6 10966.9 11007.9 11290.8 11460.3 11689.9 2030.7 2071.7 2130.6 2155.3 2160.4 62830.0 66735.0 77492.6 81491.6 91618.5 12165.4 12185.6 12211.9 12295.0 12460.4 17624.6 17798.7 17799.9 17808.2 17826.4 8934.7 8970.6 9161.0 9195.2 9256.3 8489.9 8568.0 8619.0 8781.9 8822.5 5220.7 5347.4 5351.8 5366.0 5505.8 2139.6 2173.9 2190.0 2207.6 2244.8 912.9 969.2 993.9 1036.7 1069.9 20886.9 21325.7 21413.8 21455.2 21677.2 1000.5 1048.9 1058.9 1062.7 1114.8 10967.0 11855.1 11857.8 12061.6 12098.5 504370.0 508248.0 511163.0 565317.0 571171.0 101794.5 105677.5 106631.0 107874.5 109867.5 1034.2 1053.9 1079.0 1142.3 1143.1 18422.3 18530.3 19014.4 19215.3 19478.2 44878.8 45174.0 47852.8 47900.3 48284.0 57561.0 61084.7 62647.7 63653.4 64345.4 13090.7 13431.7 13488.3 13615.5 13653.7 10046.2 15089.9 15162.5 15173.2 15180.8 9171.1 9337.6 9435.0 9533.0 9594.6 9389.0 9466.4 9550.1 9552.8 9655.5 9441.9 9649.9 9658.3 9708.0 9798.7 12701.9 13380.1 13380.3 13440.8 13663.7 12745.1 12805.5 12895.3 12927.9 13520.7 06:16:44 INFO - PROCESS | 3533 | 06:16:44 INFO - PROCESS | 3533 | [#3] mootools.html Cycles:180 Average:6051.04 Median:2671.24 stddev:3466.09 (129.8%) stddev-sans-first:3458.48 06:16:44 INFO - PROCESS | 3533 | Values: 1437.8 1539.3 1574.0 1735.6 1850.0 8129.4 9041.0 9368.8 9369.4 9641.1 9396.6 9485.8 9490.1 9490.6 9491.9 2489.6 2522.1 2522.7 2526.6 2529.2 2496.8 2548.7 2550.5 2550.9 2551.2 2494.7 2497.8 2562.1 2563.2 2565.3 2494.3 2498.5 2499.6 2533.5 2536.1 2436.1 2480.3 2484.8 2487.8 2488.7 9172.8 9345.5 9397.8 9435.5 9458.5 5468.6 5598.5 5601.7 5603.1 5605.0 3637.6 3652.9 3676.2 3690.6 3693.5 1336.7 1338.1 1347.7 1365.9 1373.2 12773.0 12911.4 12918.7 12930.3 12939.9 4790.4 4801.0 4804.9 4859.4 5015.7 10249.7 10262.8 10266.7 10274.7 10277.3 5527.3 5528.9 5531.0 5532.9 5534.2 4625.0 4766.8 4986.1 5104.7 5161.4 4156.1 4184.5 4240.7 4275.1 4284.5 1281.3 1320.6 1325.2 1329.5 1342.9 965.3 967.8 984.0 989.7 990.1 5897.6 6040.3 6047.4 6104.2 6168.9 483.1 486.6 490.0 511.6 525.0 3877.1 4062.3 4117.5 4142.9 4219.5 11257.9 11594.1 11658.7 11683.5 11693.6 11612.9 11616.1 11628.9 11630.3 11631.4 8265.2 8487.2 8607.3 8734.8 8749.7 6035.5 6051.2 6102.7 6122.8 6137.1 10201.3 10691.8 10692.1 10717.9 10823.7 10686.5 10901.3 10921.4 10958.6 10962.3 7013.8 7550.6 7929.0 8095.3 8348.0 9046.6 9148.4 9163.5 9169.0 9236.0 7493.3 7640.2 7835.7 7866.6 8129.6 7562.7 7693.0 7712.2 7742.0 7837.6 7158.3 7261.7 7270.9 7272.5 7274.1 5030.3 5041.8 5071.7 5090.8 5298.9 5160.8 5259.1 5293.8 5300.7 5393.7 06:16:44 INFO - PROCESS | 3533 | 06:16:44 INFO - PROCESS | 3533 | [#4] prototype.html Cycles:180 Average:4798.43 Median:5314.43 stddev:1390.57 (26.2%) stddev-sans-first:1353.86 06:16:44 INFO - PROCESS | 3533 | Values: 353.5 354.0 356.2 359.9 368.2 5670.2 5691.8 5705.4 5714.8 5736.9 5311.5 5590.9 5616.5 5642.3 5647.7 3849.0 4294.8 4295.5 4387.5 4403.4 4393.1 4400.0 4400.6 4409.8 4429.4 4359.8 4382.9 4399.4 4400.3 4412.3 4269.0 4337.2 4343.4 4375.3 4381.9 3853.1 3889.5 3892.2 3899.8 3908.4 5453.9 5470.8 5481.8 5485.0 5493.0 4852.7 5004.8 5007.1 5020.6 5047.5 5859.6 5871.6 5893.7 5902.7 5908.7 4079.2 4257.2 4260.8 4271.6 4273.0 5809.7 5962.8 5981.9 6090.8 6112.5 4242.6 4340.2 4342.2 4343.2 4348.3 4382.8 4385.6 4395.5 4418.8 4458.0 4949.0 5046.3 5051.0 5069.8 5102.3 5840.3 5864.8 5884.5 5928.5 5929.9 1501.7 1518.5 1525.9 1533.9 1535.0 926.2 965.4 971.8 982.7 990.3 4952.3 4959.6 4969.3 4972.9 4977.9 5274.3 5282.6 5285.9 5294.2 5317.3 4720.8 4956.7 4968.0 4991.7 4998.0 2825.5 2825.8 2828.3 2835.3 2836.0 5411.9 5425.5 5427.0 5432.3 5467.9 5058.5 5340.6 5368.8 5372.8 5393.7 5369.4 5411.4 5432.9 5459.7 5464.9 5277.8 5279.4 5279.9 5300.5 5305.9 5535.8 5998.4 5998.7 6007.4 6022.9 5944.2 6100.5 6118.0 6128.7 6271.2 4229.7 4254.7 4342.5 4348.3 4365.6 5823.7 5985.3 5992.2 6017.2 6024.2 5869.6 5881.1 5889.3 5892.4 5900.9 5850.0 5856.9 5857.2 5878.2 5878.6 5647.5 5845.4 5866.0 5900.6 5915.8 5962.9 5967.8 5968.5 5970.8 5980.5 5610.5 5826.2 5833.9 5840.7 5849.9 06:16:44 INFO - PROCESS | 3533 | 06:16:44 INFO - PROCESS | 3533 | [#5] yui.html Cycles:180 Average:22029.08 Median:4080.45 stddev:31152.05 (763.4%) stddev-sans-first:31198.62 06:16:44 INFO - PROCESS | 3533 | Values: 791.5 795.9 809.5 832.4 934.8 16618.1 17851.8 18089.8 18102.4 18146.4 16009.1 16170.0 16198.5 16204.8 16495.4 864.6 893.0 895.3 895.5 896.0 893.3 894.3 894.8 899.2 899.9 947.0 947.9 949.2 949.6 950.2 934.3 940.6 941.0 947.2 947.7 691.7 692.1 692.4 692.4 692.8 17363.3 17401.4 17401.8 17403.2 17474.6 15797.2 15869.6 15876.6 16056.1 16577.3 12801.2 12919.4 13250.5 13302.2 13649.3 3996.7 4041.4 4049.2 4111.7 4142.0 75552.0 78507.0 79019.0 82835.6 83168.0 12238.8 12488.9 12568.6 12639.3 12784.3 22089.8 22297.3 22960.9 23235.7 23321.2 8520.6 8661.8 8734.9 8781.3 8806.5 4802.3 4901.4 4927.2 5110.9 5219.1 3815.3 3953.1 3953.4 3955.1 3958.7 660.0 660.3 671.4 673.0 675.1 649.1 670.7 727.2 730.3 773.1 9507.6 9933.5 10114.4 10403.4 10631.1 673.4 759.4 767.8 805.4 810.6 5036.1 5038.8 5085.7 5101.5 5167.3 75038.0 81507.0 123170.8 134977.0 135950.0 73947.6 96936.5 97705.5 99627.5 116192.0 94229.5 98719.0 99341.5 100334.0 102307.5 9765.8 10281.2 10318.6 10399.5 10420.9 54890.7 55470.0 55642.4 55646.7 56504.0 76582.2 82383.6 82922.0 84260.6 84794.0 13876.0 13972.2 14036.1 14164.6 14369.3 14624.8 14851.9 15237.4 15583.3 17180.0 14670.4 14765.6 15328.7 15749.8 15802.5 13899.9 14369.7 14462.8 15445.5 15467.3 13924.9 13974.9 14012.6 14581.9 15065.9 12354.6 12485.9 12525.9 12602.6 12907.7 11435.0 12350.5 12864.4 13028.2 13084.2 06:16:44 INFO - PROCESS | 3533 | -------- Summary: end -------- 06:16:44 INFO - PROCESS | 3533 | 06:16:45 INFO - TEST-INFO | 3533: exit 0 06:16:45 INFO - TEST-OK | dromaeo_css | took 1115334ms 06:16:45 INFO - TEST-START | kraken 06:16:45 INFO - operating with platform_type : linux_ 06:16:45 INFO - Initialising browser for kraken test... 06:16:45 INFO - TEST-INFO | started process 3837 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTYXFtQ/profile http://localhost:40109/getInfo.html) 06:16:49 INFO - PROCESS | 3837 | __metrics Screen width/height:1600/1200 06:16:49 INFO - PROCESS | 3837 | colorDepth:24 06:16:49 INFO - PROCESS | 3837 | Browser inner width/height: 1024/697 06:16:49 INFO - PROCESS | 3837 | __metrics 06:16:49 INFO - PROCESS | 3837 | 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" 06:16:49 INFO - PROCESS | 3837 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:16:50 INFO - PROCESS | 3837 | console.error: PushService: 06:16:50 INFO - PROCESS | 3837 | stateChangeProcessEnqueue: Error transitioning state 06:16:50 INFO - PROCESS | 3837 | UnknownError 06:16:50 INFO - TEST-INFO | 3837: exit 0 06:16:50 INFO - Browser initialized. 06:16:50 INFO - Running cycle 1/1 for kraken test... 06:16:50 INFO - TEST-INFO | started process 3928 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTYXFtQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:17:02 INFO - PROCESS | 3928 | RSS: Main: 143679488 06:17:02 INFO - PROCESS | 3928 | 06:17:05 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40109/tests/kraken/driver.html?testName=audio-beat-detection) 06:17:05 INFO - PROCESS | 3928 | RSS: Main: 186707968 06:17:05 INFO - PROCESS | 3928 | 06:17:08 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40109/tests/kraken/driver.html?testName=audio-dft) 06:17:08 INFO - PROCESS | 3928 | RSS: Main: 173703168 06:17:08 INFO - PROCESS | 3928 | 06:17:11 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40109/tests/kraken/driver.html?testName=audio-fft) 06:17:12 INFO - PROCESS | 3928 | RSS: Main: 266403840 06:17:12 INFO - PROCESS | 3928 | 06:17:14 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40109/tests/kraken/driver.html?testName=audio-oscillator) 06:17:14 INFO - PROCESS | 3928 | RSS: Main: 172023808 06:17:14 INFO - PROCESS | 3928 | 06:17:17 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40109/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:17:17 INFO - PROCESS | 3928 | RSS: Main: 172888064 06:17:17 INFO - PROCESS | 3928 | 06:17:20 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40109/tests/kraken/driver.html?testName=imaging-darkroom) 06:17:21 INFO - PROCESS | 3928 | RSS: Main: 209612800 06:17:21 INFO - PROCESS | 3928 | 06:17:24 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40109/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:25 INFO - PROCESS | 3928 | RSS: Main: 208486400 06:17:25 INFO - PROCESS | 3928 | 06:17:27 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40109/tests/kraken/driver.html?testName=json-parse-financial) 06:17:28 INFO - PROCESS | 3928 | RSS: Main: 208285696 06:17:28 INFO - PROCESS | 3928 | 06:17:29 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40109/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:30 INFO - PROCESS | 3928 | RSS: Main: 170336256 06:17:30 INFO - PROCESS | 3928 | 06:17:32 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40109/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:17:32 INFO - PROCESS | 3928 | RSS: Main: 178257920 06:17:32 INFO - PROCESS | 3928 | 06:17:34 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40109/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:17:35 INFO - PROCESS | 3928 | RSS: Main: 186994688 06:17:35 INFO - PROCESS | 3928 | 06:17:37 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:17:38 INFO - PROCESS | 3928 | RSS: Main: 196259840 06:17:38 INFO - PROCESS | 3928 | 06:17:41 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:17:41 INFO - PROCESS | 3928 | RSS: Main: 192335872 06:17:41 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | Cycle 1(1): loaded http://localhost:40109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40109/tests/kraken/driver.html?testName=ai-astar) 06:17:43 INFO - PROCESS | 3928 | RSS: Main: 243544064 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | __start_tp_report 06:17:43 INFO - PROCESS | 3928 | _x_x_mozilla_page_load 06:17:43 INFO - PROCESS | 3928 | _x_x_mozilla_page_load_details 06:17:43 INFO - PROCESS | 3928 | |i|pagename|runs| 06:17:43 INFO - PROCESS | 3928 | |0;ai-astar;112;113;110;111;113;109;110;112;110;112 06:17:43 INFO - PROCESS | 3928 | |1;audio-beat-detection;148;115;115;155;118;123;116;120;148;121 06:17:43 INFO - PROCESS | 3928 | |2;audio-dft;160;160;160;160;163;163;163;159;166;159 06:17:43 INFO - PROCESS | 3928 | |3;audio-fft;75;77;75;122;76;76;76;75;97;75 06:17:43 INFO - PROCESS | 3928 | |4;audio-oscillator;82;82;114;82;82;81;102;81;81;110 06:17:43 INFO - PROCESS | 3928 | |5;imaging-gaussian-blur;117;116;119;116;117;117;116;117;117;116 06:17:43 INFO - PROCESS | 3928 | |6;imaging-darkroom;146;146;146;146;146;145;146;146;146;145 06:17:43 INFO - PROCESS | 3928 | |7;imaging-desaturate;95;95;95;95;95;96;95;96;95;95 06:17:43 INFO - PROCESS | 3928 | |8;json-parse-financial;48;48;50;49;48;48;47;50;49;48 06:17:43 INFO - PROCESS | 3928 | |9;json-stringify-tinderbox;59;60;59;59;60;59;59;59;60;60 06:17:43 INFO - PROCESS | 3928 | |10;stanford-crypto-aes;74;74;79;73;74;74;75;74;74;75 06:17:43 INFO - PROCESS | 3928 | |11;stanford-crypto-ccm;135;121;123;130;119;124;127;134;159;119 06:17:43 INFO - PROCESS | 3928 | |12;stanford-crypto-pbkdf2;157;149;150;150;158;151;153;151;152;152 06:17:43 INFO - PROCESS | 3928 | |13;stanford-crypto-sha256-iterative;62;62;63;62;60;61;60;60;60;60 06:17:43 INFO - PROCESS | 3928 | __end_tp_report 06:17:43 INFO - PROCESS | 3928 | __start_cc_report 06:17:43 INFO - PROCESS | 3928 | _x_x_mozilla_cycle_collect,1574 06:17:43 INFO - PROCESS | 3928 | __end_cc_report 06:17:43 INFO - PROCESS | 3928 | __startTimestamp1479651463616__endTimestamp 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | ------- Summary: start ------- 06:17:43 INFO - PROCESS | 3928 | Number of tests: 14 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:1.40 (1.2%) stddev-sans-first:1.45 06:17:43 INFO - PROCESS | 3928 | Values: 112.0 113.0 110.0 111.0 113.0 109.0 110.0 112.0 110.0 112.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:122.00 stddev:15.81 (13.0%) stddev-sans-first:15.00 06:17:43 INFO - PROCESS | 3928 | Values: 148.0 115.0 115.0 155.0 118.0 123.0 116.0 120.0 148.0 121.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:2.31 (1.4%) stddev-sans-first:2.40 06:17:43 INFO - PROCESS | 3928 | Values: 160.0 160.0 160.0 160.0 163.0 163.0 163.0 159.0 166.0 159.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:15.46 (20.3%) stddev-sans-first:16.17 06:17:43 INFO - PROCESS | 3928 | Values: 75.0 77.0 75.0 122.0 76.0 76.0 76.0 75.0 97.0 75.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:81.50 stddev:13.41 (16.5%) stddev-sans-first:13.93 06:17:43 INFO - PROCESS | 3928 | Values: 82.0 82.0 114.0 82.0 82.0 81.0 102.0 81.0 81.0 110.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 06:17:43 INFO - PROCESS | 3928 | Values: 117.0 116.0 119.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 06:17:43 INFO - PROCESS | 3928 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 145.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 06:17:43 INFO - PROCESS | 3928 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#8] json-parse-financial Cycles:10 Average:48.50 Median:48.50 stddev:0.97 (2.0%) stddev-sans-first:1.01 06:17:43 INFO - PROCESS | 3928 | Values: 48.0 48.0 50.0 49.0 48.0 48.0 47.0 50.0 49.0 48.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 06:17:43 INFO - PROCESS | 3928 | Values: 59.0 60.0 59.0 59.0 60.0 59.0 59.0 59.0 60.0 60.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.00 stddev:1.65 (2.2%) stddev-sans-first:1.73 06:17:43 INFO - PROCESS | 3928 | Values: 74.0 74.0 79.0 73.0 74.0 74.0 75.0 74.0 74.0 75.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#11] stanford-crypto-ccm Cycles:10 Average:129.10 Median:128.50 stddev:11.98 (9.3%) stddev-sans-first:12.51 06:17:43 INFO - PROCESS | 3928 | Values: 135.0 121.0 123.0 130.0 119.0 124.0 127.0 134.0 159.0 119.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:152.00 stddev:2.98 (2.0%) stddev-sans-first:2.64 06:17:43 INFO - PROCESS | 3928 | Values: 157.0 149.0 150.0 150.0 158.0 151.0 153.0 151.0 152.0 152.0 06:17:43 INFO - PROCESS | 3928 | 06:17:43 INFO - PROCESS | 3928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.50 stddev:1.15 (1.9%) stddev-sans-first:1.17 06:17:43 INFO - PROCESS | 3928 | Values: 62.0 62.0 63.0 62.0 60.0 61.0 60.0 60.0 60.0 60.0 06:17:43 INFO - PROCESS | 3928 | -------- Summary: end -------- 06:17:43 INFO - PROCESS | 3928 | 06:17:44 INFO - TEST-INFO | 3928: exit 0 06:17:44 INFO - TEST-OK | kraken | took 59490ms 06:17:44 INFO - SUITE-END | took 1174s 06:17:44 INFO - Completed test suite (00:19:35) 06:17:44 INFO - javascript benchmark 06:17:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3122.7657925655576, 3138.2154461384666, 3156.873725050999, 3186.330870517191, 3204.695553922378, 5549.528290095342, 5643.266375371201, 5656.349519805223, 5661.0376236039865, 5665.376808551061, 5533.889322213553, 5550.27846379971, 5556.583256255777, 5567.526760225375, 5575.080111781551, 4980.701157930518, 4985.476524964881, 4985.651004429682, 5014.172661510846, 5015.949840501603, 5047.596192304607, 5072.594192464594, 5075.974620126913, 5078.517540833634, 5078.949210507903, 4966.553010229079, 4994.175960966436, 5051.898962020758, 5056.241563765428, 5107.69353838769, 5120.718360490171, 5134.306868572732, 5188.066148093341, 5195.324607801, 5222.738863056832, 4946.109700253953, 5074.0612986597225, 5092.905025419553, 5128.358955130609, 5154.046501397216, 5553.416891226444, 5560.110382338847, 5569.192467092289, 5578.0, 5616.157576363537, 5972.283326000865, 5983.730732117066, 6072.332043475214, 6075.574709770318, 6076.453119219292, 5799.623024503436, 5846.298444186725, 6101.328853826076, 6122.448979591813, 6130.448259656652, 5515.145152501369, 5568.498835104779, 5570.2480165177585, 5571.359293681238, 5600.887982240414, 6127.662978536223, 6128.142060111588, 6132.938670613326, 6135.263768347827, 6140.3552626974415, 5789.710514474259, 5829.154772557956, 5893.469587737124, 5899.321578018583, 5900.144479050599, 5917.201177840936, 5965.164876917235, 5966.0, 6024.367441418588, 6032.306284777219, 5966.343702192756, 5979.222701048836, 5981.551383646157, 5987.311459182162, 6007.609505382136, 5931.0, 5933.0, 5975.641461512237, 5984.491318237999, 6016.939830601726, 4423.4912985006495, 4450.710703804242, 4474.149911516802, 4509.0, 4519.751413672214, 4101.9950112222095, 4122.3404255319, 4129.256733787947, 4137.606927341898, 4151.211269858717, 5729.828105156852, 5730.942690573041, 5732.828015159468, 5742.885142297131, 5746.13807928814, 5934.82195534126, 5959.404059594006, 6039.305479869783, 6108.755649774048, 6114.2357205349335, 5679.375268720438, 5740.512056475163, 5742.16738572913, 5776.3068431787615, 5780.855478613068, 5067.797288108434, 5091.1854103343285, 5106.540411362995, 5116.948830511722, 5134.178531434953, 6037.15479832815, 6294.559380843389, 6312.0531920211415, 6314.557980941382, 6322.810315690536, 5907.084401917709, 6034.487068599043, 6089.939100608937, 6112.602680825732, 6135.04893764628, 6263.279722831932, 6292.905606415816, 6306.716197771019, 6307.96846015767, 6310.6529140895855, 5936.851578710567, 6001.549883758823, 6003.429674180946, 6005.012481377613, 6005.789797357072, 5971.0, 5981.910271345846, 5986.0, 6005.939940600538, 6055.091736239599, 5915.378885217162, 5929.466348028698, 5936.317323507852, 5937.5249980000635, 5954.76180952757, 5919.230500034968, 5920.970395147997, 5926.851828704317, 5930.229070220844, 5936.3470018297085, 5593.916091258553, 5683.261176047087, 5831.591788574759, 5866.911996319973, 5898.970505147447, 5977.133315669291, 5987.790427335022, 5993.6403815769445, 6010.128531363015, 6023.126646636126, 5736.4550367715, 5797.797077102281, 5860.09168578871, 5860.443257890493, 5861.501772349398], "value": 5531.501870839745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.4497994375257, 601.0263373335197, 625.3402660193494, 630.1713247080089, 633.7465013994401, 35481.64518354816, 38576.0, 46749.0, 47427.52572474274, 47635.047299054, 48357.51642483574, 48837.75581122089, 48849.0, 48875.266870996966, 48990.26514602284, 9451.291153163505, 9640.517974101302, 9641.662541811038, 9650.324477286593, 9650.758731031745, 7999.160088190743, 8187.590620468983, 11051.834222486628, 11262.0, 11278.323300601991, 11446.08431325492, 11493.540258389654, 11497.942510287437, 11514.884851151464, 11547.30716157029, 11193.888061119407, 11249.156313276537, 11273.32360058395, 11274.887251127466, 11278.887211127947, 11181.105511559055, 11244.606438774603, 11254.718632034183, 11261.605843795509, 11261.774764504746, 44878.102437951384, 45136.0, 45169.32246016312, 45227.773861130816, 45262.0, 3791.696664266852, 3891.5913829047827, 3900.843966241347, 3949.7037722170953, 3949.881503554898, 33431.665683343344, 35089.0, 35670.46494302587, 36549.90350289496, 36757.63242367569, 4677.766111694402, 4721.484093674708, 4756.453007904066, 4822.590079843218, 4927.408710954708, 350059.0, 352460.0, 353238.0, 355033.0, 356466.0, 40571.59428405737, 70161.0, 71059.2894071063, 71102.2889771106, 71116.64441677759, 41013.79493102516, 42516.36225456621, 42971.78514107409, 43112.78443607762, 43137.13725725468, 16998.915005424893, 17160.22778974949, 17173.57066073358, 17175.141242937803, 17178.828211717722, 12581.182223155465, 12878.291693956922, 13959.0, 14100.858991409954, 14104.576862694135, 9604.471754053455, 9793.657221997197, 9910.900890990997, 9964.551595178234, 10020.64927727526, 3795.203007368428, 3799.7340186186702, 3810.1808111256214, 3870.574236833946, 3905.33609286422, 994.4132961552718, 1139.880312567185, 1146.1232157399595, 1218.3725381428571, 1228.3919459867423, 32246.516302255015, 34208.82895585506, 35436.64563354385, 35604.821975890474, 35682.64317356845, 1143.0912424622445, 1193.254216114928, 1212.9332886691316, 1258.5028913579085, 1262.4634530324574, 17210.741838872425, 18186.727199092024, 18244.726329105073, 18297.26810927548, 18298.359557415428, 602289.0, 610746.0, 617174.0, 619789.0, 622909.0, 60122.39877601255, 60331.69834150801, 60473.0, 60519.69740151271, 60540.39459605348, 1336.8596297388829, 1399.4192410149878, 1414.7877818327333, 1437.4106616287272, 1475.4614565683448, 30031.849840750656, 33597.32805343831, 33868.3226335467, 33873.3225335487, 34271.828640856635, 53344.4665553355, 53904.19143712926, 53946.460535394144, 53977.19034214569, 54142.18786718281, 447018.0, 449453.0, 449468.0, 450292.0, 450936.0, 27592.724072759014, 28314.2921426966, 29821.552676709434, 30375.24061898382, 30975.69024309728, 48059.27911081267, 48841.511584883694, 48853.51146488632, 49203.26195107148, 49210.01579968452, 41732.0, 41829.58170418379, 42329.78835105805, 43065.78467107644, 43252.0, 41556.0, 42289.154216916104, 42463.57536424596, 42509.31221077622, 42833.94819290848, 42033.0, 42329.365059523516, 42780.144397112505, 42871.7856410716, 43808.78095609502, 34231.0, 38397.23205535818, 38615.80692096522, 39364.21271574495, 39384.60615393924, 28724.0, 29820.701792981792, 29874.0, 35990.28019439649, 36835.0], "value": 20678.71488942244, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.4260996789224, 437.10611798871304, 443.4634092747775, 448.9371487991681, 454.69081024903073, 19397.41807745768, 21036.263730769428, 21159.57680846384, 21262.14951401946, 21494.677579836316, 18481.168347424365, 19635.312764053262, 20378.388648340537, 20573.074211660474, 20574.177032918666, 5485.61600687952, 5673.063944449161, 5673.8581535461535, 5677.687727175004, 5683.829485115453, 5420.403755586902, 5565.916511252334, 5572.721363931808, 5610.523105536014, 5612.719364031803, 5605.2713147291, 5615.157726341041, 5618.269624948751, 5618.410066942974, 5624.803131890385, 5486.890262194754, 5955.553333499983, 5996.760129594811, 6012.068129439966, 6012.789552365646, 5300.549453296474, 5480.534154596864, 5482.698451585161, 5483.8080667176455, 5489.780408783644, 15541.14523701196, 15741.606459838482, 22544.09823607054, 24092.036318547238, 24571.63142552863, 2383.5828729972186, 2418.8585607940445, 2560.884760185796, 2961.363306889029, 3013.623297087864, 10966.945165274203, 11007.944960275227, 11290.774184516264, 11460.255083419552, 11689.883101168965, 2030.7360043194437, 2071.7099606055162, 2130.6164890319737, 2155.3426205007468, 2160.3518944316797, 62829.99999999954, 66735.0, 77492.61253693752, 81491.59254203692, 91618.54190729071, 12165.391730413445, 12185.573504927463, 12211.877881221251, 12295.0, 12460.439280232411, 17624.590032797347, 17798.733019004725, 17799.911000444914, 17808.198631061634, 17826.37607683725, 8934.73195804127, 8970.641174353083, 9160.954195228982, 9195.218406435393, 9256.305777066747, 8489.872651910226, 8567.957160214159, 8618.956905215558, 8781.912180878237, 8822.51476168804, 5220.686758794409, 5347.385050719139, 5351.785928562892, 5365.9731701342025, 5505.834824955257, 2139.575171370086, 2173.9021744021557, 2189.9562008759735, 2207.631268613438, 2244.7979681828715, 912.926965842731, 969.2052516936186, 993.9254555908336, 1036.7459972306833, 1069.9251052426412, 20886.895565522074, 21325.68011479829, 21413.785862141493, 21455.249066282606, 21677.241296554545, 1000.5497526113278, 1048.8636477257908, 1058.878229003659, 1062.665260442958, 1114.8216285394299, 10967.0, 11855.110866685032, 11857.762844743056, 12061.638150855488, 12098.516059357704, 504370.0, 508248.0, 511163.0, 565317.0, 571171.0, 101794.49102754438, 105677.47161264144, 106631.0, 107874.46062769636, 109867.45066274618, 1034.234666346913, 1053.8735351757684, 1078.9514471848786, 1142.3260276436777, 1143.1426430177366, 18422.263109476007, 18530.258789648262, 19014.42956711301, 19215.32746353871, 19478.220871165562, 44878.77560612176, 45174.0, 47852.7607361961, 47900.28149577829, 48284.0, 57561.0, 61084.69457652683, 62647.6867615659, 63653.363466364746, 64345.35654643393, 13090.738185236432, 13431.664208394477, 13488.258145802076, 13615.455381785014, 13653.658658533615, 10046.246531510313, 15089.924550377616, 15162.469313573973, 15173.24133793328, 15180.772288415903, 9171.128742769426, 9337.626494940127, 9434.95282523583, 9533.0, 9594.616215351307, 9388.95305523468, 9466.384684995453, 9550.092741189575, 9552.761180970532, 9655.517224138905, 9441.858372124287, 9649.855252171363, 9658.323917326, 9708.029197080235, 9798.65704700332, 12701.87298127007, 13380.130291531019, 13380.330983450984, 13440.798388023992, 13663.726725465634, 12745.10784245131, 12805.487780488676, 12895.290759007969, 12927.870721293042, 13520.6619834501], "value": 9826.763679524072, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.8418373978866, 1539.2996186735033, 1574.0241050548661, 1735.5661084728818, 1849.963000739986, 8129.3902957278215, 9040.954795226024, 9368.782058332425, 9369.390989585672, 9641.084097010791, 9396.577154028068, 9485.814273215849, 9490.145886870181, 9490.57292421841, 9491.857622135674, 2489.589217779069, 2522.1424715596695, 2522.735112813156, 2526.60837570176, 2529.152733834168, 2496.7752902238794, 2548.732383099766, 2550.5154020736095, 2550.923472295825, 2551.2346296111186, 2494.6881639795024, 2497.7627125423146, 2562.128876182098, 2563.1541591274927, 2565.320190149612, 2494.276659768674, 2498.5252801967567, 2499.575072237724, 2533.5312967101145, 2536.074332868508, 2436.1473484280536, 2480.2931164618176, 2484.838485498437, 2487.7761001509953, 2488.688913885764, 9172.770680732969, 9345.532723363873, 9397.812043759155, 9435.481048542328, 9458.527073646359, 5468.589855760833, 5598.468145526169, 5601.691906945116, 5603.10350343943, 5605.047141985872, 3637.563492380931, 3652.853885844563, 3676.1544844685873, 3690.5571331440133, 3693.519842420495, 1336.6524703577043, 1338.130215360014, 1347.7102422979008, 1365.938532766028, 1373.176094343396, 12773.0, 12911.418986145645, 12918.741625167444, 12930.28883411412, 12939.935300323437, 4790.449098353664, 4800.95199048012, 4804.8798780030775, 4859.3682821233015, 5015.699058056528, 10249.692509224735, 10262.794744105076, 10266.691999240034, 10274.691759247235, 10277.280590358603, 5527.2538207342295, 5528.889422211533, 5531.0, 5532.889342213133, 5534.225208470817, 4625.0, 4766.7854946526795, 4986.102501549756, 5104.69371837691, 5161.4322424533275, 4156.087460250931, 4184.497860256789, 4240.681948853849, 4275.0594869128745, 4284.464441944757, 1281.3208999230424, 1320.584016034946, 1325.1982550556968, 1329.4682127149217, 1342.9462821487032, 965.290511474066, 967.8016006718606, 983.9570055740937, 989.6585677941099, 990.1286867556504, 5897.557683173779, 6040.275166979991, 6047.365026672224, 6104.236970378703, 6168.8766224675255, 483.1158979068297, 486.59126333879004, 489.9853004409873, 511.613731632615, 524.960627952905, 3877.127646279621, 4062.309407400749, 4117.505899292044, 4142.937855932163, 4219.472565929259, 11257.943710281395, 11594.072474202212, 11658.6502404928, 11683.532658693557, 11693.590724324778, 11612.941935290439, 11616.128790340757, 11628.88371116295, 11630.302181869114, 11631.418429078512, 8265.173482651686, 8487.151284871463, 8607.311415086779, 8734.781630459416, 8749.693760718344, 6035.486983606443, 6051.152838602511, 6102.6643534604245, 6122.755089796358, 6137.079438084323, 10201.336913100624, 10691.839622405514, 10692.091172250446, 10717.89282107169, 10823.67528974132, 10686.519106640375, 10901.291416057931, 10921.399323037309, 10958.616448424267, 10962.287451315638, 7013.754518591825, 7550.622468876644, 7929.048514178336, 8095.311898488459, 8347.95826020866, 9046.638134474548, 9148.405353651991, 9163.450192988443, 9169.0, 9236.0, 7493.325600695964, 7640.159582445815, 7835.725749598964, 7866.646000930086, 8129.593520324079, 7562.697492100255, 7692.9615351922885, 7712.1516633169185, 7741.961290193513, 7837.568933708701, 7158.2841715828, 7261.745838895825, 7270.854582908418, 7272.527285726411, 7274.054372931485, 5030.346055012825, 5041.798328066836, 5071.670341427795, 5090.847274581768, 5298.94701053, 5160.845174644766, 5259.079661059376, 5293.788248470018, 5300.655457395257, 5393.7033463159905], "value": 4783.5462473456255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.5351013417356, 353.9663731945464, 356.16657022567193, 359.931612993531, 368.2138634016374, 5670.1778242154915, 5691.772329106836, 5705.4294570542925, 5714.828555143347, 5736.942630573693, 5311.548518375933, 5590.86022849429, 5616.494515493605, 5642.266505354298, 5647.717614119288, 3848.9807550962278, 4294.763787991659, 4295.52749197588, 4387.451568553931, 4403.36151258069, 4393.07745373472, 4399.956000440003, 4400.581944715247, 4409.757463339515, 4429.446319210098, 4359.782010899442, 4382.948092457803, 4399.38408622793, 4400.317950717644, 4412.316091005883, 4268.957310426903, 4337.240982828024, 4343.370211319341, 4375.343698445243, 4381.890452738675, 3853.0560012796764, 3889.533256009297, 3892.2020985697864, 3899.805009749501, 3908.4332771748223, 5453.918191227135, 5470.753816078286, 5481.780728770845, 5484.972575137139, 5492.956338295751, 4852.708837469763, 5004.84985450437, 5007.148784706608, 5020.569137795711, 5047.536214497792, 5859.648421094706, 5871.5596330274975, 5893.675847828367, 5902.734376953047, 5908.704564771744, 4079.1637714268504, 4257.19113368459, 4260.829566817323, 4271.551487093872, 4273.017206042628, 5809.692819115665, 5962.761489540457, 5981.8803623927715, 6090.786822461237, 6112.480439162677, 4242.5757424257945, 4340.175366680321, 4342.196693611692, 4343.218220720238, 4348.2825333819565, 4382.780860956939, 4385.627221686129, 4395.516493185747, 4418.756968366706, 4457.977710111493, 4949.0, 5046.26829109777, 5051.004853083085, 5069.822556210515, 5102.33669622954, 5840.29916410025, 5864.830012895256, 5884.499817515473, 5928.525717942554, 5929.940700593025, 1501.6846462242834, 1518.4533567915541, 1525.9160746159068, 1533.8619524242872, 1535.0022485384588, 926.1849572376266, 965.3821554205174, 971.8299297622888, 982.7051884434641, 990.2721499697716, 4952.331435256267, 4959.628027897922, 4969.27945447908, 4972.8508144755715, 4977.855093328555, 5274.340707411574, 5282.603804714662, 5285.920711189336, 5294.179402192589, 5317.308749862493, 4720.787564559603, 4956.677815942025, 4967.950320496749, 4991.6522538915015, 4997.9750101249265, 2825.5479123340165, 2825.7598104160966, 2828.250513613894, 2835.2770043639152, 2835.9506982416224, 5411.891762164734, 5425.484578964992, 5426.972865135649, 5432.320959880015, 5467.890642187134, 5058.494150584986, 5340.572754179734, 5368.785248590013, 5372.838814835562, 5393.730313484389, 5369.436209197978, 5411.377691565521, 5432.91850622241, 5459.689674478097, 5464.9180262296095, 5277.788888444573, 5279.366476022902, 5279.894402112013, 5300.5494532963585, 5305.867353316044, 5535.778568857361, 5998.370171131968, 5998.700064996646, 6007.438066102757, 6022.90965635507, 5944.227250457413, 6100.481459076028, 6118.0, 6128.662923539248, 6271.184745982993, 4229.661627069889, 4254.723442976197, 4342.457192850894, 4348.304271316574, 4365.585269399402, 5823.70881455934, 5985.25184351956, 5992.221011268507, 6017.18767966319, 6024.156618073561, 5869.589128760946, 5881.089075764568, 5889.322727886348, 5892.35184129754, 5900.852478688067, 5849.9872565629585, 5856.853578660568, 5857.209276747755, 5878.207146820261, 5878.559108066997, 5647.5199608032135, 5845.41545845429, 5866.032104702676, 5900.616459930091, 5915.822525324247, 5962.850928726816, 5967.8209653710455, 5968.507873031742, 5970.791022314198, 5980.461758441761, 5610.49505544503, 5826.213461182686, 5833.883322333445, 5840.737166827418, 5849.883002340014], "value": 4360.411843154472, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.548817174211, 795.9442839001272, 809.4819315637989, 832.4089896173715, 934.7569631895707, 16618.086005269706, 17851.821481785177, 18089.81910180898, 18102.366417175403, 18146.364877229298, 16009.119498427583, 16170.0, 16198.514044578682, 16204.83795162048, 16495.422660206896, 864.6022829498422, 893.0043002052729, 895.3374502867896, 895.5164211325877, 896.0143841774029, 893.2898345815092, 894.2577660541767, 894.8031433084743, 899.1772528136747, 899.8965119011299, 946.981060378792, 947.8862536495631, 949.1599934058373, 949.6248981652243, 950.2445555783135, 934.2619330728717, 940.6472572785206, 941.0448394879203, 947.2232769129316, 947.6825263536723, 691.6887400669718, 692.0726226856037, 692.3837784371913, 692.4114502672738, 692.8233300508389, 17363.3054677814, 17401.390951316644, 17401.825981740272, 17403.216855241546, 17474.563135921577, 15797.21013949298, 15869.603259918478, 15876.603084922854, 16056.11691356975, 16577.336906523724, 12801.231926084372, 12919.354032298348, 13250.488690309965, 13302.201867887958, 13649.317534123353, 3996.7402118862183, 4041.373587093976, 4049.1901619675887, 4111.71218014739, 4141.97929010353, 75552.0, 78507.0, 79019.0, 82835.5858220705, 83168.0, 12238.755224895453, 12488.937555312346, 12568.560100396626, 12639.304838233986, 12784.296863672587, 22089.779102209093, 22297.3310800673, 22960.88519557425, 23235.651465228035, 23321.183758568706, 8520.57397130141, 8661.826763464822, 8734.86897696535, 8781.2535934445, 8806.515641639771, 4802.27965805132, 4901.411830580353, 4927.236278376786, 5110.872228194251, 5219.138842091088, 3815.256025075084, 3953.1303113315025, 3953.3872249801325, 3955.149642826806, 3958.7030972677167, 659.9934000659932, 660.2671035150978, 671.4292851076643, 672.9899051514232, 675.1223409567542, 649.0621052578978, 670.6579644381401, 727.2400341642996, 730.3244498838553, 773.0568706178453, 9507.619695212114, 9933.503324833873, 10114.443705596263, 10403.375797452178, 10631.149508039338, 673.4074014866886, 759.369723129812, 767.8464307138595, 805.3516918880357, 810.6068556749972, 5036.118679231193, 5038.823641172615, 5085.720285384339, 5101.489851014869, 5167.276581278624, 75038.0, 81507.0, 123170.76829231772, 134977.0, 135950.0, 73947.63026184942, 96936.51531742438, 97705.51147244219, 99627.50186249167, 116192.0, 94229.5288523553, 98719.0, 99341.50329248307, 100334.0, 102307.4884625587, 9765.755856103655, 10281.177505799667, 10318.638847640597, 10399.480025998519, 10420.947895260779, 54890.725546372014, 55470.0, 55642.44357556535, 55646.72176639091, 56504.0, 76582.2341776575, 82383.5880820592, 82922.0, 84260.57869710612, 84794.0, 13876.0, 13972.161670299409, 14036.087654302437, 14164.575062748134, 14369.281535923372, 14624.780628290371, 14851.925740371229, 15237.390504380144, 15583.29875155598, 17180.0, 14670.413183472541, 14765.557033289018, 15328.693426131638, 15749.842501574838, 15802.525924222291, 13899.861001389856, 14369.712605748036, 14462.783058253925, 15445.459408920633, 15467.303971321542, 13924.930375348058, 13974.860251397356, 14012.579622611338, 14581.854181458473, 15065.924670376578, 12354.630867128182, 12485.93757031209, 12525.937370313455, 12602.558910438458, 12907.741845163231, 11435.0, 12350.505979761068, 12864.439136040874, 13028.218306901616, 13084.168216449894], "value": 8175.779270478303, "unit": "score"}], "name": "dromaeo_css", "value": 7593.429563036084, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 110.0, 111.0, 113.0, 109.0, 110.0, 112.0, 110.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 115.0, 155.0, 118.0, 123.0, 116.0, 120.0, 148.0, 121.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 163.0, 163.0, 163.0, 159.0, 166.0, 159.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 122.0, 76.0, 76.0, 76.0, 75.0, 97.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 114.0, 82.0, 82.0, 81.0, 102.0, 81.0, 81.0, 110.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 119.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 50.0, 49.0, 48.0, 48.0, 47.0, 50.0, 49.0, 48.0], "value": 48.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 60.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 79.0, 73.0, 74.0, 74.0, 75.0, 74.0, 74.0, 75.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 121.0, 123.0, 130.0, 119.0, 124.0, 127.0, 134.0, 159.0, 119.0], "value": 129.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 149.0, 150.0, 150.0, 158.0, 151.0, 153.0, 151.0, 152.0, 152.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0], "value": 61.0, "unit": "score"}], "name": "kraken", "value": 1455.2, "alertThreshold": 2.0}]} 06:17:44 INFO - Return code: 0 06:17:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:17:44 INFO - # TBPL SUCCESS # 06:17:44 INFO - Running post-action listener: _resource_record_post_action 06:17:44 INFO - [mozharness: 2016-11-20 14:17:44.951671Z] Finished run-tests step (success) 06:17:44 INFO - Running post-run listener: _resource_record_post_run 06:17:45 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 7471104; Write bytes: 347815936; Read time: 936; Write time: 284468 06:17:45 INFO - TinderboxPrint: CPU usage
13.0% 06:17:45 INFO - TinderboxPrint: I/O read bytes / time
7,471,104 / 936 06:17:45 INFO - TinderboxPrint: I/O write bytes / time
347,815,936 / 284,468 06:17:45 INFO - TinderboxPrint: CPU idle
8,260.3 (87.0%) 06:17:45 INFO - TinderboxPrint: CPU user
1,186.0 (12.5%) 06:17:45 INFO - TinderboxPrint: Swap in / out
0 / 0 06:17:45 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 532480; Read time: 0; Write time: 56 06:17:45 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5619712; Write bytes: 347283456; Read time: 860; Write time: 284412 06:17:45 INFO - Running post-run listener: _upload_blobber_files 06:17:45 INFO - Blob upload gear active. 06:17:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:17:45 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 06:17:45 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:17:45 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:17:45 INFO - (blobuploader) - INFO - Open directory for files ... 06:17:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:17:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:17:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:46 INFO - (blobuploader) - INFO - Done attempting. 06:17:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:17:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:17:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:47 INFO - (blobuploader) - INFO - Done attempting. 06:17:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:17:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:17:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:17:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:17:50 INFO - (blobuploader) - INFO - Done attempting. 06:17:50 INFO - (blobuploader) - INFO - Iteration through files over. 06:17:50 INFO - Return code: 0 06:17:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:17:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:17:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4c27d20298a5a44851b4f4b7609a8df2af36fb8de4879881e670b096d0f4edd6607ce008ff798b1dd8ed5c3ca9bc2811c6b5ee365bc62c6b626b3545f737134", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/679e147e6ffa0e4b83afbe9de9f2fdd89e505a45b92fd09ffe84ea9b5205809be809ade55be55fd442ec222eae8917620b1476cd8a0bba2e5b33ee5dcf195132", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d13b723ec00e1635c39f1a2b3229628254ae8ea3ff742e88b6ed3139b73f6a4ee80c797be85792ffd83f9798a44f3d6cc889e0573e81cd9dab5378fe3538fd7"} 06:17:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:17:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:17:50 INFO - Contents: 06:17:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4c27d20298a5a44851b4f4b7609a8df2af36fb8de4879881e670b096d0f4edd6607ce008ff798b1dd8ed5c3ca9bc2811c6b5ee365bc62c6b626b3545f737134", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/679e147e6ffa0e4b83afbe9de9f2fdd89e505a45b92fd09ffe84ea9b5205809be809ade55be55fd442ec222eae8917620b1476cd8a0bba2e5b33ee5dcf195132", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d13b723ec00e1635c39f1a2b3229628254ae8ea3ff742e88b6ed3139b73f6a4ee80c797be85792ffd83f9798a44f3d6cc889e0573e81cd9dab5378fe3538fd7"} 06:17:50 INFO - Running post-run listener: copy_logs_to_upload_dir 06:17:50 INFO - Copying logs to upload dir... 06:17:50 INFO - mkdir: /builds/slave/test/build/upload/logs 06:17:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.058662 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-20 06:17:50.897532) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-20 06:17:50.901430) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078 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-Beta/sha512/d4c27d20298a5a44851b4f4b7609a8df2af36fb8de4879881e670b096d0f4edd6607ce008ff798b1dd8ed5c3ca9bc2811c6b5ee365bc62c6b626b3545f737134", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/679e147e6ffa0e4b83afbe9de9f2fdd89e505a45b92fd09ffe84ea9b5205809be809ade55be55fd442ec222eae8917620b1476cd8a0bba2e5b33ee5dcf195132", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d13b723ec00e1635c39f1a2b3229628254ae8ea3ff742e88b6ed3139b73f6a4ee80c797be85792ffd83f9798a44f3d6cc889e0573e81cd9dab5378fe3538fd7"} build_url:https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011392 build_url: 'https://queue.taskcluster.net/v1/task/CaivwoyTQQ2V_ARNYdKEhw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4c27d20298a5a44851b4f4b7609a8df2af36fb8de4879881e670b096d0f4edd6607ce008ff798b1dd8ed5c3ca9bc2811c6b5ee365bc62c6b626b3545f737134", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/679e147e6ffa0e4b83afbe9de9f2fdd89e505a45b92fd09ffe84ea9b5205809be809ade55be55fd442ec222eae8917620b1476cd8a0bba2e5b33ee5dcf195132", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d13b723ec00e1635c39f1a2b3229628254ae8ea3ff742e88b6ed3139b73f6a4ee80c797be85792ffd83f9798a44f3d6cc889e0573e81cd9dab5378fe3538fd7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-20 06:17:50.926328) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 06:17:50.926613) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1479642013.754004-401670078 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-20 06:17:51.011966) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-20 06:17:51.012290) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-20 06:17:51.012577) ========= ========= Total master_lag: 0.17 =========