builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1483404366.5 results: success (0) buildid: 20170102144919 builduid: 1a5ddf4d7fc9407d92a060a90df7f3d9 revision: 405b825d5efd916e6ef5a687a22f0da84bd3f52a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:06.503224) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:06.503727) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:06.504028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:06.556836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:06.557115) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-02 16:46:07-- 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% 226M=0s 2017-01-02 16:46:07 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120284 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:06.698954) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:06.699250) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016905 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:06.755974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-02 16:46:06.756334) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 405b825d5efd916e6ef5a687a22f0da84bd3f52a --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 405b825d5efd916e6ef5a687a22f0da84bd3f52a --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=9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-02 16:46:07,661 truncating revision to first 12 chars 2017-01-02 16:46:07,661 Setting DEBUG logging. 2017-01-02 16:46:07,661 attempt 1/10 2017-01-02 16:46:07,661 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/405b825d5efd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-02 16:46:08,869 unpacking tar archive at: mozilla-beta-405b825d5efd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.567293 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-02 16:46:08.337028) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:08.337370) ========= script_repo_revision: 405b825d5efd916e6ef5a687a22f0da84bd3f52a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:08.337769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:08.338046) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 16:46:08.348043) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-01-02 16:46:08.348345) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:46:09 INFO - MultiFileLogger online at 20170102 16:46:09 in /builds/slave/test 16:46:09 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 16:46:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:46:09 INFO - {'append_to_log': False, 16:46:09 INFO - 'base_work_dir': '/builds/slave/test', 16:46:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:46:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:46:09 INFO - 'branch': 'Mozilla-Beta', 16:46:09 INFO - 'buildbot_json_path': 'buildprops.json', 16:46:09 INFO - 'config_files': ('talos/linux_config.py',), 16:46:09 INFO - 'default_actions': ('clobber', 16:46:09 INFO - 'read-buildbot-config', 16:46:09 INFO - 'download-and-extract', 16:46:09 INFO - 'populate-webroot', 16:46:09 INFO - 'create-virtualenv', 16:46:09 INFO - 'install', 16:46:09 INFO - 'run-tests'), 16:46:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:46:09 INFO - 'download_minidump_stackwalk': True, 16:46:09 INFO - 'download_symbols': 'ondemand', 16:46:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:46:09 INFO - 'tooltool.py': '/tools/tooltool.py', 16:46:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:46:09 INFO - '/tools/misc-python/virtualenv.py')}, 16:46:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:46:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:46:09 INFO - 'installer_path': 'installer.exe', 16:46:09 INFO - 'log_level': 'info', 16:46:09 INFO - 'log_name': 'talos', 16:46:09 INFO - 'log_to_console': True, 16:46:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:46:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:46:09 INFO - 'opt_config_files': (), 16:46:09 INFO - 'pip_index': False, 16:46:09 INFO - 'sps_profile': False, 16:46:09 INFO - 'sps_profile_interval': 0, 16:46:09 INFO - 'suite': 'dromaeojs', 16:46:09 INFO - 'system_bits': '32', 16:46:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:46:09 INFO - 'title': 'talos-linux64-ix-040', 16:46:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:46:09 INFO - 'use_talos_json': True, 16:46:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:46:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:46:09 INFO - 'work_dir': 'build'} 16:46:09 INFO - [mozharness: 2017-01-03 00:46:09.354434Z] Running clobber step. 16:46:09 INFO - Running pre-action listener: _resource_record_pre_action 16:46:09 INFO - Running main action method: clobber 16:46:09 INFO - rmtree: /builds/slave/test/build 16:46:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:46:09 INFO - Running post-action listener: _resource_record_post_action 16:46:09 INFO - [mozharness: 2017-01-03 00:46:09.811481Z] Finished clobber step (success) 16:46:09 INFO - [mozharness: 2017-01-03 00:46:09.811583Z] Running read-buildbot-config step. 16:46:09 INFO - Running pre-action listener: _resource_record_pre_action 16:46:09 INFO - Running main action method: read_buildbot_config 16:46:09 INFO - Using buildbot properties: 16:46:09 INFO - { 16:46:09 INFO - "project": "", 16:46:09 INFO - "product": "firefox", 16:46:09 INFO - "script_repo_revision": "production", 16:46:09 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:46:09 INFO - "repository": "", 16:46:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:46:09 INFO - "buildid": "20170102144919", 16:46:09 INFO - "pgo_build": "True", 16:46:09 INFO - "basedir": "/builds/slave/test", 16:46:09 INFO - "buildnumber": 502, 16:46:09 INFO - "slavename": "talos-linux64-ix-040", 16:46:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:46:09 INFO - "platform": "ubuntu64_hw", 16:46:09 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:46:09 INFO - "branch": "mozilla-beta", 16:46:09 INFO - "repo_path": "releases/mozilla-beta", 16:46:09 INFO - "revision": "405b825d5efd916e6ef5a687a22f0da84bd3f52a", 16:46:09 INFO - "stage_platform": "linux64-pgo", 16:46:09 INFO - "builduid": "1a5ddf4d7fc9407d92a060a90df7f3d9", 16:46:09 INFO - "slavebuilddir": "test" 16:46:09 INFO - } 16:46:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 16:46:09 INFO - Running post-action listener: _resource_record_post_action 16:46:09 INFO - [mozharness: 2017-01-03 00:46:09.814327Z] Finished read-buildbot-config step (success) 16:46:09 INFO - [mozharness: 2017-01-03 00:46:09.814416Z] Running download-and-extract step. 16:46:09 INFO - Running pre-action listener: _resource_record_pre_action 16:46:09 INFO - Running main action method: download_and_extract 16:46:09 INFO - mkdir: /builds/slave/test/build/tests 16:46: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')]} 16:46:09 INFO - trying https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 16:46:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/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 16:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/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 16:46:10 INFO - Downloaded 1327 bytes. 16:46:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 16:46:10 INFO - Using the following test package requirements: 16:46:10 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 16:46:10 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:46:10 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:46:10 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:46:10 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 16:46:10 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:46:10 INFO - u'jsshell-linux-x86_64.zip'], 16:46:10 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:46:10 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:46:10 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 16:46:10 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:46:10 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 16:46:10 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:46:10 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 16:46:10 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:46:10 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:46:10 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:46:10 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:46:10 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:46:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 16:46:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:46:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 16:46:10 INFO - Expected file size: 21970951 16:46:15 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 16:46:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 16:46:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:46:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 16:46:15 INFO - Expected file size: 21970951 16:46:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 16:46:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:46:20 INFO - Fetch https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 16:46:21 INFO - Expected file size: 14414089 16:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:21 INFO - trying https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 16:46:21 INFO - Downloading https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/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 16:46:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/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 16:46:22 INFO - Downloaded 56948139 bytes. 16:46:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 16:46:22 INFO - mkdir: /builds/slave/test/properties 16:46:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:46:22 INFO - Writing to file /builds/slave/test/properties/build_url 16:46:22 INFO - Contents: 16:46:22 INFO - build_url:https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 16:46:23 INFO - Running post-action listener: _resource_record_post_action 16:46:23 INFO - Running post-action listener: set_extra_try_arguments 16:46:23 INFO - [mozharness: 2017-01-03 00:46:23.220819Z] Finished download-and-extract step (success) 16:46:23 INFO - [mozharness: 2017-01-03 00:46:23.221046Z] Running populate-webroot step. 16:46:23 INFO - Running pre-action listener: _resource_record_pre_action 16:46:23 INFO - Running main action method: populate_webroot 16:46:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:46:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:46:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:46:23 INFO - '--tppagecycles', 16:46:23 INFO - '7'], 16:46:23 INFO - 'tests': ['tsvgm']}}, 16:46:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:46:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:46:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:46:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:46:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:23 INFO - 'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:46:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:46:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:23 INFO - 'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['damp', 'tps']}, 16:46:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:23 INFO - 'tests': ['damp', 'tps']}, 16:46:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['dromaeo_dom']}, 16:46:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:46:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['basic_compositor_video']}, 16:46:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:46:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['a11yr', 16:46:23 INFO - 'ts_paint', 16:46:23 INFO - 'tpaint', 16:46:23 INFO - 'sessionrestore', 16:46:23 INFO - 'sessionrestore_no_auto_restore', 16:46:23 INFO - 'tabpaint']}, 16:46:23 INFO - 'other-e10s': {'tests': ['a11yr', 16:46:23 INFO - 'ts_paint', 16:46:23 INFO - 'tpaint', 16:46:23 INFO - 'sessionrestore', 16:46:23 INFO - 'sessionrestore_no_auto_restore', 16:46:23 INFO - 'tabpaint']}, 16:46:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:46:23 INFO - 'ts_paint', 16:46:23 INFO - 'tpaint', 16:46:23 INFO - 'sessionrestore', 16:46:23 INFO - 'sessionrestore_no_auto_restore', 16:46:23 INFO - 'tabpaint']}, 16:46:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:46:23 INFO - 'ts_paint', 16:46:23 INFO - 'tpaint', 16:46:23 INFO - 'sessionrestore', 16:46:23 INFO - 'sessionrestore_no_auto_restore', 16:46:23 INFO - 'tabpaint']}, 16:46:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['a11yr', 16:46:23 INFO - 'ts_paint', 16:46:23 INFO - 'tpaint', 16:46:23 INFO - 'sessionrestore', 16:46:23 INFO - 'sessionrestore_no_auto_restore', 16:46:23 INFO - 'tabpaint']}, 16:46:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['a11yr', 16:46:23 INFO - 'ts_paint', 16:46:23 INFO - 'tpaint', 16:46:23 INFO - 'sessionrestore', 16:46:23 INFO - 'sessionrestore_no_auto_restore', 16:46:23 INFO - 'tabpaint']}, 16:46:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['tsvgx', 16:46:23 INFO - 'tsvgr_opacity', 16:46:23 INFO - 'tart', 16:46:23 INFO - 'tscrollx', 16:46:23 INFO - 'cart']}, 16:46:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:46:23 INFO - 'tsvgr_opacity', 16:46:23 INFO - 'tart', 16:46:23 INFO - 'tscrollx', 16:46:23 INFO - 'cart']}, 16:46:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:23 INFO - 'talos_options': ['--disable-e10s'], 16:46:23 INFO - 'tests': ['tp5o']}, 16:46:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:23 INFO - 'tests': ['tp5o']}, 16:46:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:23 INFO - 'talos_options': ['--disable-e10s', 16:46:23 INFO - '--xperf_path', 16:46:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:46:23 INFO - 'tests': ['tp5n']}, 16:46:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:23 INFO - 'talos_options': ['--xperf_path', 16:46:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:46:23 INFO - 'tests': ['tp5n']}}, 16:46:23 INFO - 'talos.zip': {'path': '', 16:46:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:46:23 INFO - Running post-action listener: _resource_record_post_action 16:46:23 INFO - [mozharness: 2017-01-03 00:46:23.236656Z] Finished populate-webroot step (success) 16:46:23 INFO - [mozharness: 2017-01-03 00:46:23.236750Z] Running create-virtualenv step. 16:46:23 INFO - Running pre-action listener: _resource_record_pre_action 16:46:23 INFO - Running main action method: create_virtualenv 16:46:23 INFO - Creating virtualenv /builds/slave/test/build/venv 16:46:23 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 16:46:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:46:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:46:23 INFO - Using real prefix '/usr' 16:46:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:46:23 INFO - Installing distribute.............................................................................................................................................................................................done. 16:46:25 INFO - Installing pip.................done. 16:46:25 INFO - Return code: 0 16:46:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:46:25 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')]} 16:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:25 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')]} 16:46:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:25 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 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46:25 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 16:46:25 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 16:46:25 INFO - Using env: {'DISPLAY': ':0', 16:46:25 INFO - 'HOME': '/home/cltbld', 16:46:25 INFO - 'LANG': 'en_US.UTF-8', 16:46:25 INFO - 'LANGUAGE': 'en_US:en', 16:46:25 INFO - 'LOGNAME': 'cltbld', 16:46:25 INFO - 'MAIL': '/var/mail/cltbld', 16:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:25 INFO - 'MOZ_NO_REMOTE': '1', 16:46:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:25 INFO - 'NO_EM_RESTART': '1', 16:46:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:25 INFO - 'PWD': '/builds/slave/test', 16:46:25 INFO - 'SHELL': '/bin/bash', 16:46:25 INFO - 'SHLVL': '1', 16:46:25 INFO - 'TERM': 'linux', 16:46:25 INFO - 'TMOUT': '86400', 16:46:25 INFO - 'USER': 'cltbld', 16:46:25 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:25 INFO - '_': '/tools/buildbot/bin/python'} 16:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:26 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 16:46:26 INFO - Cleaning up... 16:46:26 INFO - Return code: 0 16:46:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:46:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:46:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:46:26 INFO - Using env: {'DISPLAY': ':0', 16:46:26 INFO - 'HOME': '/home/cltbld', 16:46:26 INFO - 'LANG': 'en_US.UTF-8', 16:46:26 INFO - 'LANGUAGE': 'en_US:en', 16:46:26 INFO - 'LOGNAME': 'cltbld', 16:46:26 INFO - 'MAIL': '/var/mail/cltbld', 16:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:26 INFO - 'MOZ_NO_REMOTE': '1', 16:46:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:26 INFO - 'NO_EM_RESTART': '1', 16:46:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:26 INFO - 'PWD': '/builds/slave/test', 16:46:26 INFO - 'SHELL': '/bin/bash', 16:46:26 INFO - 'SHLVL': '1', 16:46:26 INFO - 'TERM': 'linux', 16:46:26 INFO - 'TMOUT': '86400', 16:46:26 INFO - 'USER': 'cltbld', 16:46:26 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:26 INFO - '_': '/tools/buildbot/bin/python'} 16:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:26 INFO - Downloading/unpacking psutil>=3.1.1 16:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:46:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:46:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:46:28 INFO - Installing collected packages: psutil 16:46:28 INFO - Running setup.py install for psutil 16:46:28 INFO - building 'psutil._psutil_linux' extension 16:46:28 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 16:46:28 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 16:46:28 INFO - building 'psutil._psutil_posix' extension 16:46:28 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 16:46:28 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 16:46:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:46:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:46:28 INFO - Successfully installed psutil 16:46:28 INFO - Cleaning up... 16:46:28 INFO - Return code: 0 16:46:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:46:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:46:28 INFO - Using env: {'DISPLAY': ':0', 16:46:28 INFO - 'HOME': '/home/cltbld', 16:46:28 INFO - 'LANG': 'en_US.UTF-8', 16:46:28 INFO - 'LANGUAGE': 'en_US:en', 16:46:28 INFO - 'LOGNAME': 'cltbld', 16:46:28 INFO - 'MAIL': '/var/mail/cltbld', 16:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:28 INFO - 'MOZ_NO_REMOTE': '1', 16:46:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:28 INFO - 'NO_EM_RESTART': '1', 16:46:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:28 INFO - 'PWD': '/builds/slave/test', 16:46:28 INFO - 'SHELL': '/bin/bash', 16:46:28 INFO - 'SHLVL': '1', 16:46:28 INFO - 'TERM': 'linux', 16:46:28 INFO - 'TMOUT': '86400', 16:46:28 INFO - 'USER': 'cltbld', 16:46:28 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:28 INFO - '_': '/tools/buildbot/bin/python'} 16:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:46:29 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 16:46:29 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 16:46:29 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 16:46:29 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 16:46:29 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 16:46:29 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 16:46:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:46:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:46:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:46:31 INFO - Installing collected packages: mozsystemmonitor 16:46:31 INFO - Running setup.py install for mozsystemmonitor 16:46:31 INFO - Successfully installed mozsystemmonitor 16:46:31 INFO - Cleaning up... 16:46:31 INFO - Return code: 0 16:46:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:46:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:46:31 INFO - Using env: {'DISPLAY': ':0', 16:46:31 INFO - 'HOME': '/home/cltbld', 16:46:31 INFO - 'LANG': 'en_US.UTF-8', 16:46:31 INFO - 'LANGUAGE': 'en_US:en', 16:46:31 INFO - 'LOGNAME': 'cltbld', 16:46:31 INFO - 'MAIL': '/var/mail/cltbld', 16:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:31 INFO - 'MOZ_NO_REMOTE': '1', 16:46:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:31 INFO - 'NO_EM_RESTART': '1', 16:46:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:31 INFO - 'PWD': '/builds/slave/test', 16:46:31 INFO - 'SHELL': '/bin/bash', 16:46:31 INFO - 'SHLVL': '1', 16:46:31 INFO - 'TERM': 'linux', 16:46:31 INFO - 'TMOUT': '86400', 16:46:31 INFO - 'USER': 'cltbld', 16:46:31 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:31 INFO - '_': '/tools/buildbot/bin/python'} 16:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:31 INFO - Downloading/unpacking jsonschema==2.5.1 16:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:46:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:33 INFO - Downloading functools32-3.2.3-2.tar.gz 16:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:46:33 INFO - warning: no files found matching '*.txt' 16:46:33 INFO - no previously-included directories found matching 'build' 16:46:33 INFO - no previously-included directories found matching 'dist' 16:46:33 INFO - no previously-included directories found matching '.git*' 16:46:33 INFO - Installing collected packages: jsonschema, functools32 16:46:33 INFO - Running setup.py install for functools32 16:46:33 INFO - warning: no files found matching '*.txt' 16:46:33 INFO - no previously-included directories found matching 'build' 16:46:33 INFO - no previously-included directories found matching 'dist' 16:46:33 INFO - no previously-included directories found matching '.git*' 16:46:33 INFO - Successfully installed jsonschema functools32 16:46:33 INFO - Cleaning up... 16:46:34 INFO - Return code: 0 16:46:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:46: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')]} 16:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46: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')]} 16:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46: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', '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 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:46: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 functools32==3.2.3-2 16:46:34 INFO - Using env: {'DISPLAY': ':0', 16:46:34 INFO - 'HOME': '/home/cltbld', 16:46:34 INFO - 'LANG': 'en_US.UTF-8', 16:46:34 INFO - 'LANGUAGE': 'en_US:en', 16:46:34 INFO - 'LOGNAME': 'cltbld', 16:46:34 INFO - 'MAIL': '/var/mail/cltbld', 16:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:34 INFO - 'MOZ_NO_REMOTE': '1', 16:46:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:34 INFO - 'NO_EM_RESTART': '1', 16:46:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:34 INFO - 'PWD': '/builds/slave/test', 16:46:34 INFO - 'SHELL': '/bin/bash', 16:46:34 INFO - 'SHLVL': '1', 16:46:34 INFO - 'TERM': 'linux', 16:46:34 INFO - 'TMOUT': '86400', 16:46:34 INFO - 'USER': 'cltbld', 16:46:34 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:34 INFO - '_': '/tools/buildbot/bin/python'} 16:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:46:34 INFO - Cleaning up... 16:46:34 INFO - Return code: 0 16:46:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:46: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')]} 16:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46: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')]} 16:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46: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', '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 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:46: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 blobuploader==1.2.4 16:46:34 INFO - Using env: {'DISPLAY': ':0', 16:46:34 INFO - 'HOME': '/home/cltbld', 16:46:34 INFO - 'LANG': 'en_US.UTF-8', 16:46:34 INFO - 'LANGUAGE': 'en_US:en', 16:46:34 INFO - 'LOGNAME': 'cltbld', 16:46:34 INFO - 'MAIL': '/var/mail/cltbld', 16:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:34 INFO - 'MOZ_NO_REMOTE': '1', 16:46:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:34 INFO - 'NO_EM_RESTART': '1', 16:46:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:34 INFO - 'PWD': '/builds/slave/test', 16:46:34 INFO - 'SHELL': '/bin/bash', 16:46:34 INFO - 'SHLVL': '1', 16:46:34 INFO - 'TERM': 'linux', 16:46:34 INFO - 'TMOUT': '86400', 16:46:34 INFO - 'USER': 'cltbld', 16:46:34 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:34 INFO - '_': '/tools/buildbot/bin/python'} 16:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:34 INFO - Downloading/unpacking blobuploader==1.2.4 16:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:36 INFO - Downloading blobuploader-1.2.4.tar.gz 16:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:46:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:46:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:37 INFO - Downloading docopt-0.6.1.tar.gz 16:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:46:37 INFO - Installing collected packages: blobuploader, requests, docopt 16:46:37 INFO - Running setup.py install for blobuploader 16:46:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:46:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:46:37 INFO - Running setup.py install for requests 16:46:37 INFO - Running setup.py install for docopt 16:46:37 INFO - Successfully installed blobuploader requests docopt 16:46:37 INFO - Cleaning up... 16:46:37 INFO - Return code: 0 16:46:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:37 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 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46:37 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 16:46:37 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 16:46:37 INFO - Using env: {'DISPLAY': ':0', 16:46:37 INFO - 'HOME': '/home/cltbld', 16:46:37 INFO - 'LANG': 'en_US.UTF-8', 16:46:37 INFO - 'LANGUAGE': 'en_US:en', 16:46:37 INFO - 'LOGNAME': 'cltbld', 16:46:37 INFO - 'MAIL': '/var/mail/cltbld', 16:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:37 INFO - 'MOZ_NO_REMOTE': '1', 16:46:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:37 INFO - 'NO_EM_RESTART': '1', 16:46:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:37 INFO - 'PWD': '/builds/slave/test', 16:46:37 INFO - 'SHELL': '/bin/bash', 16:46:37 INFO - 'SHLVL': '1', 16:46:37 INFO - 'TERM': 'linux', 16:46:37 INFO - 'TMOUT': '86400', 16:46:37 INFO - 'USER': 'cltbld', 16:46:37 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:37 INFO - '_': '/tools/buildbot/bin/python'} 16:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:46:38 INFO - Running setup.py (path:/tmp/pip-L0dPFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:46:38 INFO - Running setup.py (path:/tmp/pip-rGyoJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:46:38 INFO - Running setup.py (path:/tmp/pip-PgIc8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:46:38 INFO - Running setup.py (path:/tmp/pip-X19qMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:46:38 INFO - Running setup.py (path:/tmp/pip-OsoEKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:46:38 INFO - Running setup.py (path:/tmp/pip-DhnzOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:46:38 INFO - Running setup.py (path:/tmp/pip-hAOUI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:46:38 INFO - Running setup.py (path:/tmp/pip-MNZuXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:46:38 INFO - Running setup.py (path:/tmp/pip-aZOF_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:46:38 INFO - Running setup.py (path:/tmp/pip-X7IxGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:46:38 INFO - Running setup.py (path:/tmp/pip-CVpnDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:46:38 INFO - Running setup.py (path:/tmp/pip-EGBHjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:46:38 INFO - Running setup.py (path:/tmp/pip-xarC4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:46:39 INFO - Running setup.py (path:/tmp/pip-AjQZjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:46:39 INFO - Running setup.py (path:/tmp/pip-W42ooy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:46:39 INFO - Running setup.py (path:/tmp/pip-WuJ0CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:46:39 INFO - Running setup.py (path:/tmp/pip-9R6rb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:46:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:46:39 INFO - Running setup.py install for manifestparser 16:46:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:46:39 INFO - Running setup.py install for mozcrash 16:46:39 INFO - Running setup.py install for mozdebug 16:46:39 INFO - Running setup.py install for mozdevice 16:46:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:46:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:46:39 INFO - Running setup.py install for mozfile 16:46:39 INFO - Running setup.py install for mozhttpd 16:46:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:46:39 INFO - Running setup.py install for mozinfo 16:46:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Running setup.py install for mozInstall 16:46:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Running setup.py install for mozleak 16:46:40 INFO - Running setup.py install for mozlog 16:46:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Running setup.py install for moznetwork 16:46:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Running setup.py install for mozprocess 16:46:40 INFO - Running setup.py install for mozprofile 16:46:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Running setup.py install for mozrunner 16:46:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:46:40 INFO - Running setup.py install for mozscreenshot 16:46:40 INFO - Running setup.py install for moztest 16:46:40 INFO - Running setup.py install for mozversion 16:46:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:46:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:46:41 INFO - Cleaning up... 16:46:41 INFO - Return code: 0 16:46:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:46: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')]} 16:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46: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')]} 16:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:41 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 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46:41 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 16:46:41 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 16:46:41 INFO - Using env: {'DISPLAY': ':0', 16:46:41 INFO - 'HOME': '/home/cltbld', 16:46:41 INFO - 'LANG': 'en_US.UTF-8', 16:46:41 INFO - 'LANGUAGE': 'en_US:en', 16:46:41 INFO - 'LOGNAME': 'cltbld', 16:46:41 INFO - 'MAIL': '/var/mail/cltbld', 16:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:41 INFO - 'MOZ_NO_REMOTE': '1', 16:46:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:41 INFO - 'NO_EM_RESTART': '1', 16:46:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:41 INFO - 'PWD': '/builds/slave/test', 16:46:41 INFO - 'SHELL': '/bin/bash', 16:46:41 INFO - 'SHLVL': '1', 16:46:41 INFO - 'TERM': 'linux', 16:46:41 INFO - 'TMOUT': '86400', 16:46:41 INFO - 'USER': 'cltbld', 16:46:41 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:41 INFO - '_': '/tools/buildbot/bin/python'} 16:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:46:41 INFO - Running setup.py (path:/tmp/pip-4c1YRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:46:41 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)) 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:46:41 INFO - Running setup.py (path:/tmp/pip-tue7zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:46:41 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)) 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:46:41 INFO - Running setup.py (path:/tmp/pip-EgfEqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:46:41 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)) 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:46:41 INFO - Running setup.py (path:/tmp/pip-4B6WyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:46:41 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)) 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:46:41 INFO - Running setup.py (path:/tmp/pip-ToTgmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:46:41 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)) 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:46:41 INFO - Running setup.py (path:/tmp/pip-ZOOqsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:46:41 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)) 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:46:41 INFO - Running setup.py (path:/tmp/pip-CCEG7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:46:41 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)) 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:46:41 INFO - Running setup.py (path:/tmp/pip-X9cI4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:46:41 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)) 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:46:41 INFO - Running setup.py (path:/tmp/pip-0MnnTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:46:41 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)) 16:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:46:41 INFO - Running setup.py (path:/tmp/pip-tx_kqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:46:42 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)) 16:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:46:42 INFO - Running setup.py (path:/tmp/pip-Achv8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:46:42 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)) 16:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:46:42 INFO - Running setup.py (path:/tmp/pip-SkAF2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:46:42 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)) 16:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:46:42 INFO - Running setup.py (path:/tmp/pip-L32cHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:46:42 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)) 16:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:46:42 INFO - Running setup.py (path:/tmp/pip-PFpAzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:46:42 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)) 16:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:46:42 INFO - Running setup.py (path:/tmp/pip-I0x_US-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:46:42 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)) 16:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:46:42 INFO - Running setup.py (path:/tmp/pip-yZI70D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:46:42 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)) 16:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:46:42 INFO - Running setup.py (path:/tmp/pip-BNvzkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:46:42 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)) 16:46:42 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)) 16:46:42 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)) 16:46:42 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)) 16:46:42 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)) 16:46:42 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)) 16:46:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:44 INFO - Downloading blessings-1.6.tar.gz 16:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:46:44 INFO - Installing collected packages: blessings 16:46:44 INFO - Running setup.py install for blessings 16:46:44 INFO - Successfully installed blessings 16:46:44 INFO - Cleaning up... 16:46:44 INFO - Return code: 0 16:46:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:46:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:46:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:46:44 INFO - Reading from file tmpfile_stdout 16:46:44 INFO - Current package versions: 16:46:44 INFO - argparse == 1.2.1 16:46:45 INFO - blessings == 1.6 16:46:45 INFO - blobuploader == 1.2.4 16:46:45 INFO - docopt == 0.6.1 16:46:45 INFO - functools32 == 3.2.3-2 16:46:45 INFO - jsonschema == 2.5.1 16:46:45 INFO - manifestparser == 1.1 16:46:45 INFO - mozInstall == 1.12 16:46:45 INFO - mozcrash == 0.17 16:46:45 INFO - mozdebug == 0.1 16:46:45 INFO - mozdevice == 0.48 16:46:45 INFO - mozfile == 1.2 16:46:45 INFO - mozhttpd == 0.7 16:46:45 INFO - mozinfo == 0.9 16:46:45 INFO - mozleak == 0.1 16:46:45 INFO - mozlog == 3.4 16:46:45 INFO - moznetwork == 0.27 16:46:45 INFO - mozprocess == 0.23 16:46:45 INFO - mozprofile == 0.28 16:46:45 INFO - mozrunner == 6.12 16:46:45 INFO - mozscreenshot == 0.1 16:46:45 INFO - mozsystemmonitor == 0.3 16:46:45 INFO - moztest == 0.7 16:46:45 INFO - mozversion == 1.4 16:46:45 INFO - psutil == 3.1.1 16:46:45 INFO - requests == 1.2.3 16:46:45 INFO - wsgiref == 0.1.2 16:46:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:45 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 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46:45 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 16:46:45 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 16:46:45 INFO - Using env: {'DISPLAY': ':0', 16:46:45 INFO - 'HOME': '/home/cltbld', 16:46:45 INFO - 'LANG': 'en_US.UTF-8', 16:46:45 INFO - 'LANGUAGE': 'en_US:en', 16:46:45 INFO - 'LOGNAME': 'cltbld', 16:46:45 INFO - 'MAIL': '/var/mail/cltbld', 16:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:45 INFO - 'MOZ_NO_REMOTE': '1', 16:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:45 INFO - 'NO_EM_RESTART': '1', 16:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:45 INFO - 'PWD': '/builds/slave/test', 16:46:45 INFO - 'SHELL': '/bin/bash', 16:46:45 INFO - 'SHLVL': '1', 16:46:45 INFO - 'TERM': 'linux', 16:46:45 INFO - 'TMOUT': '86400', 16:46:45 INFO - 'USER': 'cltbld', 16:46:45 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:45 INFO - '_': '/tools/buildbot/bin/python'} 16:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:45 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)) 16:46:45 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)) 16:46:45 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)) 16:46:45 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)) 16:46:45 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)) 16:46:45 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)) 16:46:45 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)) 16:46:45 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)) 16:46:45 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)) 16:46:45 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)) 16:46:45 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)) 16:46:45 INFO - Cleaning up... 16:46:45 INFO - Return code: 0 16:46:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f874ccbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', '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 16:46:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:46:45 INFO - Using env: {'DISPLAY': ':0', 16:46:45 INFO - 'HOME': '/home/cltbld', 16:46:45 INFO - 'LANG': 'en_US.UTF-8', 16:46:45 INFO - 'LANGUAGE': 'en_US:en', 16:46:45 INFO - 'LOGNAME': 'cltbld', 16:46:45 INFO - 'MAIL': '/var/mail/cltbld', 16:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:45 INFO - 'MOZ_NO_REMOTE': '1', 16:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:45 INFO - 'NO_EM_RESTART': '1', 16:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:45 INFO - 'PWD': '/builds/slave/test', 16:46:45 INFO - 'SHELL': '/bin/bash', 16:46:45 INFO - 'SHLVL': '1', 16:46:45 INFO - 'TERM': 'linux', 16:46:45 INFO - 'TMOUT': '86400', 16:46:45 INFO - 'USER': 'cltbld', 16:46:45 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:45 INFO - '_': '/tools/buildbot/bin/python'} 16:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:46:45 INFO - Cleaning up... 16:46:45 INFO - Return code: 0 16:46:45 INFO - Running post-action listener: _resource_record_post_action 16:46:45 INFO - Running post-action listener: _start_resource_monitoring 16:46:45 INFO - Starting resource monitoring. 16:46:45 INFO - [mozharness: 2017-01-03 00:46:45.490133Z] Finished create-virtualenv step (success) 16:46:45 INFO - [mozharness: 2017-01-03 00:46:45.490590Z] Running install step. 16:46:45 INFO - Running pre-action listener: _resource_record_pre_action 16:46:45 INFO - Running main action method: install 16:46:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:46:45 INFO - Reading from file tmpfile_stdout 16:46:45 INFO - Detecting whether we're running mozinstall >=1.0... 16:46:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:46:45 INFO - Reading from file tmpfile_stdout 16:46:45 INFO - Output received: 16:46:45 INFO - Usage: mozinstall [options] installer 16:46:45 INFO - Options: 16:46:45 INFO - -h, --help show this help message and exit 16:46:45 INFO - -d DEST, --destination=DEST 16:46:45 INFO - Directory to install application into. [default: 16:46:45 INFO - "/builds/slave/test"] 16:46:45 INFO - --app=APP Application being installed. [default: firefox] 16:46:45 INFO - mkdir: /builds/slave/test/build/application 16:46:45 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'] 16:46:45 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 16:46:59 INFO - Reading from file tmpfile_stdout 16:46:59 INFO - Output received: 16:46:59 INFO - /builds/slave/test/build/application/firefox/firefox 16:46:59 INFO - Running post-action listener: _resource_record_post_action 16:46:59 INFO - [mozharness: 2017-01-03 00:46:59.275212Z] Finished install step (success) 16:46:59 INFO - [mozharness: 2017-01-03 00:46:59.275455Z] Running run-tests step. 16:46:59 INFO - Running pre-action listener: _resource_record_pre_action 16:46:59 INFO - Running main action method: run_tests 16:46:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:46:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:46:59 INFO - Python 2.7.3 16:46:59 INFO - Return code: 0 16:46:59 INFO - grabbing minidump binary from tooltool 16:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:59 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 0xce5f60>, '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 0xce6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xce71d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:46:59 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 16:46:59 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 16:46:59 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 16:46:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:46:59 INFO - Return code: 0 16:46:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:46:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:46:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:46:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:46:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:46:59 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/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 16:46:59 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/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 16:46:59 INFO - Using env: {'DISPLAY': ':0', 16:46:59 INFO - 'HOME': '/home/cltbld', 16:46:59 INFO - 'LANG': 'en_US.UTF-8', 16:46:59 INFO - 'LANGUAGE': 'en_US:en', 16:46:59 INFO - 'LOGNAME': 'cltbld', 16:46:59 INFO - 'MAIL': '/var/mail/cltbld', 16:46:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:46:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:59 INFO - 'MOZ_NO_REMOTE': '1', 16:46:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:46:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:59 INFO - 'NO_EM_RESTART': '1', 16:46:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:59 INFO - 'PWD': '/builds/slave/test', 16:46:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:46:59 INFO - 'SHELL': '/bin/bash', 16:46:59 INFO - 'SHLVL': '1', 16:46:59 INFO - 'TERM': 'linux', 16:46:59 INFO - 'TMOUT': '86400', 16:46:59 INFO - 'USER': 'cltbld', 16:46:59 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416', 16:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:59 INFO - '_': '/tools/buildbot/bin/python'} 16:46:59 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/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 16:46:59 INFO - mozversion application_buildid: 20170102144919 16:46:59 INFO - mozversion application_changeset: 405b825d5efd916e6ef5a687a22f0da84bd3f52a 16:46:59 INFO - mozversion application_display_name: Firefox 16:46:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:46:59 INFO - mozversion application_name: Firefox 16:46:59 INFO - mozversion application_remotingname: firefox 16:46:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:46:59 INFO - mozversion application_vendor: Mozilla 16:46:59 INFO - mozversion application_version: 51.0 16:46:59 INFO - mozversion platform_buildid: 20170102144919 16:46:59 INFO - mozversion platform_changeset: 405b825d5efd916e6ef5a687a22f0da84bd3f52a 16:46:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:46:59 INFO - mozversion platform_version: 51.0 16:46:59 INFO - using testdate: 1483404419 16:46:59 INFO - actual date: 1483404419 16:46:59 INFO - starting webserver on 'localhost:35097' 16:46:59 INFO - SUITE-START | Running 2 tests 16:46:59 INFO - TEST-START | dromaeo_css 16:46:59 INFO - operating with platform_type : linux_ 16:46:59 INFO - Initialising browser for dromaeo_css test... 16:46:59 INFO - TEST-INFO | started process 20617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpixIrwV/profile http://localhost:35097/getInfo.html) 16:47:03 INFO - PROCESS | 20617 | __metrics Screen width/height:1600/1200 16:47:03 INFO - PROCESS | 20617 | colorDepth:24 16:47:03 INFO - PROCESS | 20617 | Browser inner width/height: 1024/697 16:47:03 INFO - PROCESS | 20617 | __metrics 16:47:04 INFO - PROCESS | 20617 | 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" 16:47:04 INFO - PROCESS | 20617 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:47:04 INFO - PROCESS | 20617 | console.error: PushService: 16:47:04 INFO - PROCESS | 20617 | stateChangeProcessEnqueue: Error transitioning state 16:47:04 INFO - PROCESS | 20617 | UnknownError 16:47:05 INFO - TEST-INFO | 20617: exit 0 16:47:05 INFO - Browser initialized. 16:47:05 INFO - Running cycle 1/1 for dromaeo_css test... 16:47:05 INFO - TEST-INFO | started process 20707 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpixIrwV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:16 INFO - PROCESS | 20707 | RSS: Main: 143183872 16:47:16 INFO - PROCESS | 20707 | 16:48:08 INFO - PROCESS | 20707 | 1483404488016 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] 16:50:17 INFO - PROCESS | 20707 | Cycle 1(1): loaded http://localhost:35097/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35097/tests/dromaeo/cssquery-ext.html) 16:50:18 INFO - PROCESS | 20707 | RSS: Main: 246431744 16:50:18 INFO - PROCESS | 20707 | 16:53:20 INFO - PROCESS | 20707 | Cycle 1(1): loaded http://localhost:35097/tests/dromaeo/cssquery-ext.html (next: http://localhost:35097/tests/dromaeo/cssquery-jquery.html) 16:53:21 INFO - PROCESS | 20707 | RSS: Main: 231079936 16:53:21 INFO - PROCESS | 20707 | 16:56:23 INFO - PROCESS | 20707 | Cycle 1(1): loaded http://localhost:35097/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35097/tests/dromaeo/cssquery-mootools.html) 16:56:23 INFO - PROCESS | 20707 | RSS: Main: 202842112 16:56:23 INFO - PROCESS | 20707 | 16:59:25 INFO - PROCESS | 20707 | Cycle 1(1): loaded http://localhost:35097/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35097/tests/dromaeo/cssquery-prototype.html) 16:59:26 INFO - PROCESS | 20707 | RSS: Main: 217772032 16:59:26 INFO - PROCESS | 20707 | 17:02:28 INFO - PROCESS | 20707 | Cycle 1(1): loaded http://localhost:35097/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35097/tests/dromaeo/cssquery-yui.html) 17:02:29 INFO - PROCESS | 20707 | RSS: Main: 175861760 17:02:29 INFO - PROCESS | 20707 | 17:05:31 INFO - PROCESS | 20707 | Cycle 1(1): loaded http://localhost:35097/tests/dromaeo/cssquery-yui.html (next: http://localhost:35097/tests/dromaeo/cssquery-dojo.html) 17:05:31 INFO - PROCESS | 20707 | RSS: Main: 256561152 17:05:31 INFO - PROCESS | 20707 | 17:05:31 INFO - PROCESS | 20707 | __start_tp_report 17:05:31 INFO - PROCESS | 20707 | _x_x_mozilla_page_load 17:05:31 INFO - PROCESS | 20707 | _x_x_mozilla_page_load_details 17:05:31 INFO - PROCESS | 20707 | |i|pagename|runs| 17:05:31 INFO - PROCESS | 20707 | |0;dojo.html;3146.4493713600123;3188.8405579721016;3190.7447404207664;3208.663090375512;3231.9192020199494;5653.236813030245;5741.856453588657;5751.166080918264;5790.739416726247;5798.739056742441;5664.773409063643;5726.856828579288;5731.398203188657;5739.139129130633;5770.4518070783315;5164.586833053366;5181.222816577525;5203.739813009353;5259.737013149327;5267.710275934822;5053.140966035764;5265;5275.0768615492325;5278;5280.471952804708;5064.772085256154;5142.254373115896;5160;5172.043172013171;5224.895502089956;5229.633925625208;5242.501962313593;5308.8141915033175;5309.070912590281;5338.973305133488;5054;5135.101357262464;5224.660397074178;5269.525742683177;5279.577633789288;5548.472895074961;5595.720213989325;5642.689652069134;5669.603127781057;5738.368779434259;5979.073243647242;6015.819525414245;6088.695565221721;6104.023356262976;6109.633421994695;5878.50032747217;6105.633661980295;6118.847028824271;6128.601640893327;6130.2030736004035;5510.9448905510835;5606.074997625387;5631.4087020863035;5641.041023026095;5688.061469857427;6248.625082494974;6256.092866534327;6256.655883926428;6262.060690896311;6263.404976527223;5758.395368486333;6023.789167379208;6024.3072046714315;6024.3072046714315;6032.306284777219;5897.085951677497;5970.731317090741;6027.578069535178;6034.336223015464;6127.4485296322655;6023.969880150571;6029.698515074228;6034.034554471351;6038.396160383926;6048.697565121726;5975.163477113208;6037.335893051761;6048.758049677964;6067.757289708362;6076.361981991828;4535.138323718483;4578.153041687298;4627.305904114342;4699.671023028355;4717.033008233304;4171.0615111600255;4172.979135104366;4178.832846686159;4182.205380977604;4196.034911970264;5861.472467477947;5866.970665146732;5874;5874.089516125007;5882.205902203234;5956.106584012433;6020.036794112921;6033.18551995475;6052.636841789418;6178.042403427476;5806.970965145232;5825.126231065374;5828.912566311508;5854.970725146347;5863.912041319383;5280.894382112337;5302.469753024744;5302.814401496006;5303.522682958553;5322.866928326745;6091.573589848668;6130.489860652645;6170.352113028068;6285.497160227171;6310.05349197624;6136.815895523141;6167.259928808392;6179.938200617937;6187.071939209155;6192.81421557354;6348.143000694847;6357.4596159326975;6357.491400688026;6358.650274234961;6358.777442789666;5803.071508558779;5926.762929482772;6079.148919151234;6085.117657939662;6085.4523092921845;6121.602095863755;6148.108524263867;6169.56813023102;6229.190205273286;6244.188255526752;6022.638427560615;6061.848453788691;6064.909026364697;6087.208662873798;6111.297200821962;5797.304323481209;5994.108397523893;6048.274207095001;6051.546134039877;6061.484773794101;5779.68211748341;5780.104083867007;5860.882782344244;5861.208736820645;5955.791547295824;5967.492763115184;5969.253843269591;5977.701114944322;5979.282486101695;5982;5701.828945131652;5756.68338241384;5815.5929084963645;5869.647821130746;5872.324682661549 17:05:31 INFO - PROCESS | 20707 | |1;ext.html;592.7777083593652;601.7382438639191;635.7043974551832;662.0169048962288;665.2682049745277;36521;44266.778666106664;45079;45100.77449612755;49069.50930490694;46750.53249467504;47008.52991470084;47145.52854471454;47238.52761472384;47274;8522.105178956213;9186.954065229682;9330.62677492903;9337.859932101024;9355.017723139075;8441.831163376728;10844.891551084507;11146.108311335114;11156;11482.0814334853;11294.830577541343;11367.147463940197;11368.772624547504;11376.544938202462;11376.772464550704;11148.05241554469;11214.943925280322;11218.27081239725;11221.158413119048;11222.775544489146;11022.614208502664;11074.501647425885;11077.50151243188;11082.05802506788;11096.389698566576;44193.77903110464;44427.11145777066;44438.33342499865;44523.55476445259;44581.554184458066;3779.111908701453;3859.787711675857;3930.960690393088;3998.2203470323298;4285.507166675841;33661.15847103817;33702.83148584241;33729.15677108067;33897.49153762696;34087.659123408695;4552.9772351138035;4694.131585656631;4704.129735998851;4791.664583479158;4881.121398148367;337644;338125.309373454;338864;339789;340163;61568.69215653893;61913.9755915864;61944;62048;62214.68892655508;39112.608873911464;40659.796701016305;40716.38925416121;42365.57634423678;42393.15213695709;19344.90327548353;19554.804451955584;19577.412677619694;19584.608307833765;19587.11857966395;13212.207267563977;13608.251546164858;14284.428622855177;14339.354729037219;14566.781498277534;20412;20438.386848394573;20502.897485512476;20603.484912876997;20614.793852061586;7401.740939067215;7470.70117195317;7489.475736698379;7558.2441755823975;7651.579163145969;967.3953778888194;1016.1413605503337;1113.2207454781685;1137.9943100284445;1150.246588484544;34020.65979340224;34062.148446289044;34225;34235.14412139716;34882.47676284858;1130.231442619027;1142.714321419645;1153.4982282707049;1247.3326770177912;1250.531050855929;17836.643267134583;17894.821051789575;17923.28306867711;18065.819341806677;18109.547261318574;535540;540101;540430;541360;544234;59817.70091149603;59819;60541.394586054455;60608;60793.69603151956;1292.9353532323346;1375.2917247617486;1411.611806753151;1445.6530432696288;1452.992735036318;30725.692743072283;35095.649043509235;35613;35926.461103082955;36019.81990090033;52271;52758.472415275355;52825.471745282055;52870.47129528655;52916.470835291155;437047;440069;440365;440843;441631;26590.468190636468;27913.44173116567;29348.55977160387;30267.394652107276;30634.84682576573;49679.25481117859;49737.502624973284;49969.50030499649;50332.49667503278;51777.22334165066;42832.57167428286;43226.35160472533;43504.34743478914;43659;45025.77487112675;42542.36186457144;42836.5092488369;43002.569974299855;43040.78479607582;43576.78211608922;42532;43603;44114.779426102665;44124.77937610292;44146;35965.82017089898;38042;38674;38945.80527097346;39028.41457378085;28517.047747644374;28569.28576785597;28836.134915952556;28972.565411519266;29085.7091429083 17:05:31 INFO - PROCESS | 20707 | |2;jquery.html;396.8868872371373;405.722080374943;413.8158519458841;417.5824175824177;437.30250250849883;19120.42638720839;20446.591068178626;20590.279340223096;20727.481812954684;20892.268770593033;19979;20208.39374818754;20500.8974955125;20715.274965376215;20824.68762968553;5744.4829965303115;6000.729967151467;6007.339192688801;6016.217891674098;6026.819195424122;5818.912716309259;5898.174255604219;5969.5821292509545;5990.400959904018;6097.359777223394;6168.074788781674;6194.163787888622;6196.75212991482;6207.503399728011;6208.565400421972;5941.079132734411;6066.878662426749;6105.145279660851;6113.449789518964;6146.108814221935;5936;5943.970280148572;5949.286085669704;5952.255968003999;5954.4938680212235;22599.661005084938;23449.882750586137;23486;23639.645405318934;23787.881060594587;2392.342105920868;2484.2671411933525;2551.4769472258326;2576.2915198320425;2685.9597106043425;10668.626598069108;10725.517351719114;10892.727681808019;11059.447027648584;16039.759403608956;2072.3886453496257;2100.789921007902;2146.098638571804;2171.0989939175383;2279.332495392157;66991;74744;79270.20729792686;99613.5019324906;104129.4793526035;17185.828141718674;18070.548236293936;18132.546686332684;19105.522361941064;19210.711839322423;34586.827065865014;34858.65141348605;34866.8619824217;34939.82530087333;35218;11231.831522527169;11273.774524509465;11281.32312061279;11323.207375483802;11558.768824623461;9529.332946693803;9570.33007689455;9608.423494590346;9609.759756006155;9838.16375608067;9479.810403791886;9744.90255097454;9846.556904939294;9854.260930430102;10015.248856335804;3599.8200089995394;3666.7249956253386;3672.8163591820303;3688.7233457490797;3803.1062700265416;928.1971095002909;937.1612406895927;994.8308787506141;1045.3153184663904;1046.1735229169024;19462.902685486766;20057;20306.172444480067;20395.796042039685;20689.68965465519;992.6624947517879;998.32612986234;1014.5535964175887;1043.4417586591137;1048.16670746755;10888.455577221108;11597.18819682614;11618.419079046183;11626.651200463999;11672.299662020136;475253;483693;484013;490450;517307;93872;97500.51249743706;97702;99496;103013;963.4700914496999;993.215359865698;1018.3228153278135;1023.8464230365505;1024.8975102489692;11544.480498377761;12664.620061398173;17787.644247114727;18274.543136421165;18582.721259180853;40815;41807.790961045;42387.152256955305;43931.34102988522;45580.54419455763;57081;57617;58632.70683646554;58900.41099589121;61030;13329.800052999019;13583.592492225249;13624.727505450033;13709.588712338646;13874.861251387358;14937;15008.549743507712;15169.393224270905;15467.690646187239;15649.530514084596;9398.389104708172;12022.27866328023;12516.12387132892;13036.674083147996;13092.149010314299;12883;12994.545190918272;13060.346982651019;13061.477540898257;13625.795613065613;12905.612831615066;12933.22400655926;13020.28388438607;13084;13414.530491432753;13479.865201348253;13542.79685804694;13587.86412135866;13618.591442256748;13643.386047628079;13000.739985200433;13175.2753598553;13209.207447553177;13220.933895330461;13298.069135160446 17:05:31 INFO - PROCESS | 20707 | |3;mootools.html;1418.524794193945;1473.7052589482103;1608.1396452897695;1802.2245741558002;1838.4576549917772;8808.16322449367;9304.86042709359;9317.208037316836;9318.394304370211;9320.300977426694;7775;8018.358531317495;8697.52163631;9015.278777697786;9152.8627070594;2460.1758410932316;2482.987585062072;2484.763947424992;2486.316263027664;2487.9004839806385;2376.3346263046374;2451.705795304566;2466.580681284177;2476.2818782553036;2480.193936970492;2449.8285120041605;2451.0930955546473;2466.1984854922134;2468.4075821802817;2470.1354525915967;2418.153646223825;2419.685440892673;2422.733499315074;2437.3297343230574;2445.9388515287083;2381.9642705359433;2383.6186210206283;2387.1644924276366;2388.080588973258;2396.221228100857;8886.777830554223;8994.820103597891;9105.089491050909;9203.861942070875;9261;5594.440555944414;5720.141978703186;5723.112917497794;5731.283589551306;5735.4551317624755;3513.5608048993877;3653.872114476007;3653.8903832885057;3682.1899182179877;3703.129764505366;1205.9276443413335;1256.2839181666454;1257.3210466348162;1265.1144199060695;1292.1923797626484;12743.490260389573;12869.742605148032;12889.548865789653;12892.033097517724;12899.67750806228;4860.902781944341;4909.7790599423115;5018.673786203885;5050.949490505048;5089.821856235014;10752.892471075345;10755.623553175757;10812.67561973142;10888.237823352289;10890.564377424815;5585.748641311069;5606;5606.243157173811;5609.523190528851;5612.803551875746;4480.260756975063;4543.886402839956;4808.519148085164;5008.424031236382;5200.531952124327;4236.131593023423;4257.531671516131;4306.1172459645695;4357;4387.868363949086;1329.694170340827;1343.1940835498622;1349.4939397725852;1357.633438971472;1372.8970327225497;973.7468258252763;990.3463713948903;993.3791380387258;1010.4998025977188;1024.2676486312323;5901.380355062656;5986.132010858401;6098.268207815002;6170.136180934673;6193.071039344044;496.76155445386416;502.78631581577685;512.2444394518056;513.7405610166917;513.8972205558903;4034.3141665916864;4034.616711412411;4114.305130139764;4140.399642051885;4157.355609880473;11046.723831904266;11349.09207263417;11419;11424.600138995096;11435.085193184525;11303.604373846876;11345;11356.432178391047;11361.886381136248;11364.431778411046;8647.481151130953;8723.65105395777;8726.563671816511;8726.956365218133;8732.34507411934;5670.829875103754;5782.797602083907;5842.269716285465;5889.469947704727;5907.497862681548;10418.739531511774;10481.633142839964;10490.580376784843;10520.736981575521;10539.894601053891;10656.840147397641;10800.135989121009;10852.240343175901;10887.836682449612;10896.56413743473;7351.742689005859;7581.090269167735;7763;8120.431569790294;8177.550234736908;8642.654293828176;9069.637214511346;9087.772805679911;9089.5000774958;9094.408863423856;7533.54798712079;7567;7658.081030276402;7786.065672119382;7826.7651970440975;7412.221716719667;7504.774856754306;7542.47202695806;7546.849063018819;7577.431692623186;6535.379138981789;6661.4004739573675;6666.2333831609985;6670.166229221348;6670.899936500859;5206.2450944612065;5208.322918020633;5213.582913366998;5248.160294352885;5444.537214336668;5339.893202136013;5367.624266301322;5407.378151512469;5444.972775136099;5576.832695019156 17:05:31 INFO - PROCESS | 20707 | |4;prototype.html;350.7702454892047;353.80363898036586;359.2169071424294;360.44491483116;367.71502085883435;5560;5568.415316391782;5587.106063029916;5608.52327552158;5616.466435688613;5247.632665713401;5296.655717378368;5409.24270602116;5462.9726851365685;5528.668279903209;4378.627816635574;4386.517483076867;4400.8679739607705;4404.4934832494355;4430.734155950637;4380.956190438103;4396.824127034914;4412.4925633552075;4414.580614841601;4422.6240769534625;4069.613386728271;4238.7032907696475;4305.375720520523;4325.767156360438;4391;4298.978505107454;4302.462192225972;4322.891927701817;4326.351047342908;4334.436523251957;3833.4058220975794;3835.846566137351;3841.2893614681375;3842.9807850960847;3844.711646626486;5200.6619569727845;5344.812931547377;5386.622936394454;5409.891802163935;5435.239066530688;4935.333729946447;4942.431620363668;4948.158813001763;4953.752312384402;4979.278004689336;5768.42315768424;5771.913421298684;5774.16274640179;5784.537237021028;5803.88392232153;4130.463039804837;4211.094614657833;4223.577642235782;4232.386303985935;4237.173751118533;5972.940270597282;5975.52195824333;5988.580799344048;6011.57918945674;6016.729247183887;4329.631981281626;4333.826646934151;4335.263005289108;4338.58783415575;4342.131573685276;4220.409142720022;4301.526832048472;4307.655387568987;4342.2261272415135;4396.824127034947;4889.655344780213;4896.338994235733;4904.852854414374;4907.460179380337;4908.607311415079;5798.391168927202;5805.651660900359;5828.562857785683;5839.9124013139835;5854.248503203487;1455.8835293176521;1475.6089636246404;1476.6677497562962;1489.7020595880867;1490.4559835660123;951.7715748220515;956.5073986896754;964.3008818606594;965.3339195954769;973.6543527047937;4837.814735389782;4905.5094490550655;4923.556879880756;4926.926096108561;4930.7288099154175;5164.974175129176;5201.375834899836;5221.817236396708;5261.973690131525;5266.420693723688;4718.947257942806;4917.7786999585105;5039.840836607601;5049.444561098277;5069.366329208849;2762.5027495051086;2770.3074231442138;2771.6673999120235;2795.147480018614;2811.1707046421357;5301.257823904656;5356.250124982505;5367.1483338248245;5389.622726409114;5395.676259424447;5290.259363689087;5309.283246761715;5714.885702286014;6136.662483563447;6154.846128846725;6115.052166914135;6121.724522396503;6132.908006379907;6135.018397056539;6159.907601385983;5754.5684073695475;5968.791092311922;5979.402059793986;6011.054583254261;6015.4809436480145;5908.881822363443;5912.438318359749;5985.760569577168;5987.6108052976415;6000.859836631026;6137.723802428991;6155.938440615537;6165.815025549241;6171.413715697002;6189.721462534107;4128.587141285968;4133.318002529549;4252.468441444819;4264.4456220692355;4276.786160692015;5804.722960948585;5844.707764611838;5984.8803023940145;5996.730147143477;6016.8784382697995;5828.154917536848;5843;5844.620099693507;5850.970745146247;5862.882742345215;5745.7988970387505;5855.824325270249;5859.94140058594;5867.559933005127;5867.946218823377;5817.883642327214;5834.445727655866;5840.474357307863;5860.443257890493;5863.589548731547;5638.915416268677;5923.703814809329;5925.585209035326;5931.169636251015;5944.959632064285;5709.400512946081;5800.912986305125;5802.390748971297;5806.970965145148;5822 17:05:31 INFO - PROCESS | 20707 | |5;yui.html;813.2274339377591;834.5117409229423;841.457260067257;883.6067949762356;901.68441045634;16447.75328370074;17613.207405666744;17697.55756106098;17802.82197178028;17814.931104133746;14902.329395177216;14964.850351496481;15015.174165420925;15949.122798246091;16639.084850333256;853.3855623950745;870.5647176411794;874.152072489684;874.3660845886745;875.8817559629464;724.3155218318691;745.5861996591889;854.8803167556547;872.4634349874813;879.2087121590588;922.2161163011453;927.23966347595;927.9675211367603;928;928.9442633441981;919.8482250428705;920.903305152956;921.8432866412659;922.2437601167046;922.916937475627;683.2723149845449;684.428502200663;685.4345165238648;685.4790359326947;685.6674512861258;16044;16062.839371606251;16101.516954491384;16149.677006459806;16157.434489792919;16293.755593666105;16305.755413668805;16456.094914779682;16865.831341686673;16982.40561580339;13330.800037999437;13517.526886558922;13538.59384218475;13589.252591107484;13725.45098196071;3936.8031598419966;3999.2601368746873;4014.739041962263;4053.7973101344814;4095.24238015965;87202.56398717966;87454;88588;89152.10847891503;89935.55032224863;11923.642290731292;11934.403279835973;11971.46128424223;12011.759764804656;12223.449944752507;23750.64374034391;23757;23878.88060559686;23890.163844265368;24022.759772402052;9310.37068513021;9335.626574936927;9443.584780802417;9534.332596718163;9811.215102791759;5008.29883816266;5321.201819727072;5346.385165705916;5431.429699881534;5438.102713052302;3746.5691445484117;3875.980620096938;3879.922401551953;3885.592012838668;3891.5330160380936;653.4837478392112;662.1524448705665;665.866826634675;668.0713807807152;685.6194811879355;754.35125791819;754.5563020799771;758.768575584452;785.2736218997519;799.1808396393743;10257.384556926609;10389.480525973671;10486.790264194673;10908.400037997828;10915.126789856793;679.6465837764334;691.2292793535172;754.9509281896659;777.4557809533236;797.924197201265;5239.963002715394;5277.287566178594;5334.226537152169;5352.625316227826;5467.644603100865;75006;75303;75982.24017759752;125425.37287313506;127832;64590.35409645843;65902.67048664822;79516;97108.51445742867;111704.44147779209;63065.36934630687;73029;77521.6123919388;98062.50968745253;100696;10088.293819432864;10343.63797267092;10393.272470926962;10431.895681043092;10479.895201047892;56102.43897561135;57552.71223643855;57827.71086144543;58023.4197658018;58845.411545885705;68283.65858170876;76032;84258;84804;84847;13392.531261405804;13528.93235533816;13861.534019058128;14073.507427239998;14697.85302146965;16861.831381686025;16868.831311687216;16881.831181688027;18242.45272641823;18347.174377152794;15448.30482628262;15528.14595197275;15711.764323535384;16669.66660666804;16981.830181698024;14252.786208206679;14469.00066430679;15018.24908754536;15480.303386347416;15830.28763705613;12508.624741257778;13965.231912244517;14381.712365752835;14517.346719397441;15747.527574172793;12295;12536.74926501483;12902.935485322514;13121.475140994253;13473.46106155782;12673.8098928518;12678.366081695694;12691.619251422102;12784.6803829905;12887.871121288666 17:05:31 INFO - PROCESS | 20707 | __end_tp_report 17:05:31 INFO - PROCESS | 20707 | __start_cc_report 17:05:31 INFO - PROCESS | 20707 | _x_x_mozilla_cycle_collect,849 17:05:31 INFO - PROCESS | 20707 | __end_cc_report 17:05:31 INFO - PROCESS | 20707 | __startTimestamp1483405531973__endTimestamp 17:05:31 INFO - PROCESS | 20707 | 17:05:31 INFO - PROCESS | 20707 | ------- Summary: start ------- 17:05:31 INFO - PROCESS | 20707 | Number of tests: 6 17:05:31 INFO - PROCESS | 20707 | 17:05:31 INFO - PROCESS | 20707 | [#0] dojo.html Cycles:165 Average:5663.89 Median:5869.65 stddev:650.14 (11.1%) stddev-sans-first:621.42 17:05:31 INFO - PROCESS | 20707 | Values: 3146.4 3188.8 3190.7 3208.7 3231.9 5653.2 5741.9 5751.2 5790.7 5798.7 5664.8 5726.9 5731.4 5739.1 5770.5 5164.6 5181.2 5203.7 5259.7 5267.7 5053.1 5265.0 5275.1 5278.0 5280.5 5064.8 5142.3 5160.0 5172.0 5224.9 5229.6 5242.5 5308.8 5309.1 5339.0 5054.0 5135.1 5224.7 5269.5 5279.6 5548.5 5595.7 5642.7 5669.6 5738.4 5979.1 6015.8 6088.7 6104.0 6109.6 5878.5 6105.6 6118.8 6128.6 6130.2 5510.9 5606.1 5631.4 5641.0 5688.1 6248.6 6256.1 6256.7 6262.1 6263.4 5758.4 6023.8 6024.3 6024.3 6032.3 5897.1 5970.7 6027.6 6034.3 6127.4 6024.0 6029.7 6034.0 6038.4 6048.7 5975.2 6037.3 6048.8 6067.8 6076.4 4535.1 4578.2 4627.3 4699.7 4717.0 4171.1 4173.0 4178.8 4182.2 4196.0 5861.5 5867.0 5874.0 5874.1 5882.2 5956.1 6020.0 6033.2 6052.6 6178.0 5807.0 5825.1 5828.9 5855.0 5863.9 5280.9 5302.5 5302.8 5303.5 5322.9 6091.6 6130.5 6170.4 6285.5 6310.1 6136.8 6167.3 6179.9 6187.1 6192.8 6348.1 6357.5 6357.5 6358.7 6358.8 5803.1 5926.8 6079.1 6085.1 6085.5 6121.6 6148.1 6169.6 6229.2 6244.2 6022.6 6061.8 6064.9 6087.2 6111.3 5797.3 5994.1 6048.3 6051.5 6061.5 5779.7 5780.1 5860.9 5861.2 5955.8 5967.5 5969.3 5977.7 5979.3 5982.0 5701.8 5756.7 5815.6 5869.6 5872.3 17:05:31 INFO - PROCESS | 20707 | 17:05:31 INFO - PROCESS | 20707 | [#1] ext.html Cycles:180 Average:61000.24 Median:39070.51 stddev:118345.08 (302.9%) stddev-sans-first:118590.16 17:05:31 INFO - PROCESS | 20707 | Values: 592.8 601.7 635.7 662.0 665.3 36521.0 44266.8 45079.0 45100.8 49069.5 46750.5 47008.5 47145.5 47238.5 47274.0 8522.1 9187.0 9330.6 9337.9 9355.0 8441.8 10844.9 11146.1 11156.0 11482.1 11294.8 11367.1 11368.8 11376.5 11376.8 11148.1 11214.9 11218.3 11221.2 11222.8 11022.6 11074.5 11077.5 11082.1 11096.4 44193.8 44427.1 44438.3 44523.6 44581.6 3779.1 3859.8 3931.0 3998.2 4285.5 33661.2 33702.8 33729.2 33897.5 34087.7 4553.0 4694.1 4704.1 4791.7 4881.1 337644.0 338125.3 338864.0 339789.0 340163.0 61568.7 61914.0 61944.0 62048.0 62214.7 39112.6 40659.8 40716.4 42365.6 42393.2 19344.9 19554.8 19577.4 19584.6 19587.1 13212.2 13608.3 14284.4 14339.4 14566.8 20412.0 20438.4 20502.9 20603.5 20614.8 7401.7 7470.7 7489.5 7558.2 7651.6 967.4 1016.1 1113.2 1138.0 1150.2 34020.7 34062.1 34225.0 34235.1 34882.5 1130.2 1142.7 1153.5 1247.3 1250.5 17836.6 17894.8 17923.3 18065.8 18109.5 535540.0 540101.0 540430.0 541360.0 544234.0 59817.7 59819.0 60541.4 60608.0 60793.7 1292.9 1375.3 1411.6 1445.7 1453.0 30725.7 35095.6 35613.0 35926.5 36019.8 52271.0 52758.5 52825.5 52870.5 52916.5 437047.0 440069.0 440365.0 440843.0 441631.0 26590.5 27913.4 29348.6 30267.4 30634.8 49679.3 49737.5 49969.5 50332.5 51777.2 42832.6 43226.4 43504.3 43659.0 45025.8 42542.4 42836.5 43002.6 43040.8 43576.8 42532.0 43603.0 44114.8 44124.8 44146.0 35965.8 38042.0 38674.0 38945.8 39028.4 28517.0 28569.3 28836.1 28972.6 29085.7 17:05:31 INFO - PROCESS | 20707 | 17:05:31 INFO - PROCESS | 20707 | [#2] jquery.html Cycles:180 Average:31062.02 Median:12864.61 stddev:80807.49 (628.1%) stddev-sans-first:81001.38 17:05:31 INFO - PROCESS | 20707 | Values: 396.9 405.7 413.8 417.6 437.3 19120.4 20446.6 20590.3 20727.5 20892.3 19979.0 20208.4 20500.9 20715.3 20824.7 5744.5 6000.7 6007.3 6016.2 6026.8 5818.9 5898.2 5969.6 5990.4 6097.4 6168.1 6194.2 6196.8 6207.5 6208.6 5941.1 6066.9 6105.1 6113.4 6146.1 5936.0 5944.0 5949.3 5952.3 5954.5 22599.7 23449.9 23486.0 23639.6 23787.9 2392.3 2484.3 2551.5 2576.3 2686.0 10668.6 10725.5 10892.7 11059.4 16039.8 2072.4 2100.8 2146.1 2171.1 2279.3 66991.0 74744.0 79270.2 99613.5 104129.5 17185.8 18070.5 18132.5 19105.5 19210.7 34586.8 34858.7 34866.9 34939.8 35218.0 11231.8 11273.8 11281.3 11323.2 11558.8 9529.3 9570.3 9608.4 9609.8 9838.2 9479.8 9744.9 9846.6 9854.3 10015.2 3599.8 3666.7 3672.8 3688.7 3803.1 928.2 937.2 994.8 1045.3 1046.2 19462.9 20057.0 20306.2 20395.8 20689.7 992.7 998.3 1014.6 1043.4 1048.2 10888.5 11597.2 11618.4 11626.7 11672.3 475253.0 483693.0 484013.0 490450.0 517307.0 93872.0 97500.5 97702.0 99496.0 103013.0 963.5 993.2 1018.3 1023.8 1024.9 11544.5 12664.6 17787.6 18274.5 18582.7 40815.0 41807.8 42387.2 43931.3 45580.5 57081.0 57617.0 58632.7 58900.4 61030.0 13329.8 13583.6 13624.7 13709.6 13874.9 14937.0 15008.5 15169.4 15467.7 15649.5 9398.4 12022.3 12516.1 13036.7 13092.1 12883.0 12994.5 13060.3 13061.5 13625.8 12905.6 12933.2 13020.3 13084.0 13414.5 13479.9 13542.8 13587.9 13618.6 13643.4 13000.7 13175.3 13209.2 13220.9 13298.1 17:05:31 INFO - PROCESS | 20707 | 17:05:31 INFO - PROCESS | 20707 | [#3] mootools.html Cycles:180 Average:5986.61 Median:4885.34 stddev:3425.84 (70.1%) stddev-sans-first:3418.25 17:05:31 INFO - PROCESS | 20707 | Values: 1418.5 1473.7 1608.1 1802.2 1838.5 8808.2 9304.9 9317.2 9318.4 9320.3 7775.0 8018.4 8697.5 9015.3 9152.9 2460.2 2483.0 2484.8 2486.3 2487.9 2376.3 2451.7 2466.6 2476.3 2480.2 2449.8 2451.1 2466.2 2468.4 2470.1 2418.2 2419.7 2422.7 2437.3 2445.9 2382.0 2383.6 2387.2 2388.1 2396.2 8886.8 8994.8 9105.1 9203.9 9261.0 5594.4 5720.1 5723.1 5731.3 5735.5 3513.6 3653.9 3653.9 3682.2 3703.1 1205.9 1256.3 1257.3 1265.1 1292.2 12743.5 12869.7 12889.5 12892.0 12899.7 4860.9 4909.8 5018.7 5050.9 5089.8 10752.9 10755.6 10812.7 10888.2 10890.6 5585.7 5606.0 5606.2 5609.5 5612.8 4480.3 4543.9 4808.5 5008.4 5200.5 4236.1 4257.5 4306.1 4357.0 4387.9 1329.7 1343.2 1349.5 1357.6 1372.9 973.7 990.3 993.4 1010.5 1024.3 5901.4 5986.1 6098.3 6170.1 6193.1 496.8 502.8 512.2 513.7 513.9 4034.3 4034.6 4114.3 4140.4 4157.4 11046.7 11349.1 11419.0 11424.6 11435.1 11303.6 11345.0 11356.4 11361.9 11364.4 8647.5 8723.7 8726.6 8727.0 8732.3 5670.8 5782.8 5842.3 5889.5 5907.5 10418.7 10481.6 10490.6 10520.7 10539.9 10656.8 10800.1 10852.2 10887.8 10896.6 7351.7 7581.1 7763.0 8120.4 8177.6 8642.7 9069.6 9087.8 9089.5 9094.4 7533.5 7567.0 7658.1 7786.1 7826.8 7412.2 7504.8 7542.5 7546.8 7577.4 6535.4 6661.4 6666.2 6670.2 6670.9 5206.2 5208.3 5213.6 5248.2 5444.5 5339.9 5367.6 5407.4 5445.0 5576.8 17:05:31 INFO - PROCESS | 20707 | 17:05:31 INFO - PROCESS | 20707 | [#4] prototype.html Cycles:180 Average:4809.82 Median:5327.05 stddev:1416.17 (26.6%) stddev-sans-first:1380.03 17:05:31 INFO - PROCESS | 20707 | Values: 350.8 353.8 359.2 360.4 367.7 5560.0 5568.4 5587.1 5608.5 5616.5 5247.6 5296.7 5409.2 5463.0 5528.7 4378.6 4386.5 4400.9 4404.5 4430.7 4381.0 4396.8 4412.5 4414.6 4422.6 4069.6 4238.7 4305.4 4325.8 4391.0 4299.0 4302.5 4322.9 4326.4 4334.4 3833.4 3835.8 3841.3 3843.0 3844.7 5200.7 5344.8 5386.6 5409.9 5435.2 4935.3 4942.4 4948.2 4953.8 4979.3 5768.4 5771.9 5774.2 5784.5 5803.9 4130.5 4211.1 4223.6 4232.4 4237.2 5972.9 5975.5 5988.6 6011.6 6016.7 4329.6 4333.8 4335.3 4338.6 4342.1 4220.4 4301.5 4307.7 4342.2 4396.8 4889.7 4896.3 4904.9 4907.5 4908.6 5798.4 5805.7 5828.6 5839.9 5854.2 1455.9 1475.6 1476.7 1489.7 1490.5 951.8 956.5 964.3 965.3 973.7 4837.8 4905.5 4923.6 4926.9 4930.7 5165.0 5201.4 5221.8 5262.0 5266.4 4718.9 4917.8 5039.8 5049.4 5069.4 2762.5 2770.3 2771.7 2795.1 2811.2 5301.3 5356.3 5367.1 5389.6 5395.7 5290.3 5309.3 5714.9 6136.7 6154.8 6115.1 6121.7 6132.9 6135.0 6159.9 5754.6 5968.8 5979.4 6011.1 6015.5 5908.9 5912.4 5985.8 5987.6 6000.9 6137.7 6155.9 6165.8 6171.4 6189.7 4128.6 4133.3 4252.5 4264.4 4276.8 5804.7 5844.7 5984.9 5996.7 6016.9 5828.2 5843.0 5844.6 5851.0 5862.9 5745.8 5855.8 5859.9 5867.6 5867.9 5817.9 5834.4 5840.5 5860.4 5863.6 5638.9 5923.7 5925.6 5931.2 5945.0 5709.4 5800.9 5802.4 5807.0 5822.0 17:05:31 INFO - PROCESS | 20707 | 17:05:31 INFO - PROCESS | 20707 | [#5] yui.html Cycles:180 Average:21151.55 Median:4007.00 stddev:28716.62 (716.7%) stddev-sans-first:28756.57 17:05:31 INFO - PROCESS | 20707 | Values: 813.2 834.5 841.5 883.6 901.7 16447.8 17613.2 17697.6 17802.8 17814.9 14902.3 14964.9 15015.2 15949.1 16639.1 853.4 870.6 874.2 874.4 875.9 724.3 745.6 854.9 872.5 879.2 922.2 927.2 928.0 928.0 928.9 919.8 920.9 921.8 922.2 922.9 683.3 684.4 685.4 685.5 685.7 16044.0 16062.8 16101.5 16149.7 16157.4 16293.8 16305.8 16456.1 16865.8 16982.4 13330.8 13517.5 13538.6 13589.3 13725.5 3936.8 3999.3 4014.7 4053.8 4095.2 87202.6 87454.0 88588.0 89152.1 89935.6 11923.6 11934.4 11971.5 12011.8 12223.4 23750.6 23757.0 23878.9 23890.2 24022.8 9310.4 9335.6 9443.6 9534.3 9811.2 5008.3 5321.2 5346.4 5431.4 5438.1 3746.6 3876.0 3879.9 3885.6 3891.5 653.5 662.2 665.9 668.1 685.6 754.4 754.6 758.8 785.3 799.2 10257.4 10389.5 10486.8 10908.4 10915.1 679.6 691.2 755.0 777.5 797.9 5240.0 5277.3 5334.2 5352.6 5467.6 75006.0 75303.0 75982.2 125425.4 127832.0 64590.4 65902.7 79516.0 97108.5 111704.4 63065.4 73029.0 77521.6 98062.5 100696.0 10088.3 10343.6 10393.3 10431.9 10479.9 56102.4 57552.7 57827.7 58023.4 58845.4 68283.7 76032.0 84258.0 84804.0 84847.0 13392.5 13528.9 13861.5 14073.5 14697.9 16861.8 16868.8 16881.8 18242.5 18347.2 15448.3 15528.1 15711.8 16669.7 16981.8 14252.8 14469.0 15018.2 15480.3 15830.3 12508.6 13965.2 14381.7 14517.3 15747.5 12295.0 12536.7 12902.9 13121.5 13473.5 12673.8 12678.4 12691.6 12784.7 12887.9 17:05:31 INFO - PROCESS | 20707 | -------- Summary: end -------- 17:05:31 INFO - PROCESS | 20707 | 17:05:32 INFO - TEST-INFO | 20707: exit 0 17:05:32 INFO - TEST-OK | dromaeo_css | took 1113111ms 17:05:32 INFO - TEST-START | kraken 17:05:32 INFO - operating with platform_type : linux_ 17:05:32 INFO - Initialising browser for kraken test... 17:05:32 INFO - TEST-INFO | started process 21015 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBCeA2A/profile http://localhost:35097/getInfo.html) 17:05:36 INFO - PROCESS | 21015 | __metrics Screen width/height:1600/1200 17:05:36 INFO - PROCESS | 21015 | colorDepth:24 17:05:36 INFO - PROCESS | 21015 | Browser inner width/height: 1024/697 17:05:36 INFO - PROCESS | 21015 | __metrics 17:05:37 INFO - PROCESS | 21015 | 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" 17:05:37 INFO - PROCESS | 21015 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:05:37 INFO - PROCESS | 21015 | console.error: PushService: 17:05:37 INFO - PROCESS | 21015 | stateChangeProcessEnqueue: Error transitioning state 17:05:37 INFO - PROCESS | 21015 | UnknownError 17:05:38 INFO - TEST-INFO | 21015: exit 0 17:05:38 INFO - Browser initialized. 17:05:38 INFO - Running cycle 1/1 for kraken test... 17:05:38 INFO - TEST-INFO | started process 21107 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBCeA2A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:05:50 INFO - PROCESS | 21107 | RSS: Main: 143163392 17:05:50 INFO - PROCESS | 21107 | 17:05:52 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35097/tests/kraken/driver.html?testName=audio-beat-detection) 17:05:53 INFO - PROCESS | 21107 | RSS: Main: 187297792 17:05:53 INFO - PROCESS | 21107 | 17:05:55 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35097/tests/kraken/driver.html?testName=audio-dft) 17:05:56 INFO - PROCESS | 21107 | RSS: Main: 174510080 17:05:56 INFO - PROCESS | 21107 | 17:05:59 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35097/tests/kraken/driver.html?testName=audio-fft) 17:05:59 INFO - PROCESS | 21107 | RSS: Main: 265977856 17:05:59 INFO - PROCESS | 21107 | 17:06:01 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35097/tests/kraken/driver.html?testName=audio-oscillator) 17:06:02 INFO - PROCESS | 21107 | RSS: Main: 172453888 17:06:02 INFO - PROCESS | 21107 | 17:06:04 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35097/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:06:05 INFO - PROCESS | 21107 | RSS: Main: 173768704 17:06:05 INFO - PROCESS | 21107 | 17:06:08 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35097/tests/kraken/driver.html?testName=imaging-darkroom) 17:06:08 INFO - PROCESS | 21107 | RSS: Main: 210247680 17:06:08 INFO - PROCESS | 21107 | 17:06:12 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35097/tests/kraken/driver.html?testName=imaging-desaturate) 17:06:12 INFO - PROCESS | 21107 | RSS: Main: 208769024 17:06:12 INFO - PROCESS | 21107 | 17:06:15 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35097/tests/kraken/driver.html?testName=json-parse-financial) 17:06:16 INFO - PROCESS | 21107 | RSS: Main: 208662528 17:06:16 INFO - PROCESS | 21107 | 17:06:17 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35097/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:06:18 INFO - PROCESS | 21107 | RSS: Main: 170549248 17:06:18 INFO - PROCESS | 21107 | 17:06:19 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35097/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:06:20 INFO - PROCESS | 21107 | RSS: Main: 177790976 17:06:20 INFO - PROCESS | 21107 | 17:06:22 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35097/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:06:22 INFO - PROCESS | 21107 | RSS: Main: 186527744 17:06:22 INFO - PROCESS | 21107 | 17:06:25 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:06:26 INFO - PROCESS | 21107 | RSS: Main: 195203072 17:06:26 INFO - PROCESS | 21107 | 17:06:28 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:06:29 INFO - PROCESS | 21107 | RSS: Main: 192258048 17:06:29 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | Cycle 1(1): loaded http://localhost:35097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35097/tests/kraken/driver.html?testName=ai-astar) 17:06:31 INFO - PROCESS | 21107 | RSS: Main: 244162560 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | __start_tp_report 17:06:31 INFO - PROCESS | 21107 | _x_x_mozilla_page_load 17:06:31 INFO - PROCESS | 21107 | _x_x_mozilla_page_load_details 17:06:31 INFO - PROCESS | 21107 | |i|pagename|runs| 17:06:31 INFO - PROCESS | 21107 | |0;ai-astar;113;109;111;111;111;111;110;110;109;109 17:06:31 INFO - PROCESS | 21107 | |1;audio-beat-detection;144;116;118;168;120;116;117;120;148;116 17:06:31 INFO - PROCESS | 21107 | |2;audio-dft;161;160;159;159;160;161;161;161;161;160 17:06:31 INFO - PROCESS | 21107 | |3;audio-fft;76;76;75;116;75;75;76;75;107;74 17:06:31 INFO - PROCESS | 21107 | |4;audio-oscillator;81;83;118;80;81;81;105;80;80;114 17:06:31 INFO - PROCESS | 21107 | |5;imaging-gaussian-blur;117;117;117;116;116;116;116;117;117;116 17:06:31 INFO - PROCESS | 21107 | |6;imaging-darkroom;146;147;147;147;147;147;148;148;147;146 17:06:31 INFO - PROCESS | 21107 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;95 17:06:31 INFO - PROCESS | 21107 | |8;json-parse-financial;52;50;51;50;50;50;49;50;50;50 17:06:31 INFO - PROCESS | 21107 | |9;json-stringify-tinderbox;59;59;59;58;60;58;59;58;59;59 17:06:31 INFO - PROCESS | 21107 | |10;stanford-crypto-aes;76;79;73;73;73;77;73;73;72;73 17:06:31 INFO - PROCESS | 21107 | |11;stanford-crypto-ccm;121;121;125;123;123;118;125;117;166;128 17:06:31 INFO - PROCESS | 21107 | |12;stanford-crypto-pbkdf2;153;157;151;156;156;154;152;154;151;152 17:06:31 INFO - PROCESS | 21107 | |13;stanford-crypto-sha256-iterative;61;62;62;62;62;65;62;66;62;61 17:06:31 INFO - PROCESS | 21107 | __end_tp_report 17:06:31 INFO - PROCESS | 21107 | __start_cc_report 17:06:31 INFO - PROCESS | 21107 | _x_x_mozilla_cycle_collect,1616 17:06:31 INFO - PROCESS | 21107 | __end_cc_report 17:06:31 INFO - PROCESS | 21107 | __startTimestamp1483405591268__endTimestamp 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | ------- Summary: start ------- 17:06:31 INFO - PROCESS | 21107 | Number of tests: 14 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:1.26 (1.1%) stddev-sans-first:0.93 17:06:31 INFO - PROCESS | 21107 | Values: 113.0 109.0 111.0 111.0 111.0 111.0 110.0 110.0 109.0 109.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:120.00 stddev:18.37 (15.3%) stddev-sans-first:18.58 17:06:31 INFO - PROCESS | 21107 | Values: 144.0 116.0 118.0 168.0 120.0 116.0 117.0 120.0 148.0 116.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 17:06:31 INFO - PROCESS | 21107 | Values: 161.0 160.0 159.0 159.0 160.0 161.0 161.0 161.0 161.0 160.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#3] audio-fft Cycles:10 Average:82.50 Median:75.00 stddev:15.44 (20.6%) stddev-sans-first:16.20 17:06:31 INFO - PROCESS | 21107 | Values: 76.0 76.0 75.0 116.0 75.0 75.0 76.0 75.0 107.0 74.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:80.50 stddev:15.55 (19.3%) stddev-sans-first:16.12 17:06:31 INFO - PROCESS | 21107 | Values: 81.0 83.0 118.0 80.0 81.0 81.0 105.0 80.0 80.0 114.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 17:06:31 INFO - PROCESS | 21107 | Values: 117.0 117.0 117.0 116.0 116.0 116.0 116.0 117.0 117.0 116.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 17:06:31 INFO - PROCESS | 21107 | Values: 146.0 147.0 147.0 147.0 147.0 147.0 148.0 148.0 147.0 146.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 17:06:31 INFO - PROCESS | 21107 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.00 stddev:0.79 (1.6%) stddev-sans-first:0.50 17:06:31 INFO - PROCESS | 21107 | Values: 52.0 50.0 51.0 50.0 50.0 50.0 49.0 50.0 50.0 50.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 17:06:31 INFO - PROCESS | 21107 | Values: 59.0 59.0 59.0 58.0 60.0 58.0 59.0 58.0 59.0 59.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:73.00 stddev:2.30 (3.2%) stddev-sans-first:2.35 17:06:31 INFO - PROCESS | 21107 | Values: 76.0 79.0 73.0 73.0 73.0 77.0 73.0 73.0 72.0 73.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:124.00 stddev:14.20 (11.4%) stddev-sans-first:14.91 17:06:31 INFO - PROCESS | 21107 | Values: 121.0 121.0 125.0 123.0 123.0 118.0 125.0 117.0 166.0 128.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.60 Median:154.00 stddev:2.17 (1.4%) stddev-sans-first:2.29 17:06:31 INFO - PROCESS | 21107 | Values: 153.0 157.0 151.0 156.0 156.0 154.0 152.0 154.0 151.0 152.0 17:06:31 INFO - PROCESS | 21107 | 17:06:31 INFO - PROCESS | 21107 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.00 stddev:1.65 (2.7%) stddev-sans-first:1.66 17:06:31 INFO - PROCESS | 21107 | Values: 61.0 62.0 62.0 62.0 62.0 65.0 62.0 66.0 62.0 61.0 17:06:31 INFO - PROCESS | 21107 | -------- Summary: end -------- 17:06:31 INFO - PROCESS | 21107 | 17:06:32 INFO - TEST-INFO | 21107: exit 0 17:06:32 INFO - TEST-OK | kraken | took 59531ms 17:06:32 INFO - SUITE-END | took 1172s 17:06:32 INFO - Completed test suite (00:19:32) 17:06:32 INFO - javascript benchmark 17:06:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.4493713600123, 3188.8405579721016, 3190.7447404207664, 3208.663090375512, 3231.9192020199494, 5653.236813030245, 5741.856453588657, 5751.166080918264, 5790.739416726247, 5798.739056742441, 5664.773409063643, 5726.856828579288, 5731.398203188657, 5739.139129130633, 5770.4518070783315, 5164.586833053366, 5181.222816577525, 5203.739813009353, 5259.737013149327, 5267.710275934822, 5053.140966035764, 5265.0, 5275.0768615492325, 5278.0, 5280.471952804708, 5064.772085256154, 5142.254373115896, 5160.0, 5172.043172013171, 5224.895502089956, 5229.633925625208, 5242.501962313593, 5308.8141915033175, 5309.070912590281, 5338.973305133488, 5054.0, 5135.101357262464, 5224.660397074178, 5269.525742683177, 5279.577633789288, 5548.472895074961, 5595.720213989325, 5642.689652069134, 5669.603127781057, 5738.368779434259, 5979.073243647242, 6015.819525414245, 6088.695565221721, 6104.023356262976, 6109.633421994695, 5878.50032747217, 6105.633661980295, 6118.847028824271, 6128.601640893327, 6130.2030736004035, 5510.9448905510835, 5606.074997625387, 5631.4087020863035, 5641.041023026095, 5688.061469857427, 6248.625082494974, 6256.092866534327, 6256.655883926428, 6262.060690896311, 6263.404976527223, 5758.395368486333, 6023.789167379208, 6024.3072046714315, 6024.3072046714315, 6032.306284777219, 5897.085951677497, 5970.731317090741, 6027.578069535178, 6034.336223015464, 6127.4485296322655, 6023.969880150571, 6029.698515074228, 6034.034554471351, 6038.396160383926, 6048.697565121726, 5975.163477113208, 6037.335893051761, 6048.758049677964, 6067.757289708362, 6076.361981991828, 4535.138323718483, 4578.153041687298, 4627.305904114342, 4699.671023028355, 4717.033008233304, 4171.0615111600255, 4172.979135104366, 4178.832846686159, 4182.205380977604, 4196.034911970264, 5861.472467477947, 5866.970665146732, 5874.0, 5874.089516125007, 5882.205902203234, 5956.106584012433, 6020.036794112921, 6033.18551995475, 6052.636841789418, 6178.042403427476, 5806.970965145232, 5825.126231065374, 5828.912566311508, 5854.970725146347, 5863.912041319383, 5280.894382112337, 5302.469753024744, 5302.814401496006, 5303.522682958553, 5322.866928326745, 6091.573589848668, 6130.489860652645, 6170.352113028068, 6285.497160227171, 6310.05349197624, 6136.815895523141, 6167.259928808392, 6179.938200617937, 6187.071939209155, 6192.81421557354, 6348.143000694847, 6357.4596159326975, 6357.491400688026, 6358.650274234961, 6358.777442789666, 5803.071508558779, 5926.762929482772, 6079.148919151234, 6085.117657939662, 6085.4523092921845, 6121.602095863755, 6148.108524263867, 6169.56813023102, 6229.190205273286, 6244.188255526752, 6022.638427560615, 6061.848453788691, 6064.909026364697, 6087.208662873798, 6111.297200821962, 5797.304323481209, 5994.108397523893, 6048.274207095001, 6051.546134039877, 6061.484773794101, 5779.68211748341, 5780.104083867007, 5860.882782344244, 5861.208736820645, 5955.791547295824, 5967.492763115184, 5969.253843269591, 5977.701114944322, 5979.282486101695, 5982.0, 5701.828945131652, 5756.68338241384, 5815.5929084963645, 5869.647821130746, 5872.324682661549], "value": 5618.742604957301, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.7777083593652, 601.7382438639191, 635.7043974551832, 662.0169048962288, 665.2682049745277, 36521.0, 44266.778666106664, 45079.0, 45100.77449612755, 49069.50930490694, 46750.53249467504, 47008.52991470084, 47145.52854471454, 47238.52761472384, 47274.0, 8522.105178956213, 9186.954065229682, 9330.62677492903, 9337.859932101024, 9355.017723139075, 8441.831163376728, 10844.891551084507, 11146.108311335114, 11156.0, 11482.0814334853, 11294.830577541343, 11367.147463940197, 11368.772624547504, 11376.544938202462, 11376.772464550704, 11148.05241554469, 11214.943925280322, 11218.27081239725, 11221.158413119048, 11222.775544489146, 11022.614208502664, 11074.501647425885, 11077.50151243188, 11082.05802506788, 11096.389698566576, 44193.77903110464, 44427.11145777066, 44438.33342499865, 44523.55476445259, 44581.554184458066, 3779.111908701453, 3859.787711675857, 3930.960690393088, 3998.2203470323298, 4285.507166675841, 33661.15847103817, 33702.83148584241, 33729.15677108067, 33897.49153762696, 34087.659123408695, 4552.9772351138035, 4694.131585656631, 4704.129735998851, 4791.664583479158, 4881.121398148367, 337644.0, 338125.309373454, 338864.0, 339789.0, 340163.0, 61568.69215653893, 61913.9755915864, 61944.0, 62048.0, 62214.68892655508, 39112.608873911464, 40659.796701016305, 40716.38925416121, 42365.57634423678, 42393.15213695709, 19344.90327548353, 19554.804451955584, 19577.412677619694, 19584.608307833765, 19587.11857966395, 13212.207267563977, 13608.251546164858, 14284.428622855177, 14339.354729037219, 14566.781498277534, 20412.0, 20438.386848394573, 20502.897485512476, 20603.484912876997, 20614.793852061586, 7401.740939067215, 7470.70117195317, 7489.475736698379, 7558.2441755823975, 7651.579163145969, 967.3953778888194, 1016.1413605503337, 1113.2207454781685, 1137.9943100284445, 1150.246588484544, 34020.65979340224, 34062.148446289044, 34225.0, 34235.14412139716, 34882.47676284858, 1130.231442619027, 1142.714321419645, 1153.4982282707049, 1247.3326770177912, 1250.531050855929, 17836.643267134583, 17894.821051789575, 17923.28306867711, 18065.819341806677, 18109.547261318574, 535540.0, 540101.0, 540430.0, 541360.0, 544234.0, 59817.70091149603, 59819.0, 60541.394586054455, 60608.0, 60793.69603151956, 1292.9353532323346, 1375.2917247617486, 1411.611806753151, 1445.6530432696288, 1452.992735036318, 30725.692743072283, 35095.649043509235, 35613.0, 35926.461103082955, 36019.81990090033, 52271.0, 52758.472415275355, 52825.471745282055, 52870.47129528655, 52916.470835291155, 437047.0, 440069.0, 440365.0, 440843.0, 441631.0, 26590.468190636468, 27913.44173116567, 29348.55977160387, 30267.394652107276, 30634.84682576573, 49679.25481117859, 49737.502624973284, 49969.50030499649, 50332.49667503278, 51777.22334165066, 42832.57167428286, 43226.35160472533, 43504.34743478914, 43659.0, 45025.77487112675, 42542.36186457144, 42836.5092488369, 43002.569974299855, 43040.78479607582, 43576.78211608922, 42532.0, 43603.0, 44114.779426102665, 44124.77937610292, 44146.0, 35965.82017089898, 38042.0, 38674.0, 38945.80527097346, 39028.41457378085, 28517.047747644374, 28569.28576785597, 28836.134915952556, 28972.565411519266, 29085.7091429083], "value": 21326.98124465228, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.8868872371373, 405.722080374943, 413.8158519458841, 417.5824175824177, 437.30250250849883, 19120.42638720839, 20446.591068178626, 20590.279340223096, 20727.481812954684, 20892.268770593033, 19979.0, 20208.39374818754, 20500.8974955125, 20715.274965376215, 20824.68762968553, 5744.4829965303115, 6000.729967151467, 6007.339192688801, 6016.217891674098, 6026.819195424122, 5818.912716309259, 5898.174255604219, 5969.5821292509545, 5990.400959904018, 6097.359777223394, 6168.074788781674, 6194.163787888622, 6196.75212991482, 6207.503399728011, 6208.565400421972, 5941.079132734411, 6066.878662426749, 6105.145279660851, 6113.449789518964, 6146.108814221935, 5936.0, 5943.970280148572, 5949.286085669704, 5952.255968003999, 5954.4938680212235, 22599.661005084938, 23449.882750586137, 23486.0, 23639.645405318934, 23787.881060594587, 2392.342105920868, 2484.2671411933525, 2551.4769472258326, 2576.2915198320425, 2685.9597106043425, 10668.626598069108, 10725.517351719114, 10892.727681808019, 11059.447027648584, 16039.759403608956, 2072.3886453496257, 2100.789921007902, 2146.098638571804, 2171.0989939175383, 2279.332495392157, 66991.0, 74744.0, 79270.20729792686, 99613.5019324906, 104129.4793526035, 17185.828141718674, 18070.548236293936, 18132.546686332684, 19105.522361941064, 19210.711839322423, 34586.827065865014, 34858.65141348605, 34866.8619824217, 34939.82530087333, 35218.0, 11231.831522527169, 11273.774524509465, 11281.32312061279, 11323.207375483802, 11558.768824623461, 9529.332946693803, 9570.33007689455, 9608.423494590346, 9609.759756006155, 9838.16375608067, 9479.810403791886, 9744.90255097454, 9846.556904939294, 9854.260930430102, 10015.248856335804, 3599.8200089995394, 3666.7249956253386, 3672.8163591820303, 3688.7233457490797, 3803.1062700265416, 928.1971095002909, 937.1612406895927, 994.8308787506141, 1045.3153184663904, 1046.1735229169024, 19462.902685486766, 20057.0, 20306.172444480067, 20395.796042039685, 20689.68965465519, 992.6624947517879, 998.32612986234, 1014.5535964175887, 1043.4417586591137, 1048.16670746755, 10888.455577221108, 11597.18819682614, 11618.419079046183, 11626.651200463999, 11672.299662020136, 475253.0, 483693.0, 484013.0, 490450.0, 517307.0, 93872.0, 97500.51249743706, 97702.0, 99496.0, 103013.0, 963.4700914496999, 993.215359865698, 1018.3228153278135, 1023.8464230365505, 1024.8975102489692, 11544.480498377761, 12664.620061398173, 17787.644247114727, 18274.543136421165, 18582.721259180853, 40815.0, 41807.790961045, 42387.152256955305, 43931.34102988522, 45580.54419455763, 57081.0, 57617.0, 58632.70683646554, 58900.41099589121, 61030.0, 13329.800052999019, 13583.592492225249, 13624.727505450033, 13709.588712338646, 13874.861251387358, 14937.0, 15008.549743507712, 15169.393224270905, 15467.690646187239, 15649.530514084596, 9398.389104708172, 12022.27866328023, 12516.12387132892, 13036.674083147996, 13092.149010314299, 12883.0, 12994.545190918272, 13060.346982651019, 13061.477540898257, 13625.795613065613, 12905.612831615066, 12933.22400655926, 13020.28388438607, 13084.0, 13414.530491432753, 13479.865201348253, 13542.79685804694, 13587.86412135866, 13618.591442256748, 13643.386047628079, 13000.739985200433, 13175.2753598553, 13209.207447553177, 13220.933895330461, 13298.069135160446], "value": 10789.465552246389, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.524794193945, 1473.7052589482103, 1608.1396452897695, 1802.2245741558002, 1838.4576549917772, 8808.16322449367, 9304.86042709359, 9317.208037316836, 9318.394304370211, 9320.300977426694, 7775.0, 8018.358531317495, 8697.52163631, 9015.278777697786, 9152.8627070594, 2460.1758410932316, 2482.987585062072, 2484.763947424992, 2486.316263027664, 2487.9004839806385, 2376.3346263046374, 2451.705795304566, 2466.580681284177, 2476.2818782553036, 2480.193936970492, 2449.8285120041605, 2451.0930955546473, 2466.1984854922134, 2468.4075821802817, 2470.1354525915967, 2418.153646223825, 2419.685440892673, 2422.733499315074, 2437.3297343230574, 2445.9388515287083, 2381.9642705359433, 2383.6186210206283, 2387.1644924276366, 2388.080588973258, 2396.221228100857, 8886.777830554223, 8994.820103597891, 9105.089491050909, 9203.861942070875, 9261.0, 5594.440555944414, 5720.141978703186, 5723.112917497794, 5731.283589551306, 5735.4551317624755, 3513.5608048993877, 3653.872114476007, 3653.8903832885057, 3682.1899182179877, 3703.129764505366, 1205.9276443413335, 1256.2839181666454, 1257.3210466348162, 1265.1144199060695, 1292.1923797626484, 12743.490260389573, 12869.742605148032, 12889.548865789653, 12892.033097517724, 12899.67750806228, 4860.902781944341, 4909.7790599423115, 5018.673786203885, 5050.949490505048, 5089.821856235014, 10752.892471075345, 10755.623553175757, 10812.67561973142, 10888.237823352289, 10890.564377424815, 5585.748641311069, 5606.0, 5606.243157173811, 5609.523190528851, 5612.803551875746, 4480.260756975063, 4543.886402839956, 4808.519148085164, 5008.424031236382, 5200.531952124327, 4236.131593023423, 4257.531671516131, 4306.1172459645695, 4357.0, 4387.868363949086, 1329.694170340827, 1343.1940835498622, 1349.4939397725852, 1357.633438971472, 1372.8970327225497, 973.7468258252763, 990.3463713948903, 993.3791380387258, 1010.4998025977188, 1024.2676486312323, 5901.380355062656, 5986.132010858401, 6098.268207815002, 6170.136180934673, 6193.071039344044, 496.76155445386416, 502.78631581577685, 512.2444394518056, 513.7405610166917, 513.8972205558903, 4034.3141665916864, 4034.616711412411, 4114.305130139764, 4140.399642051885, 4157.355609880473, 11046.723831904266, 11349.09207263417, 11419.0, 11424.600138995096, 11435.085193184525, 11303.604373846876, 11345.0, 11356.432178391047, 11361.886381136248, 11364.431778411046, 8647.481151130953, 8723.65105395777, 8726.563671816511, 8726.956365218133, 8732.34507411934, 5670.829875103754, 5782.797602083907, 5842.269716285465, 5889.469947704727, 5907.497862681548, 10418.739531511774, 10481.633142839964, 10490.580376784843, 10520.736981575521, 10539.894601053891, 10656.840147397641, 10800.135989121009, 10852.240343175901, 10887.836682449612, 10896.56413743473, 7351.742689005859, 7581.090269167735, 7763.0, 8120.431569790294, 8177.550234736908, 8642.654293828176, 9069.637214511346, 9087.772805679911, 9089.5000774958, 9094.408863423856, 7533.54798712079, 7567.0, 7658.081030276402, 7786.065672119382, 7826.7651970440975, 7412.221716719667, 7504.774856754306, 7542.47202695806, 7546.849063018819, 7577.431692623186, 6535.379138981789, 6661.4004739573675, 6666.2333831609985, 6670.166229221348, 6670.899936500859, 5206.2450944612065, 5208.322918020633, 5213.582913366998, 5248.160294352885, 5444.537214336668, 5339.893202136013, 5367.624266301322, 5407.378151512469, 5444.972775136099, 5576.832695019156], "value": 4735.475509041263, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.7702454892047, 353.80363898036586, 359.2169071424294, 360.44491483116, 367.71502085883435, 5560.0, 5568.415316391782, 5587.106063029916, 5608.52327552158, 5616.466435688613, 5247.632665713401, 5296.655717378368, 5409.24270602116, 5462.9726851365685, 5528.668279903209, 4378.627816635574, 4386.517483076867, 4400.8679739607705, 4404.4934832494355, 4430.734155950637, 4380.956190438103, 4396.824127034914, 4412.4925633552075, 4414.580614841601, 4422.6240769534625, 4069.613386728271, 4238.7032907696475, 4305.375720520523, 4325.767156360438, 4391.0, 4298.978505107454, 4302.462192225972, 4322.891927701817, 4326.351047342908, 4334.436523251957, 3833.4058220975794, 3835.846566137351, 3841.2893614681375, 3842.9807850960847, 3844.711646626486, 5200.6619569727845, 5344.812931547377, 5386.622936394454, 5409.891802163935, 5435.239066530688, 4935.333729946447, 4942.431620363668, 4948.158813001763, 4953.752312384402, 4979.278004689336, 5768.42315768424, 5771.913421298684, 5774.16274640179, 5784.537237021028, 5803.88392232153, 4130.463039804837, 4211.094614657833, 4223.577642235782, 4232.386303985935, 4237.173751118533, 5972.940270597282, 5975.52195824333, 5988.580799344048, 6011.57918945674, 6016.729247183887, 4329.631981281626, 4333.826646934151, 4335.263005289108, 4338.58783415575, 4342.131573685276, 4220.409142720022, 4301.526832048472, 4307.655387568987, 4342.2261272415135, 4396.824127034947, 4889.655344780213, 4896.338994235733, 4904.852854414374, 4907.460179380337, 4908.607311415079, 5798.391168927202, 5805.651660900359, 5828.562857785683, 5839.9124013139835, 5854.248503203487, 1455.8835293176521, 1475.6089636246404, 1476.6677497562962, 1489.7020595880867, 1490.4559835660123, 951.7715748220515, 956.5073986896754, 964.3008818606594, 965.3339195954769, 973.6543527047937, 4837.814735389782, 4905.5094490550655, 4923.556879880756, 4926.926096108561, 4930.7288099154175, 5164.974175129176, 5201.375834899836, 5221.817236396708, 5261.973690131525, 5266.420693723688, 4718.947257942806, 4917.7786999585105, 5039.840836607601, 5049.444561098277, 5069.366329208849, 2762.5027495051086, 2770.3074231442138, 2771.6673999120235, 2795.147480018614, 2811.1707046421357, 5301.257823904656, 5356.250124982505, 5367.1483338248245, 5389.622726409114, 5395.676259424447, 5290.259363689087, 5309.283246761715, 5714.885702286014, 6136.662483563447, 6154.846128846725, 6115.052166914135, 6121.724522396503, 6132.908006379907, 6135.018397056539, 6159.907601385983, 5754.5684073695475, 5968.791092311922, 5979.402059793986, 6011.054583254261, 6015.4809436480145, 5908.881822363443, 5912.438318359749, 5985.760569577168, 5987.6108052976415, 6000.859836631026, 6137.723802428991, 6155.938440615537, 6165.815025549241, 6171.413715697002, 6189.721462534107, 4128.587141285968, 4133.318002529549, 4252.468441444819, 4264.4456220692355, 4276.786160692015, 5804.722960948585, 5844.707764611838, 5984.8803023940145, 5996.730147143477, 6016.8784382697995, 5828.154917536848, 5843.0, 5844.620099693507, 5850.970745146247, 5862.882742345215, 5745.7988970387505, 5855.824325270249, 5859.94140058594, 5867.559933005127, 5867.946218823377, 5817.883642327214, 5834.445727655866, 5840.474357307863, 5860.443257890493, 5863.589548731547, 5638.915416268677, 5923.703814809329, 5925.585209035326, 5931.169636251015, 5944.959632064285, 5709.400512946081, 5800.912986305125, 5802.390748971297, 5806.970965145148, 5822.0], "value": 4362.3030809969705, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.2274339377591, 834.5117409229423, 841.457260067257, 883.6067949762356, 901.68441045634, 16447.75328370074, 17613.207405666744, 17697.55756106098, 17802.82197178028, 17814.931104133746, 14902.329395177216, 14964.850351496481, 15015.174165420925, 15949.122798246091, 16639.084850333256, 853.3855623950745, 870.5647176411794, 874.152072489684, 874.3660845886745, 875.8817559629464, 724.3155218318691, 745.5861996591889, 854.8803167556547, 872.4634349874813, 879.2087121590588, 922.2161163011453, 927.23966347595, 927.9675211367603, 928.0, 928.9442633441981, 919.8482250428705, 920.903305152956, 921.8432866412659, 922.2437601167046, 922.916937475627, 683.2723149845449, 684.428502200663, 685.4345165238648, 685.4790359326947, 685.6674512861258, 16044.0, 16062.839371606251, 16101.516954491384, 16149.677006459806, 16157.434489792919, 16293.755593666105, 16305.755413668805, 16456.094914779682, 16865.831341686673, 16982.40561580339, 13330.800037999437, 13517.526886558922, 13538.59384218475, 13589.252591107484, 13725.45098196071, 3936.8031598419966, 3999.2601368746873, 4014.739041962263, 4053.7973101344814, 4095.24238015965, 87202.56398717966, 87454.0, 88588.0, 89152.10847891503, 89935.55032224863, 11923.642290731292, 11934.403279835973, 11971.46128424223, 12011.759764804656, 12223.449944752507, 23750.64374034391, 23757.0, 23878.88060559686, 23890.163844265368, 24022.759772402052, 9310.37068513021, 9335.626574936927, 9443.584780802417, 9534.332596718163, 9811.215102791759, 5008.29883816266, 5321.201819727072, 5346.385165705916, 5431.429699881534, 5438.102713052302, 3746.5691445484117, 3875.980620096938, 3879.922401551953, 3885.592012838668, 3891.5330160380936, 653.4837478392112, 662.1524448705665, 665.866826634675, 668.0713807807152, 685.6194811879355, 754.35125791819, 754.5563020799771, 758.768575584452, 785.2736218997519, 799.1808396393743, 10257.384556926609, 10389.480525973671, 10486.790264194673, 10908.400037997828, 10915.126789856793, 679.6465837764334, 691.2292793535172, 754.9509281896659, 777.4557809533236, 797.924197201265, 5239.963002715394, 5277.287566178594, 5334.226537152169, 5352.625316227826, 5467.644603100865, 75006.0, 75303.0, 75982.24017759752, 125425.37287313506, 127832.0, 64590.35409645843, 65902.67048664822, 79516.0, 97108.51445742867, 111704.44147779209, 63065.36934630687, 73029.0, 77521.6123919388, 98062.50968745253, 100696.0, 10088.293819432864, 10343.63797267092, 10393.272470926962, 10431.895681043092, 10479.895201047892, 56102.43897561135, 57552.71223643855, 57827.71086144543, 58023.4197658018, 58845.411545885705, 68283.65858170876, 76032.0, 84258.0, 84804.0, 84847.0, 13392.531261405804, 13528.93235533816, 13861.534019058128, 14073.507427239998, 14697.85302146965, 16861.831381686025, 16868.831311687216, 16881.831181688027, 18242.45272641823, 18347.174377152794, 15448.30482628262, 15528.14595197275, 15711.764323535384, 16669.66660666804, 16981.830181698024, 14252.786208206679, 14469.00066430679, 15018.24908754536, 15480.303386347416, 15830.28763705613, 12508.624741257778, 13965.231912244517, 14381.712365752835, 14517.346719397441, 15747.527574172793, 12295.0, 12536.74926501483, 12902.935485322514, 13121.475140994253, 13473.46106155782, 12673.8098928518, 12678.366081695694, 12691.619251422102, 12784.6803829905, 12887.871121288666], "value": 8152.502211789955, "unit": "score"}], "name": "dromaeo_css", "value": 7756.471783134667, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 111.0, 111.0, 111.0, 111.0, 110.0, 110.0, 109.0, 109.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 118.0, 168.0, 120.0, 116.0, 117.0, 120.0, 148.0, 116.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 159.0, 160.0, 161.0, 161.0, 161.0, 161.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 116.0, 75.0, 75.0, 76.0, 75.0, 107.0, 74.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 118.0, 80.0, 81.0, 81.0, 105.0, 80.0, 80.0, 114.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 148.0, 147.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 50.0, 50.0, 50.0, 49.0, 50.0, 50.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 58.0, 60.0, 58.0, 59.0, 58.0, 59.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 79.0, 73.0, 73.0, 73.0, 77.0, 73.0, 73.0, 72.0, 73.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 125.0, 123.0, 123.0, 118.0, 125.0, 117.0, 166.0, 128.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 157.0, 151.0, 156.0, 156.0, 154.0, 152.0, 154.0, 151.0, 152.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 62.0, 65.0, 62.0, 66.0, 62.0, 61.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1456.2, "alertThreshold": 2.0}]} 17:06:32 INFO - Return code: 0 17:06:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:06:32 INFO - # TBPL SUCCESS # 17:06:32 INFO - Running post-action listener: _resource_record_post_action 17:06:32 INFO - [mozharness: 2017-01-03 01:06:32.577424Z] Finished run-tests step (success) 17:06:32 INFO - Running post-run listener: _resource_record_post_run 17:06:32 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 331493376; Read time: 52; Write time: 193600 17:06:32 INFO - TinderboxPrint: CPU usage
12.9% 17:06:32 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 52 17:06:32 INFO - TinderboxPrint: I/O write bytes / time
331,493,376 / 193,600 17:06:32 INFO - TinderboxPrint: CPU idle
8,249.5 (87.0%) 17:06:32 INFO - TinderboxPrint: CPU user
1,183.3 (12.5%) 17:06:32 INFO - TinderboxPrint: Swap in / out
0 / 0 17:06:32 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 112189440; Read time: 0; Write time: 63664 17:06:32 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 219303936; Read time: 12; Write time: 129936 17:06:33 INFO - Running post-run listener: _upload_blobber_files 17:06:33 INFO - Blob upload gear active. 17:06:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:06:33 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 17:06:33 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'] 17:06:33 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 17:06:33 INFO - (blobuploader) - INFO - Open directory for files ... 17:06:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:06:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:06:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:06:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:06:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:06:37 INFO - (blobuploader) - INFO - Done attempting. 17:06:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:06:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:06:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:06:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:06:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:06:38 INFO - (blobuploader) - INFO - Done attempting. 17:06:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:06:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:06:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:06:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:06:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:06:39 INFO - (blobuploader) - INFO - Done attempting. 17:06:39 INFO - (blobuploader) - INFO - Iteration through files over. 17:06:39 INFO - Return code: 0 17:06:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:06:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:06:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d56647b258d22c8ec3ad5674d858178ab6627af6e40253beb7dee7604c99e9f8bfbe38faf2e842a8806ca21b121dcd16907e2a65b002bb6bf80696ebac2a73dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3e6c92d5ca8ce7eb90032d36377f37686f927a738360119c8329723efe878891628b6b723ad32b8167d684f1660b570c2e684829d0d310635477cf57600aa2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62cee8940ee9e2cce131a895b0d8539d2411f05c14828823d1ebd587206f7a02d454a57c49cd1f55ed8a13826feb8cc556c40eee5b7481f1e890c8ddaf686edc"} 17:06:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:06:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:06:39 INFO - Contents: 17:06:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d56647b258d22c8ec3ad5674d858178ab6627af6e40253beb7dee7604c99e9f8bfbe38faf2e842a8806ca21b121dcd16907e2a65b002bb6bf80696ebac2a73dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3e6c92d5ca8ce7eb90032d36377f37686f927a738360119c8329723efe878891628b6b723ad32b8167d684f1660b570c2e684829d0d310635477cf57600aa2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62cee8940ee9e2cce131a895b0d8539d2411f05c14828823d1ebd587206f7a02d454a57c49cd1f55ed8a13826feb8cc556c40eee5b7481f1e890c8ddaf686edc"} 17:06:39 INFO - Running post-run listener: copy_logs_to_upload_dir 17:06:39 INFO - Copying logs to upload dir... 17:06:39 INFO - mkdir: /builds/slave/test/build/upload/logs 17:06:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.090073 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2017-01-02 17:06:38.450368) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 17:06:38.453610) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416 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/d56647b258d22c8ec3ad5674d858178ab6627af6e40253beb7dee7604c99e9f8bfbe38faf2e842a8806ca21b121dcd16907e2a65b002bb6bf80696ebac2a73dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3e6c92d5ca8ce7eb90032d36377f37686f927a738360119c8329723efe878891628b6b723ad32b8167d684f1660b570c2e684829d0d310635477cf57600aa2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62cee8940ee9e2cce131a895b0d8539d2411f05c14828823d1ebd587206f7a02d454a57c49cd1f55ed8a13826feb8cc556c40eee5b7481f1e890c8ddaf686edc"} build_url:https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/Cg9QUf1URwisSlXcnjlJOg/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/d56647b258d22c8ec3ad5674d858178ab6627af6e40253beb7dee7604c99e9f8bfbe38faf2e842a8806ca21b121dcd16907e2a65b002bb6bf80696ebac2a73dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3e6c92d5ca8ce7eb90032d36377f37686f927a738360119c8329723efe878891628b6b723ad32b8167d684f1660b570c2e684829d0d310635477cf57600aa2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62cee8940ee9e2cce131a895b0d8539d2411f05c14828823d1ebd587206f7a02d454a57c49cd1f55ed8a13826feb8cc556c40eee5b7481f1e890c8ddaf686edc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 17:06:38.477924) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 17:06:38.478204) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1483387019.257125-1926979416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004649 ========= master_lag: 0.19 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 17:06:38.669118) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 17:06:38.669493) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 17:06:38.669861) ========= ========= Total master_lag: 0.29 =========