builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1479151547.27 results: success (0) buildid: 20161114081643 builduid: da4be81bc0254b29b4f65eae2dfacea3 revision: 4ed88686e49e3b941dd9b01f2f5c038f53187a05 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:47.275262) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:47.275726) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:47.276011) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:47.415015) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:47.415686) ========= 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=c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-14 11:25:48-- 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% 46.3M=0s 2016-11-14 11:25:48 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125313 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:47.556173) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:47.556459) ========= 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=c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019777 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:47.606668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:47.607027) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4ed88686e49e3b941dd9b01f2f5c038f53187a05 --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 4ed88686e49e3b941dd9b01f2f5c038f53187a05 --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=c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-14 11:25:48,282 truncating revision to first 12 chars 2016-11-14 11:25:48,282 Setting DEBUG logging. 2016-11-14 11:25:48,282 attempt 1/10 2016-11-14 11:25:48,282 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4ed88686e49e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 11:25:48,575 unpacking tar archive at: mozilla-beta-4ed88686e49e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.625191 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:48.246924) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:48.247338) ========= script_repo_revision: 4ed88686e49e3b941dd9b01f2f5c038f53187a05 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:48.247881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:48.248250) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 11:25:48.259780) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-11-14 11:25:48.260300) ========= /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=c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:25:49 INFO - MultiFileLogger online at 20161114 11:25:49 in /builds/slave/test 11:25:49 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 11:25:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:25:49 INFO - {'append_to_log': False, 11:25:49 INFO - 'base_work_dir': '/builds/slave/test', 11:25:49 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:25:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:25:49 INFO - 'branch': 'Mozilla-Beta', 11:25:49 INFO - 'buildbot_json_path': 'buildprops.json', 11:25:49 INFO - 'config_files': ('talos/linux_config.py',), 11:25:49 INFO - 'default_actions': ('clobber', 11:25:49 INFO - 'read-buildbot-config', 11:25:49 INFO - 'download-and-extract', 11:25:49 INFO - 'populate-webroot', 11:25:49 INFO - 'create-virtualenv', 11:25:49 INFO - 'install', 11:25:49 INFO - 'run-tests'), 11:25:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:25:49 INFO - 'download_minidump_stackwalk': True, 11:25:49 INFO - 'download_symbols': 'ondemand', 11:25:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:25:49 INFO - 'tooltool.py': '/tools/tooltool.py', 11:25:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:25:49 INFO - '/tools/misc-python/virtualenv.py')}, 11:25:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:25:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:25:49 INFO - 'installer_path': 'installer.exe', 11:25:49 INFO - 'log_level': 'info', 11:25:49 INFO - 'log_name': 'talos', 11:25:49 INFO - 'log_to_console': True, 11:25:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:25:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:25:49 INFO - 'opt_config_files': (), 11:25:49 INFO - 'pip_index': False, 11:25:49 INFO - 'sps_profile': False, 11:25:49 INFO - 'sps_profile_interval': 0, 11:25:49 INFO - 'suite': 'dromaeojs', 11:25:49 INFO - 'system_bits': '32', 11:25:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:25:49 INFO - 'title': 'talos-linux64-ix-029', 11:25:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:25:49 INFO - 'use_talos_json': True, 11:25:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:25:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:25:49 INFO - 'work_dir': 'build'} 11:25:49 INFO - [mozharness: 2016-11-14 19:25:49.022450Z] Running clobber step. 11:25:49 INFO - Running pre-action listener: _resource_record_pre_action 11:25:49 INFO - Running main action method: clobber 11:25:49 INFO - rmtree: /builds/slave/test/build 11:25:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:25:49 INFO - Running post-action listener: _resource_record_post_action 11:25:49 INFO - [mozharness: 2016-11-14 19:25:49.484126Z] Finished clobber step (success) 11:25:49 INFO - [mozharness: 2016-11-14 19:25:49.484235Z] Running read-buildbot-config step. 11:25:49 INFO - Running pre-action listener: _resource_record_pre_action 11:25:49 INFO - Running main action method: read_buildbot_config 11:25:49 INFO - Using buildbot properties: 11:25:49 INFO - { 11:25:49 INFO - "project": "", 11:25:49 INFO - "product": "firefox", 11:25:49 INFO - "script_repo_revision": "production", 11:25:49 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 11:25:49 INFO - "repository": "", 11:25:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 11:25:49 INFO - "buildid": "20161114081643", 11:25:49 INFO - "pgo_build": "True", 11:25:49 INFO - "basedir": "/builds/slave/test", 11:25:49 INFO - "buildnumber": 5, 11:25:49 INFO - "slavename": "talos-linux64-ix-029", 11:25:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:25:49 INFO - "platform": "ubuntu64_hw", 11:25:49 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 11:25:49 INFO - "branch": "mozilla-beta", 11:25:49 INFO - "repo_path": "releases/mozilla-beta", 11:25:49 INFO - "revision": "4ed88686e49e3b941dd9b01f2f5c038f53187a05", 11:25:49 INFO - "stage_platform": "linux64-pgo", 11:25:49 INFO - "builduid": "da4be81bc0254b29b4f65eae2dfacea3", 11:25:49 INFO - "slavebuilddir": "test" 11:25:49 INFO - } 11:25:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 11:25:49 INFO - Running post-action listener: _resource_record_post_action 11:25:49 INFO - [mozharness: 2016-11-14 19:25:49.486688Z] Finished read-buildbot-config step (success) 11:25:49 INFO - [mozharness: 2016-11-14 19:25:49.486778Z] Running download-and-extract step. 11:25:49 INFO - Running pre-action listener: _resource_record_pre_action 11:25:49 INFO - Running main action method: download_and_extract 11:25:49 INFO - mkdir: /builds/slave/test/build/tests 11:25:49 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')]} 11:25:49 INFO - trying https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 11:25:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/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 11:25:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/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 11:25:50 INFO - Downloaded 1327 bytes. 11:25:50 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 11:25:50 INFO - Using the following test package requirements: 11:25:50 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 11:25:50 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 11:25:50 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 11:25:50 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 11:25:50 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 11:25:50 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 11:25:50 INFO - u'jsshell-linux-x86_64.zip'], 11:25:50 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 11:25:50 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 11:25:50 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 11:25:50 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 11:25:50 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 11:25:50 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 11:25:50 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 11:25:50 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 11:25:50 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 11:25:50 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 11:25:50 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 11:25:50 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:25:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 11:25:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:25:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 11:25:50 INFO - Expected file size: 21962124 11:25:54 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 11:25:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 11:25:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:25:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 11:25:55 INFO - Expected file size: 21962124 11:26:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 11:26:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:26:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 11:26:00 INFO - Expected file size: 14414089 11:26:01 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')]} 11:26:01 INFO - trying https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 11:26:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/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 11:26:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/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 11:26:02 INFO - Downloaded 56967901 bytes. 11:26:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 11:26:02 INFO - mkdir: /builds/slave/test/properties 11:26:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:26:02 INFO - Writing to file /builds/slave/test/properties/build_url 11:26:02 INFO - Contents: 11:26:02 INFO - build_url:https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 11:26:02 INFO - Running post-action listener: _resource_record_post_action 11:26:02 INFO - Running post-action listener: set_extra_try_arguments 11:26:02 INFO - [mozharness: 2016-11-14 19:26:02.964782Z] Finished download-and-extract step (success) 11:26:02 INFO - [mozharness: 2016-11-14 19:26:02.965011Z] Running populate-webroot step. 11:26:02 INFO - Running pre-action listener: _resource_record_pre_action 11:26:02 INFO - Running main action method: populate_webroot 11:26:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:26:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:26:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:26:02 INFO - '--tppagecycles', 11:26:02 INFO - '7'], 11:26:02 INFO - 'tests': ['tsvgm']}}, 11:26:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:26:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:26:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:26:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:26:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:02 INFO - 'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:02 INFO - 'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['damp', 'tps']}, 11:26:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:02 INFO - 'tests': ['damp', 'tps']}, 11:26:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['dromaeo_dom']}, 11:26:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:26:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['basic_compositor_video']}, 11:26:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:26:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['a11yr', 11:26:02 INFO - 'ts_paint', 11:26:02 INFO - 'tpaint', 11:26:02 INFO - 'sessionrestore', 11:26:02 INFO - 'sessionrestore_no_auto_restore', 11:26:02 INFO - 'tabpaint']}, 11:26:02 INFO - 'other-e10s': {'tests': ['a11yr', 11:26:02 INFO - 'ts_paint', 11:26:02 INFO - 'tpaint', 11:26:02 INFO - 'sessionrestore', 11:26:02 INFO - 'sessionrestore_no_auto_restore', 11:26:02 INFO - 'tabpaint']}, 11:26:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:26:02 INFO - 'ts_paint', 11:26:02 INFO - 'tpaint', 11:26:02 INFO - 'sessionrestore', 11:26:02 INFO - 'sessionrestore_no_auto_restore', 11:26:02 INFO - 'tabpaint']}, 11:26:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:26:02 INFO - 'ts_paint', 11:26:02 INFO - 'tpaint', 11:26:02 INFO - 'sessionrestore', 11:26:02 INFO - 'sessionrestore_no_auto_restore', 11:26:02 INFO - 'tabpaint']}, 11:26:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['a11yr', 11:26:02 INFO - 'ts_paint', 11:26:02 INFO - 'tpaint', 11:26:02 INFO - 'sessionrestore', 11:26:02 INFO - 'sessionrestore_no_auto_restore', 11:26:02 INFO - 'tabpaint']}, 11:26:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['a11yr', 11:26:02 INFO - 'ts_paint', 11:26:02 INFO - 'tpaint', 11:26:02 INFO - 'sessionrestore', 11:26:02 INFO - 'sessionrestore_no_auto_restore', 11:26:02 INFO - 'tabpaint']}, 11:26:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['tsvgx', 11:26:02 INFO - 'tsvgr_opacity', 11:26:02 INFO - 'tart', 11:26:02 INFO - 'tscrollx', 11:26:02 INFO - 'cart']}, 11:26:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:26:02 INFO - 'tsvgr_opacity', 11:26:02 INFO - 'tart', 11:26:02 INFO - 'tscrollx', 11:26:02 INFO - 'cart']}, 11:26:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:02 INFO - 'talos_options': ['--disable-e10s'], 11:26:02 INFO - 'tests': ['tp5o']}, 11:26:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:02 INFO - 'tests': ['tp5o']}, 11:26:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:02 INFO - 'talos_options': ['--disable-e10s', 11:26:02 INFO - '--xperf_path', 11:26:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:02 INFO - 'tests': ['tp5n']}, 11:26:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:26:02 INFO - 'talos_options': ['--xperf_path', 11:26:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:02 INFO - 'tests': ['tp5n']}}, 11:26:02 INFO - 'talos.zip': {'path': '', 11:26:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:26:02 INFO - Running post-action listener: _resource_record_post_action 11:26:02 INFO - [mozharness: 2016-11-14 19:26:02.985157Z] Finished populate-webroot step (success) 11:26:02 INFO - [mozharness: 2016-11-14 19:26:02.985252Z] Running create-virtualenv step. 11:26:02 INFO - Running pre-action listener: _resource_record_pre_action 11:26:02 INFO - Running main action method: create_virtualenv 11:26:02 INFO - Creating virtualenv /builds/slave/test/build/venv 11:26:02 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 11:26:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:26:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:26:03 INFO - Using real prefix '/usr' 11:26:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:26:03 INFO - Installing distribute.............................................................................................................................................................................................done. 11:26:05 INFO - Installing pip.................done. 11:26:05 INFO - Return code: 0 11:26:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:26:05 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')]} 11:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:05 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')]} 11:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:05 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:05 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 11:26:05 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 11:26:05 INFO - Using env: {'DISPLAY': ':0', 11:26:05 INFO - 'HOME': '/home/cltbld', 11:26:05 INFO - 'LANG': 'en_US.UTF-8', 11:26:05 INFO - 'LANGUAGE': 'en_US:en', 11:26:05 INFO - 'LOGNAME': 'cltbld', 11:26:05 INFO - 'MAIL': '/var/mail/cltbld', 11:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:05 INFO - 'MOZ_NO_REMOTE': '1', 11:26:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:05 INFO - 'NO_EM_RESTART': '1', 11:26:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:05 INFO - 'PWD': '/builds/slave/test', 11:26:05 INFO - 'SHELL': '/bin/bash', 11:26:05 INFO - 'SHLVL': '1', 11:26:05 INFO - 'TERM': 'linux', 11:26:05 INFO - 'TMOUT': '86400', 11:26:05 INFO - 'USER': 'cltbld', 11:26:05 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:05 INFO - '_': '/tools/buildbot/bin/python'} 11:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:05 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 11:26:05 INFO - Cleaning up... 11:26:05 INFO - Return code: 0 11:26:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:26:05 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')]} 11:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:05 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')]} 11:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:05 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:05 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 11:26:05 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 11:26:05 INFO - Using env: {'DISPLAY': ':0', 11:26:05 INFO - 'HOME': '/home/cltbld', 11:26:05 INFO - 'LANG': 'en_US.UTF-8', 11:26:05 INFO - 'LANGUAGE': 'en_US:en', 11:26:05 INFO - 'LOGNAME': 'cltbld', 11:26:05 INFO - 'MAIL': '/var/mail/cltbld', 11:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:05 INFO - 'MOZ_NO_REMOTE': '1', 11:26:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:05 INFO - 'NO_EM_RESTART': '1', 11:26:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:05 INFO - 'PWD': '/builds/slave/test', 11:26:05 INFO - 'SHELL': '/bin/bash', 11:26:05 INFO - 'SHLVL': '1', 11:26:05 INFO - 'TERM': 'linux', 11:26:05 INFO - 'TMOUT': '86400', 11:26:05 INFO - 'USER': 'cltbld', 11:26:05 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:05 INFO - '_': '/tools/buildbot/bin/python'} 11:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:06 INFO - Downloading/unpacking psutil>=3.1.1 11:26:06 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 11:26:06 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 11:26:06 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 11:26:06 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 11:26:06 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 11:26:06 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 11:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:26:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:08 INFO - Installing collected packages: psutil 11:26:08 INFO - Running setup.py install for psutil 11:26:08 INFO - building 'psutil._psutil_linux' extension 11:26:08 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 11:26:08 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 11:26:08 INFO - building 'psutil._psutil_posix' extension 11:26:08 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 11:26:08 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 11:26:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:08 INFO - Successfully installed psutil 11:26:08 INFO - Cleaning up... 11:26:08 INFO - Return code: 0 11:26:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:26:08 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')]} 11:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:08 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')]} 11:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:08 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:08 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 11:26:08 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 11:26:08 INFO - Using env: {'DISPLAY': ':0', 11:26:08 INFO - 'HOME': '/home/cltbld', 11:26:08 INFO - 'LANG': 'en_US.UTF-8', 11:26:08 INFO - 'LANGUAGE': 'en_US:en', 11:26:08 INFO - 'LOGNAME': 'cltbld', 11:26:08 INFO - 'MAIL': '/var/mail/cltbld', 11:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:08 INFO - 'MOZ_NO_REMOTE': '1', 11:26:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:08 INFO - 'NO_EM_RESTART': '1', 11:26:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:08 INFO - 'PWD': '/builds/slave/test', 11:26:08 INFO - 'SHELL': '/bin/bash', 11:26:08 INFO - 'SHLVL': '1', 11:26:08 INFO - 'TERM': 'linux', 11:26:08 INFO - 'TMOUT': '86400', 11:26:08 INFO - 'USER': 'cltbld', 11:26:08 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:08 INFO - '_': '/tools/buildbot/bin/python'} 11:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:26:08 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 11:26:08 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 11:26:08 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 11:26:08 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 11:26:08 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 11:26:08 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 11:26:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:26:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:26:10 INFO - Installing collected packages: mozsystemmonitor 11:26:10 INFO - Running setup.py install for mozsystemmonitor 11:26:10 INFO - Successfully installed mozsystemmonitor 11:26:10 INFO - Cleaning up... 11:26:10 INFO - Return code: 0 11:26:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:26:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:10 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:10 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 11:26:10 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 11:26:10 INFO - Using env: {'DISPLAY': ':0', 11:26:10 INFO - 'HOME': '/home/cltbld', 11:26:10 INFO - 'LANG': 'en_US.UTF-8', 11:26:10 INFO - 'LANGUAGE': 'en_US:en', 11:26:10 INFO - 'LOGNAME': 'cltbld', 11:26:10 INFO - 'MAIL': '/var/mail/cltbld', 11:26:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:10 INFO - 'MOZ_NO_REMOTE': '1', 11:26:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:10 INFO - 'NO_EM_RESTART': '1', 11:26:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:10 INFO - 'PWD': '/builds/slave/test', 11:26:10 INFO - 'SHELL': '/bin/bash', 11:26:10 INFO - 'SHLVL': '1', 11:26:10 INFO - 'TERM': 'linux', 11:26:10 INFO - 'TMOUT': '86400', 11:26:10 INFO - 'USER': 'cltbld', 11:26:10 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:10 INFO - '_': '/tools/buildbot/bin/python'} 11:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:11 INFO - Downloading/unpacking jsonschema==2.5.1 11:26:11 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 11:26:11 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 11:26:11 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 11:26:11 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 11:26:11 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 11:26:11 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 11:26:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:26:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:26:13 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 11:26:13 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 11:26:13 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 11:26:13 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 11:26:13 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 11:26:13 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 11:26:13 INFO - Downloading functools32-3.2.3-2.tar.gz 11:26:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:26:13 INFO - warning: no files found matching '*.txt' 11:26:13 INFO - no previously-included directories found matching 'build' 11:26:13 INFO - no previously-included directories found matching 'dist' 11:26:13 INFO - no previously-included directories found matching '.git*' 11:26:13 INFO - Installing collected packages: jsonschema, functools32 11:26:13 INFO - Running setup.py install for functools32 11:26:13 INFO - warning: no files found matching '*.txt' 11:26:13 INFO - no previously-included directories found matching 'build' 11:26:13 INFO - no previously-included directories found matching 'dist' 11:26:13 INFO - no previously-included directories found matching '.git*' 11:26:13 INFO - Successfully installed jsonschema functools32 11:26:13 INFO - Cleaning up... 11:26:13 INFO - Return code: 0 11:26:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:26:13 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')]} 11:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:13 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')]} 11:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:13 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:13 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 11:26:13 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 11:26:13 INFO - Using env: {'DISPLAY': ':0', 11:26:13 INFO - 'HOME': '/home/cltbld', 11:26:13 INFO - 'LANG': 'en_US.UTF-8', 11:26:13 INFO - 'LANGUAGE': 'en_US:en', 11:26:13 INFO - 'LOGNAME': 'cltbld', 11:26:13 INFO - 'MAIL': '/var/mail/cltbld', 11:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:13 INFO - 'MOZ_NO_REMOTE': '1', 11:26:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:13 INFO - 'NO_EM_RESTART': '1', 11:26:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:13 INFO - 'PWD': '/builds/slave/test', 11:26:13 INFO - 'SHELL': '/bin/bash', 11:26:13 INFO - 'SHLVL': '1', 11:26:13 INFO - 'TERM': 'linux', 11:26:13 INFO - 'TMOUT': '86400', 11:26:13 INFO - 'USER': 'cltbld', 11:26:13 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:13 INFO - '_': '/tools/buildbot/bin/python'} 11:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:26:13 INFO - Cleaning up... 11:26:13 INFO - Return code: 0 11:26:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:26:13 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')]} 11:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:13 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')]} 11:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:13 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:13 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 11:26:13 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 11:26:13 INFO - Using env: {'DISPLAY': ':0', 11:26:13 INFO - 'HOME': '/home/cltbld', 11:26:13 INFO - 'LANG': 'en_US.UTF-8', 11:26:13 INFO - 'LANGUAGE': 'en_US:en', 11:26:13 INFO - 'LOGNAME': 'cltbld', 11:26:13 INFO - 'MAIL': '/var/mail/cltbld', 11:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:13 INFO - 'MOZ_NO_REMOTE': '1', 11:26:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:13 INFO - 'NO_EM_RESTART': '1', 11:26:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:13 INFO - 'PWD': '/builds/slave/test', 11:26:13 INFO - 'SHELL': '/bin/bash', 11:26:13 INFO - 'SHLVL': '1', 11:26:13 INFO - 'TERM': 'linux', 11:26:13 INFO - 'TMOUT': '86400', 11:26:13 INFO - 'USER': 'cltbld', 11:26:13 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:13 INFO - '_': '/tools/buildbot/bin/python'} 11:26:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:14 INFO - Downloading/unpacking blobuploader==1.2.4 11:26:14 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 11:26:14 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 11:26:14 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 11:26:14 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 11:26:14 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 11:26:14 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 11:26:15 INFO - Downloading blobuploader-1.2.4.tar.gz 11:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:26:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:26:16 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 11:26:16 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 11:26:16 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 11:26:16 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 11:26:16 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 11:26:16 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 11:26:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:26:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:26:16 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 11:26:16 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 11:26:16 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 11:26:16 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 11:26:16 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 11:26:16 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 11:26:16 INFO - Downloading docopt-0.6.1.tar.gz 11:26:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:26:16 INFO - Installing collected packages: blobuploader, requests, docopt 11:26:16 INFO - Running setup.py install for blobuploader 11:26:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:26:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:26:16 INFO - Running setup.py install for requests 11:26:17 INFO - Running setup.py install for docopt 11:26:17 INFO - Successfully installed blobuploader requests docopt 11:26:17 INFO - Cleaning up... 11:26:17 INFO - Return code: 0 11:26:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:17 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')]} 11:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:17 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')]} 11:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:17 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:17 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 11:26:17 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 11:26:17 INFO - Using env: {'DISPLAY': ':0', 11:26:17 INFO - 'HOME': '/home/cltbld', 11:26:17 INFO - 'LANG': 'en_US.UTF-8', 11:26:17 INFO - 'LANGUAGE': 'en_US:en', 11:26:17 INFO - 'LOGNAME': 'cltbld', 11:26:17 INFO - 'MAIL': '/var/mail/cltbld', 11:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:17 INFO - 'MOZ_NO_REMOTE': '1', 11:26:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:17 INFO - 'NO_EM_RESTART': '1', 11:26:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:17 INFO - 'PWD': '/builds/slave/test', 11:26:17 INFO - 'SHELL': '/bin/bash', 11:26:17 INFO - 'SHLVL': '1', 11:26:17 INFO - 'TERM': 'linux', 11:26:17 INFO - 'TMOUT': '86400', 11:26:17 INFO - 'USER': 'cltbld', 11:26:17 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:17 INFO - '_': '/tools/buildbot/bin/python'} 11:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:17 INFO - Running setup.py (path:/tmp/pip-RoVKKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:17 INFO - Running setup.py (path:/tmp/pip-OjBQtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:17 INFO - Running setup.py (path:/tmp/pip-RMIhkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:17 INFO - Running setup.py (path:/tmp/pip-0wLk_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:17 INFO - Running setup.py (path:/tmp/pip-I2oUut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:17 INFO - Running setup.py (path:/tmp/pip-EEnO1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:18 INFO - Running setup.py (path:/tmp/pip-gpbaQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:18 INFO - Running setup.py (path:/tmp/pip-YfTFHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:18 INFO - Running setup.py (path:/tmp/pip-aXf_eG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:18 INFO - Running setup.py (path:/tmp/pip-u49Mws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:18 INFO - Running setup.py (path:/tmp/pip-iLw5Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:18 INFO - Running setup.py (path:/tmp/pip-06NUB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:18 INFO - Running setup.py (path:/tmp/pip-kojVts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:18 INFO - Running setup.py (path:/tmp/pip-R4v1gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:18 INFO - Running setup.py (path:/tmp/pip-k19Y6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:18 INFO - Running setup.py (path:/tmp/pip-RmdWG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:18 INFO - Running setup.py (path:/tmp/pip-yixjdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:26:18 INFO - Running setup.py install for manifestparser 11:26:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:26:18 INFO - Running setup.py install for mozcrash 11:26:19 INFO - Running setup.py install for mozdebug 11:26:19 INFO - Running setup.py install for mozdevice 11:26:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Running setup.py install for mozfile 11:26:19 INFO - Running setup.py install for mozhttpd 11:26:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Running setup.py install for mozinfo 11:26:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Running setup.py install for mozInstall 11:26:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Running setup.py install for mozleak 11:26:19 INFO - Running setup.py install for mozlog 11:26:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Running setup.py install for moznetwork 11:26:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:26:19 INFO - Running setup.py install for mozprocess 11:26:20 INFO - Running setup.py install for mozprofile 11:26:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:26:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:26:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:26:20 INFO - Running setup.py install for mozrunner 11:26:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:26:20 INFO - Running setup.py install for mozscreenshot 11:26:20 INFO - Running setup.py install for moztest 11:26:20 INFO - Running setup.py install for mozversion 11:26:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:26:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:26:20 INFO - Cleaning up... 11:26:20 INFO - Return code: 0 11:26:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:20 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')]} 11:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:20 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')]} 11:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:20 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:20 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 11:26:20 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 11:26:20 INFO - Using env: {'DISPLAY': ':0', 11:26:20 INFO - 'HOME': '/home/cltbld', 11:26:20 INFO - 'LANG': 'en_US.UTF-8', 11:26:20 INFO - 'LANGUAGE': 'en_US:en', 11:26:20 INFO - 'LOGNAME': 'cltbld', 11:26:20 INFO - 'MAIL': '/var/mail/cltbld', 11:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:20 INFO - 'MOZ_NO_REMOTE': '1', 11:26:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:20 INFO - 'NO_EM_RESTART': '1', 11:26:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:20 INFO - 'PWD': '/builds/slave/test', 11:26:20 INFO - 'SHELL': '/bin/bash', 11:26:20 INFO - 'SHLVL': '1', 11:26:20 INFO - 'TERM': 'linux', 11:26:20 INFO - 'TMOUT': '86400', 11:26:20 INFO - 'USER': 'cltbld', 11:26:20 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:20 INFO - '_': '/tools/buildbot/bin/python'} 11:26:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:20 INFO - Running setup.py (path:/tmp/pip-2inctV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:20 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)) 11:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:20 INFO - Running setup.py (path:/tmp/pip-sQkbZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:21 INFO - Running setup.py (path:/tmp/pip-fk7VJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:21 INFO - Running setup.py (path:/tmp/pip-Ns1Vlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:21 INFO - Running setup.py (path:/tmp/pip-gd8_81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:21 INFO - Running setup.py (path:/tmp/pip-r_RxAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:21 INFO - Running setup.py (path:/tmp/pip-gioPEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:21 INFO - Running setup.py (path:/tmp/pip-kYNl1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:21 INFO - Running setup.py (path:/tmp/pip-wjyJDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:21 INFO - Running setup.py (path:/tmp/pip-EtrZou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:21 INFO - Running setup.py (path:/tmp/pip-WeXqrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:21 INFO - Running setup.py (path:/tmp/pip-5qSl2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:21 INFO - Running setup.py (path:/tmp/pip-0aDC7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:21 INFO - Running setup.py (path:/tmp/pip-NQEUjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:21 INFO - Running setup.py (path:/tmp/pip-1KQYiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:21 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)) 11:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:21 INFO - Running setup.py (path:/tmp/pip-yCcbQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:22 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)) 11:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:22 INFO - Running setup.py (path:/tmp/pip-pw0RcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:22 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)) 11:26:22 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)) 11:26:22 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)) 11:26:22 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)) 11:26:22 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)) 11:26:22 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)) 11:26:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:22 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 11:26:22 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 11:26:22 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 11:26:22 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 11:26:22 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 11:26:22 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 11:26:24 INFO - Downloading blessings-1.6.tar.gz 11:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:26:24 INFO - Installing collected packages: blessings 11:26:24 INFO - Running setup.py install for blessings 11:26:24 INFO - Successfully installed blessings 11:26:24 INFO - Cleaning up... 11:26:24 INFO - Return code: 0 11:26:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:26:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:24 INFO - Reading from file tmpfile_stdout 11:26:24 INFO - Current package versions: 11:26:24 INFO - argparse == 1.2.1 11:26:24 INFO - blessings == 1.6 11:26:24 INFO - blobuploader == 1.2.4 11:26:24 INFO - docopt == 0.6.1 11:26:24 INFO - functools32 == 3.2.3-2 11:26:24 INFO - jsonschema == 2.5.1 11:26:24 INFO - manifestparser == 1.1 11:26:24 INFO - mozInstall == 1.12 11:26:24 INFO - mozcrash == 0.17 11:26:24 INFO - mozdebug == 0.1 11:26:24 INFO - mozdevice == 0.48 11:26:24 INFO - mozfile == 1.2 11:26:24 INFO - mozhttpd == 0.7 11:26:24 INFO - mozinfo == 0.9 11:26:24 INFO - mozleak == 0.1 11:26:24 INFO - mozlog == 3.4 11:26:24 INFO - moznetwork == 0.27 11:26:24 INFO - mozprocess == 0.23 11:26:24 INFO - mozprofile == 0.28 11:26:24 INFO - mozrunner == 6.12 11:26:24 INFO - mozscreenshot == 0.1 11:26:24 INFO - mozsystemmonitor == 0.3 11:26:24 INFO - moztest == 0.7 11:26:24 INFO - mozversion == 1.4 11:26:24 INFO - psutil == 3.1.1 11:26:24 INFO - requests == 1.2.3 11:26:24 INFO - wsgiref == 0.1.2 11:26:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:24 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')]} 11:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:24 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')]} 11:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:24 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:24 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 11:26:24 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 11:26:24 INFO - Using env: {'DISPLAY': ':0', 11:26:24 INFO - 'HOME': '/home/cltbld', 11:26:24 INFO - 'LANG': 'en_US.UTF-8', 11:26:24 INFO - 'LANGUAGE': 'en_US:en', 11:26:24 INFO - 'LOGNAME': 'cltbld', 11:26:24 INFO - 'MAIL': '/var/mail/cltbld', 11:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:24 INFO - 'MOZ_NO_REMOTE': '1', 11:26:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:24 INFO - 'NO_EM_RESTART': '1', 11:26:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:24 INFO - 'PWD': '/builds/slave/test', 11:26:24 INFO - 'SHELL': '/bin/bash', 11:26:24 INFO - 'SHLVL': '1', 11:26:24 INFO - 'TERM': 'linux', 11:26:24 INFO - 'TMOUT': '86400', 11:26:24 INFO - 'USER': 'cltbld', 11:26:24 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:24 INFO - '_': '/tools/buildbot/bin/python'} 11:26:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:24 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)) 11:26:24 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)) 11:26:24 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)) 11:26:24 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)) 11:26:24 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)) 11:26:24 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)) 11:26:24 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)) 11:26:24 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)) 11:26:24 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)) 11:26:24 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)) 11:26:24 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)) 11:26:24 INFO - Cleaning up... 11:26:24 INFO - Return code: 0 11:26:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:26:24 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')]} 11:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:24 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')]} 11:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:26:24 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 0x7f0f6316ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23a1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', '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 11:26:24 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 11:26:24 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 11:26:24 INFO - Using env: {'DISPLAY': ':0', 11:26:24 INFO - 'HOME': '/home/cltbld', 11:26:24 INFO - 'LANG': 'en_US.UTF-8', 11:26:24 INFO - 'LANGUAGE': 'en_US:en', 11:26:24 INFO - 'LOGNAME': 'cltbld', 11:26:24 INFO - 'MAIL': '/var/mail/cltbld', 11:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:24 INFO - 'MOZ_NO_REMOTE': '1', 11:26:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:24 INFO - 'NO_EM_RESTART': '1', 11:26:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:24 INFO - 'PWD': '/builds/slave/test', 11:26:24 INFO - 'SHELL': '/bin/bash', 11:26:24 INFO - 'SHLVL': '1', 11:26:24 INFO - 'TERM': 'linux', 11:26:24 INFO - 'TMOUT': '86400', 11:26:24 INFO - 'USER': 'cltbld', 11:26:24 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:24 INFO - '_': '/tools/buildbot/bin/python'} 11:26:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:26:24 INFO - Cleaning up... 11:26:24 INFO - Return code: 0 11:26:24 INFO - Running post-action listener: _resource_record_post_action 11:26:24 INFO - Running post-action listener: _start_resource_monitoring 11:26:24 INFO - Starting resource monitoring. 11:26:24 INFO - [mozharness: 2016-11-14 19:26:24.952265Z] Finished create-virtualenv step (success) 11:26:24 INFO - [mozharness: 2016-11-14 19:26:24.952726Z] Running install step. 11:26:24 INFO - Running pre-action listener: _resource_record_pre_action 11:26:24 INFO - Running main action method: install 11:26:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:25 INFO - Reading from file tmpfile_stdout 11:26:25 INFO - Detecting whether we're running mozinstall >=1.0... 11:26:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:26:25 INFO - Reading from file tmpfile_stdout 11:26:25 INFO - Output received: 11:26:25 INFO - Usage: mozinstall [options] installer 11:26:25 INFO - Options: 11:26:25 INFO - -h, --help show this help message and exit 11:26:25 INFO - -d DEST, --destination=DEST 11:26:25 INFO - Directory to install application into. [default: 11:26:25 INFO - "/builds/slave/test"] 11:26:25 INFO - --app=APP Application being installed. [default: firefox] 11:26:25 INFO - mkdir: /builds/slave/test/build/application 11:26:25 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'] 11:26:25 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 11:26:38 INFO - Reading from file tmpfile_stdout 11:26:38 INFO - Output received: 11:26:38 INFO - /builds/slave/test/build/application/firefox/firefox 11:26:38 INFO - Running post-action listener: _resource_record_post_action 11:26:38 INFO - [mozharness: 2016-11-14 19:26:38.741254Z] Finished install step (success) 11:26:38 INFO - [mozharness: 2016-11-14 19:26:38.741499Z] Running run-tests step. 11:26:38 INFO - Running pre-action listener: _resource_record_pre_action 11:26:38 INFO - Running main action method: run_tests 11:26:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:26:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:26:38 INFO - Python 2.7.3 11:26:38 INFO - Return code: 0 11:26:38 INFO - grabbing minidump binary from tooltool 11:26:38 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')]} 11:26:38 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 0x239ef60>, '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 0x239fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23a01d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:26:38 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 11:26:38 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 11:26:38 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 11:26:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:26:38 INFO - Return code: 0 11:26:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:26:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:26:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:26:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:26:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:26:38 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/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 11:26:38 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/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 11:26:38 INFO - Using env: {'DISPLAY': ':0', 11:26:38 INFO - 'HOME': '/home/cltbld', 11:26:38 INFO - 'LANG': 'en_US.UTF-8', 11:26:38 INFO - 'LANGUAGE': 'en_US:en', 11:26:38 INFO - 'LOGNAME': 'cltbld', 11:26:38 INFO - 'MAIL': '/var/mail/cltbld', 11:26:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:26:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:38 INFO - 'MOZ_NO_REMOTE': '1', 11:26:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:26:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:38 INFO - 'NO_EM_RESTART': '1', 11:26:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:38 INFO - 'PWD': '/builds/slave/test', 11:26:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:26:38 INFO - 'SHELL': '/bin/bash', 11:26:38 INFO - 'SHLVL': '1', 11:26:38 INFO - 'TERM': 'linux', 11:26:38 INFO - 'TMOUT': '86400', 11:26:38 INFO - 'USER': 'cltbld', 11:26:38 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744', 11:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:38 INFO - '_': '/tools/buildbot/bin/python'} 11:26:38 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/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 11:26:39 INFO - mozversion application_buildid: 20161114081643 11:26:39 INFO - mozversion application_changeset: 4ed88686e49e3b941dd9b01f2f5c038f53187a05 11:26:39 INFO - mozversion application_display_name: Firefox 11:26:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:26:39 INFO - mozversion application_name: Firefox 11:26:39 INFO - mozversion application_remotingname: firefox 11:26:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:26:39 INFO - mozversion application_vendor: Mozilla 11:26:39 INFO - mozversion application_version: 51.0 11:26:39 INFO - mozversion platform_buildid: 20161114081643 11:26:39 INFO - mozversion platform_changeset: 4ed88686e49e3b941dd9b01f2f5c038f53187a05 11:26:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:26:39 INFO - mozversion platform_version: 51.0 11:26:39 INFO - using testdate: 1479151599 11:26:39 INFO - actual date: 1479151599 11:26:39 INFO - starting webserver on 'localhost:33492' 11:26:39 INFO - SUITE-START | Running 2 tests 11:26:39 INFO - TEST-START | dromaeo_css 11:26:39 INFO - operating with platform_type : linux_ 11:26:39 INFO - Initialising browser for dromaeo_css test... 11:26:39 INFO - TEST-INFO | started process 2091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaIaPcq/profile http://localhost:33492/getInfo.html) 11:26:43 INFO - PROCESS | 2091 | __metrics Screen width/height:1600/1200 11:26:43 INFO - PROCESS | 2091 | colorDepth:24 11:26:43 INFO - PROCESS | 2091 | Browser inner width/height: 1024/697 11:26:43 INFO - PROCESS | 2091 | __metrics 11:26:43 INFO - PROCESS | 2091 | 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" 11:26:43 INFO - PROCESS | 2091 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:26:44 INFO - PROCESS | 2091 | console.error: PushService: 11:26:44 INFO - PROCESS | 2091 | stateChangeProcessEnqueue: Error transitioning state 11:26:44 INFO - PROCESS | 2091 | UnknownError 11:26:44 INFO - TEST-INFO | 2091: exit 0 11:26:44 INFO - Browser initialized. 11:26:44 INFO - Running cycle 1/1 for dromaeo_css test... 11:26:44 INFO - TEST-INFO | started process 2184 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaIaPcq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:26:56 INFO - PROCESS | 2184 | RSS: Main: 146456576 11:26:56 INFO - PROCESS | 2184 | 11:27:47 INFO - PROCESS | 2184 | 1479151667184 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] 11:29:56 INFO - PROCESS | 2184 | Cycle 1(1): loaded http://localhost:33492/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33492/tests/dromaeo/cssquery-ext.html) 11:29:57 INFO - PROCESS | 2184 | RSS: Main: 230924288 11:29:57 INFO - PROCESS | 2184 | 11:32:59 INFO - PROCESS | 2184 | Cycle 1(1): loaded http://localhost:33492/tests/dromaeo/cssquery-ext.html (next: http://localhost:33492/tests/dromaeo/cssquery-jquery.html) 11:33:00 INFO - PROCESS | 2184 | RSS: Main: 231940096 11:33:00 INFO - PROCESS | 2184 | 11:36:01 INFO - PROCESS | 2184 | Cycle 1(1): loaded http://localhost:33492/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33492/tests/dromaeo/cssquery-mootools.html) 11:36:02 INFO - PROCESS | 2184 | RSS: Main: 235388928 11:36:02 INFO - PROCESS | 2184 | 11:39:04 INFO - PROCESS | 2184 | Cycle 1(1): loaded http://localhost:33492/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33492/tests/dromaeo/cssquery-prototype.html) 11:39:04 INFO - PROCESS | 2184 | RSS: Main: 201580544 11:39:04 INFO - PROCESS | 2184 | 11:42:07 INFO - PROCESS | 2184 | Cycle 1(1): loaded http://localhost:33492/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33492/tests/dromaeo/cssquery-yui.html) 11:42:08 INFO - PROCESS | 2184 | RSS: Main: 174383104 11:42:08 INFO - PROCESS | 2184 | 11:45:10 INFO - PROCESS | 2184 | Cycle 1(1): loaded http://localhost:33492/tests/dromaeo/cssquery-yui.html (next: http://localhost:33492/tests/dromaeo/cssquery-dojo.html) 11:45:11 INFO - PROCESS | 2184 | RSS: Main: 264171520 11:45:11 INFO - PROCESS | 2184 | 11:45:11 INFO - PROCESS | 2184 | __start_tp_report 11:45:11 INFO - PROCESS | 2184 | _x_x_mozilla_page_load 11:45:11 INFO - PROCESS | 2184 | _x_x_mozilla_page_load_details 11:45:11 INFO - PROCESS | 2184 | |i|pagename|runs| 11:45:11 INFO - PROCESS | 2184 | |0;dojo.html;3118.563401123842;3136.7647426443023;3171.8889838855644;3193.441147799135;3207.118042538302;5433.239346491489;5512.5865560083;5523.061079616465;5538.363088244843;5541.778328866841;5507.862303442416;5535.944640553594;5539.9169012464845;5543.307086614173;5574.9721251393685;4990.925136122952;5004.574611158056;5035.194368900977;5069.493050694938;5079.796808127689;4922.433920099181;4926.261060840867;5088.821891233808;5110.182370820651;5123.20590308501;4853.733044682541;4981.352424184851;4987.900241995158;5033.0688822567945;5038.420581633122;5014.799408023675;5017.899642007158;5033.270175824485;5107.080725469414;5127.53852153308;4969.975150124263;5044.092063428581;5068.797248110071;5068.949310506885;5069.847904562869;5330.307060082203;5361.892762144736;5367.597430192712;5469.179623056533;5560.277163968658;5888.352281249059;5906.586538942276;5912.290525136968;5915.704214789243;5933.821985340447;5922.674252916088;5933.3473317934995;5943.554233432466;5954.285485741739;5956.374580669054;5361.705106219157;5407.053765591006;5469.425710300361;5530.557555395559;5536.667799932017;6026.487748541335;6114.810722568954;6117.705611546597;6119.165849018138;6119.746775990644;5869.6771677558145;5871.972404829138;5872.060470324727;5876.7649294028615;5898.351181370046;5676.375598684142;5714.628549144292;5816.970915145482;5945.108233764883;5963.5527335450015;5731.398203188689;5798.565107616945;5808.651480911159;5810.622309549865;5834.299884013861;5818.4763371296785;5823.301203855564;5826.679532625746;5829.7376618052285;5872.17789509469;4439.889002774957;4466.9106617867465;4532.592066714012;4533.410656614619;4543.704659197141;4024.5975402460103;4077.347624380085;4077.408775727503;4096.078382363944;4130.855420060284;5726.627769194989;5740.770369185186;5742.28221472316;5743.54051675865;5751.338596061423;5734.741936612862;5904.114382842608;5971.880562388728;5974.9402505974385;6023.186869772525;5555.72221388937;5556.499915007668;5599.244102046172;5606.271184745957;5715.428457154335;5209.348831396075;5211.166213405837;5211.2443695663915;5214.270002199695;5234.7644356004075;6173.567850250529;6189.907151392733;6190.1952746142715;6193.876122477525;6197.03945888388;5632.7183640816975;5638.859028524155;5841.18223448726;5911.556633252438;5968.253968253968;6156.322804491413;6202.751889924352;6206.658633775005;6210.223722034746;6210.223722034746;5734.197212390185;5833.154192641959;5852.06366981298;5853.2976042875125;5881.529477641864;5661.037623604079;5714.771409143587;5879.588428810113;5989.1914591529585;6000.249968753906;5764.510016648632;5835.4748072673665;5835.912461313169;5868.530517558671;5871.970640146772;5830.766769329349;5833.329167145833;5838.386969368155;5839.445252700986;5844.240248767633;5574.219609254788;5576.581756368208;5812.709364531672;5827.562932779973;5829.59192856496;5797.652140871562;5853.2976042875125;5855.707214639337;5876.853078673067;5982.46157845796;5785.508231800344;5790.855228619318;5795.33353664334;5795.797147099831;5795.7971471 11:45:11 INFO - PROCESS | 2184 | |1;ext.html;595.565237376715;628.1488582970076;652.399792191184;654.5745265577377;655.6885479397287;34786.47820282695;47403.28895066577;48529.51470485294;48546;48596.75701621496;49180;49625;49777.751111244484;49835.25247121296;49935.75032124844;8416.663333466655;9225.72322830316;9403.153716165543;9404.388714733555;9413.152816246537;8364.623591938378;10842.078423334026;11142.27575207613;11150.609728659498;11456.599019034335;11392.316461012324;11448.599299024536;11456.968872801446;11461.255018423775;11465.541378344857;11130.38782866942;11175.44122793857;11183.776324473465;11193.608223712172;11199.944000279987;11052.447377631166;11134.16493762971;11134.944325278402;11139.554417823278;11143.108551315876;45454.77272613616;45692.77153614244;45724.54275457236;45740.3138952916;45768.54231457676;3816.4466152407745;3876.8836934891997;3900.843966241347;4183.309753890604;4426.825568150728;34353.6564634353;34421.6557834421;34480.13799655004;34605.480917786255;35246.47130293048;4723.173444647173;4791.257355109963;4803.615710743132;4884.4871288514905;4912.975435122801;369981;370723;371871;372116;372718;67424;67814.3218567808;67918;68044.31955680478;68087;41427.58572414298;42253.57746422558;42387.152256955305;42790.144197116504;72346.63826680832;15014.849851501564;15045.548633541011;15068.321925513379;15081.924590377197;15084.622884427758;12636.30500322491;12840.165389249669;12907.290099044643;13095.541656041993;13233.801492977613;20597.558170927892;20689.27587534429;20737.481562961046;20835.79164208369;20943.581128377653;7355.448341374418;7405.555666660018;7412.666430010662;7507.21174276704;7548.188164976652;1061.7823346214007;1066.4294602387686;1067.1729409707539;1086.7174534620897;1153.066016526616;33013;34131.4880276796;34272.82863585716;34307.485387718705;34447.48328775071;1141.9771604567861;1152.0348981311554;1182.0484509969385;1184.9822252666218;1263.7346157307068;17295.74056389155;18312.359067432575;18401;18439.354622588413;18530.351437699617;571006;573895;574405;576788;577684;62458.37541624525;63125.68437157877;63186;63241.36758632355;63253.36746632475;1331.467413034794;1366.5968539280937;1421.2112277685983;1439.4889814116048;1459.9270036498344;32599;34315.313693726486;34741.47887781635;34923.47614785831;34957.47563786495;55532;56058.439415605324;56152.71923640519;56243;56283;430044;431418;432315;433638;814258;27427.31431714223;31331.530027049157;31462.370752584364;31479.213019673774;33064;50272.497275026784;50779.23831142462;54053.45946540484;54230.45769542254;54393.45606543884;43810.78094609507;45244.321335180655;45360.31959520544;46160;47037;46100.538994610964;46609.3008604878;46867.489025287185;47080.7645961768;47265.29102063403;43889.78055109704;45103.77448112739;45695.77152114218;45763.08473830438;45774.313385299916;37497.4375384364;39997.60002399939;40474;40880;41009.38485922654;29466.558001630423;30079.849600751855;30442.695573043988;30521.694783052775;30555.388892222476 11:45:11 INFO - PROCESS | 2184 | |2;jquery.html;428.3382174540335;434.68702534175395;435.42741295196817;438.76087532294895;455.11253056539755;17960;19369.612607747855;20005.099770510325;20031.899840500813;20281.0873510692;19187.520311992208;19298.228070877183;19407.611847763037;19879.403617891487;20113;5623.662580245178;5885.617434866741;5898.115282707607;5898.616589921663;5903.35063143054;4354.037082229701;4634.86095417138;5843.152742852284;5928.407159284058;6598.439132673729;6677.2988836172235;6690.531662783583;6694.765683201089;6705.2624211336715;6705.932940670579;6485.254195767477;6685.799426017203;6713.765018224339;6715.832104197385;6737.831554211135;6006.309274433453;6008.669523176223;6008.699565021775;6009.699515024275;6013.609115407485;23653.881730591238;23680.881595592084;23993.160239391713;24003.51992960131;24195.637065443858;2496.2885577610477;2526.6336381224805;2601.0246157690867;2664.893404263827;2719.4561087782326;14821.481248156262;15023.323950422257;15134.697306053928;15192.544223673307;15710.842891571167;2039.51051747581;2041.6427125253022;2086.2280956046206;2098.3600001999384;2117.587070521249;60502.69748651273;82634.17365826386;82943;83466.58266708688;83871.16128838694;11315.434228288552;11340;11461.369624670557;11752.177347585757;11897.405129743478;16574.75137872933;16648.66702665964;16655.41706040283;16666.33334666624;16667.49997500077;7934.682612695543;8216.26053655174;8273.296769774637;8330.416870818985;8431.704890328932;7982.600869956479;7990.240927111915;8124;8179.4274400792565;8229.835403291901;6878.3465570770695;6945.374916257561;6945.89581156283;7003.194632617212;7072.575645461289;2048.3240530624857;2079.781622929601;2112.6725357569603;2120.6288899442534;2124.702541644171;478.77258302306126;980.3333733061587;1035.9378437293635;1047.2407504559285;1104.8619921480727;11759.294442333487;12755.29845858468;12782.424790884432;12832.679183020313;13170.868291317156;496.3001719112027;511.7901660319326;518.4530320511897;520.5497244883148;533.4691981478454;6654.633995130217;6825.726970921207;6833.077534532775;6872.518923675295;6877.449804015667;497978;499447;500797;519147;520013;96762;97691;98791;101264;101289;527.6438404077232;541.1341853035112;541.2990177719862;541.3179393963555;541.6912359954866;11662.416879155839;11777.352245626573;11782.351970641697;12085.395730213279;12162.452689629165;40216.1956760869;42271.57728422676;42357.5764242366;43118.56881431145;43703;60636;61168.69415652893;61303;61508.384916152056;62264.37735622586;5928.199693041384;8506.319494440306;8685.869711954452;8929.10708929102;9132.908670913206;12345.753084938431;13493;13497.730045399234;13947.302634868012;14005.789913151515;11871.228370155912;12074.517019319128;12184.14710970259;12285.938570307091;12427.875721243034;11181.496832642713;11394.658160255205;11557.017653499546;11588.246763960316;12050.75898482043;11611.88388116108;11864.584739534075;12340.074494413135;12385.380730963596;12445.813312800134;8674.132586741276;8808.91191088081;8904.73285801427;8924.821503570021;8990.190882820578;8615.956920215358;11108.444577770917;11745.882541174478;12018.33899135556;12035.157538972539 11:45:11 INFO - PROCESS | 2184 | |3;mootools.html;1229.8585662648786;1538.5768913548777;1572.0489104092023;1690.3745614122768;1836.1828986101182;7914.60426978651;8489.575521223936;8857.158569935862;8870.733877983655;9021.188093071622;8823.558822058887;8878.334124940622;8904.465732056082;8906.198442140207;8941.865872011926;2393.4734358441115;2420.9273721788286;2423.297243799305;2423.3941514621347;2426.1508472034825;2396.4128788446856;2412.867292298923;2425.1875621666763;2429.842060266086;2433;2369.57347677418;2377.4413012941945;2403.531311394279;2448.1064411489765;2448.3879030242438;2358.41039740065;2377.072941552796;2383.940401489968;2384.0344660412475;2397.580423425911;2353.1411034972284;2373.0270589058405;2385.0459816073535;2386.618141097416;2387.2241521505407;8729.825403491895;8809.38334316598;8839.16027977341;8895.599698013604;8948.284137269004;5302.416734159239;5511.58663100269;5514.531264842493;5518.806841760559;5520.806771762969;3438.8452519636676;3443.8622455101768;3488.773229740084;3545.716342692604;3554.289142171576;1287.2276634019606;1288.871112888713;1294.3075454631726;1323.2060763541895;1336.2049580499588;12246.510139594406;12333.814992775115;12354.38228088601;12370.690732731753;12381.380930953417;4444.133393988206;4657.580817726421;4724.220503616864;4800.495947925488;4803.543663351976;9811.215102791903;9832.213422926148;9846.606135754633;9856.408615483095;9856.802863942681;5245.816396426183;5246.868828279324;5250.422453530108;5251.789928402897;5255.448177941261;4438.467383913951;4557.703749256288;4932.679375840559;4944.1100601892;5039.118154322979;3925.509311336083;3929.901752456154;4052.392141178847;4057.16828050249;4088.2028004539425;1162.5296245478357;1168.6026750904732;1169.4503583315827;1176.3765204441659;1196.946137423818;867.6919693508839;880;954.8970938565967;982.1455333859445;1009.8182327181178;5989.4609485145675;6264.21697287839;6379.075034120119;6461.030845373138;6550.213974323112;1030.90206430389;1046.895310468947;1051.9211059170593;1069.8816343639483;1098.4068602954314;4158.08522125132;4314.654827613784;4458.08609235106;4468.396766436555;4468.843590474383;10764.78470430587;11182.552697891993;11223.046041086436;11231.213815033032;11270.154738394653;11221.495032723547;11221.551137954553;11221.719457013476;11240.775184496428;11241;7976.5612891290375;8290.253877150964;8297.709580164665;8415.116412776693;8429.114942931026;6182.227221597301;6210.3168651449205;6283.7800676976085;6358.682065896779;6573.934260657524;10165;10187.643432479828;10294.691159265234;10315.12321452655;10387.584496620051;10399.896001039893;10468.057874791306;10548.36709797384;10580.947095264783;10607.310524815863;7913;7995.560244186626;8010.639521221676;8073.15231900642;8081.4747041442115;8717.782055448664;8803.29573634095;8827.382083254111;8837.734867953972;8845.823083538422;7551.282628150317;7779.57212353326;7861.52830830152;7861.764147075597;7890.329322007694;7969.80075498094;7980.600969951363;7996.400269979891;8057.194280571896;8229.95885020571;7141.750038748619;7170.892436613351;7229.819254518679;7243.782686519413;7244.673989670372;5440.075187218262;5609.242752228397;5627.690477023639;5650.63270887391;5671.234383358194;5516.586256030733;5570.498655121059;5636;5657.915131272952;5663.716814159193 11:45:11 INFO - PROCESS | 2184 | |4;prototype.html;361.6347489036074;362.5594902193834;368.1863082587482;371.8456840411229;381.3383779143184;5273.472652734725;5447.754851031703;5479.972600136999;5529.944700552994;5540.617534460181;5213.644452442364;5366.785328586851;5379.273798037264;5385.461453854613;5405.675659460435;4331.7834108294455;4333.826646934119;4338.327559228324;4343.478782546099;4346.97826510867;4216.451861258032;4331.870043898688;4333.826646934119;4351.499577548575;4374;4279.828806847722;4296.785160741966;4298.570142985692;4302.8709138725735;4309.37514060461;4271.765052922088;4288.31386978082;4293.5401963332615;4296.677749168824;4305.115572566372;3704.6480584344445;3810.047488127968;3834.2523207974455;3841.9807900960595;3865.265599536079;5306.442823503515;5312.203169524563;5314.681119132864;5327.840164795062;5340.145576707708;4904.313396124546;4904.754762261909;4917.901641967176;4924.975375123137;4926.679765815246;5326.322208504347;5664;5682.17608446777;5705.5435565154685;5714.685692286964;4198.160367926427;4204.327307630764;4215.894602634928;4216.304309788881;4216.852410165629;5930.673812940287;5952.255968003999;5957.7319020643745;5966.850828729273;5997.340292567814;4080.6123418275215;4261;4282.650964946032;4299.161663475655;4299.226139294957;4329.766016685229;4337.544557821447;4341.696081274281;4360.367746676714;4361.847335343247;4917.950820491749;4936.6297527685565;4940.8764780880765;4949.133901567283;4952.2324039773175;5494.230807686927;5719.685417302005;5749.7700091996685;5758.337791154071;5762.394948530427;1395.9162450253018;1427.4432971141264;1454.789055586955;1475.3950880139091;1488.8238291749396;1008.5864795433837;1035.5961175141701;1042.607216438276;1056.2236755984345;1057.180684969155;5514.696691681996;5517.475839795213;5517.586181036439;5520.3651580068;5532.889342213133;5754.482096611325;5773.766690653248;5789.45000224978;5807.912881306784;5828.358880523139;5445.673259604437;5556.110922286442;5570.805021824217;5670.419958918682;5716.314042314865;3077.38452309539;3115.2679120406688;3115.6728543502604;3138.184072141214;3141.2303985523695;5937.526546970071;6108.1143234230785;6131.8160455185525;6142.754289828446;6153.7230824613;5976.701164941735;5991.460768530853;6006.519478441714;6016.488598469179;6039.969800151059;6155.230596175478;6165.1985241919165;6187.474064704551;6198.318184999736;6218;5698.487136157766;5915.852103697442;5932.2584676915385;5960.910586341295;5987.5808693391045;5812.389699081536;5856.150858125463;5856.267966504187;5873.622666966426;5883.411658834082;5936.495397891227;6168.414000669929;6189.752409903734;6202.968985155046;6216.487684458267;4316.266234740039;4332.941852619981;4343.087951530088;4344.608985191221;4352.801835073267;5675.914861277167;5837.912431313449;5981.7308221131025;5994.291626886316;5996.68073023934;5624.6625202487985;5650.773969041193;5665.518430933252;5776.220210271559;5814.5639077068545;5687.175359572756;5719.5138413235345;5736.684482353344;5802.332731735735;5807.216025836458;5589.189567512606;5709.3719690834805;5711.828645140652;5735.075111692983;5739.684317362585;5750.827475175752;5766.59633825645;5793.826185214451;5799.652020878761;5809.978811018163;5380;5655.88688226225;5660.603757736919;5686.488216060575;5697.7720891165545 11:45:11 INFO - PROCESS | 2184 | |5;yui.html;840.4453060979753;848.6902280667558;853.6244052616852;869.3349587565513;902.2195800632446;17212;17997.820021799795;18012.279508819633;18027.36904208353;18107.094645267734;16089.597760056004;16290;16303;16307.429239976573;16320.102394368305;722.9457790665695;737.7786664000805;869.3523325122777;874.2961915657893;895.0646574329809;907.9773005674845;911.2209061252632;911.3256190419075;911.4850109688039;911.8677791720197;947.3510645208055;948.5352177433043;949.2548349545608;949.5964215208543;949.7293271417648;940.1773448232797;950.2208189284789;950.7147855643314;950.7908260182784;951.3055469507264;689.3692271571556;689.5793565924782;693.6358411833828;694.4062826283505;695.0720787748362;16302.918485407616;16426.342946282133;16445;16466.42367517131;16484.175791210393;15882.76175857363;16384.50846474596;16647.173489278775;16788.160591970474;17692.469225923112;13729.278044835813;13880.722385552333;13966.86033139676;14045;14192.716145677132;4106.486689163855;4146.5853414658295;4212.978935105336;4222.831086756526;4238.08881090564;84196;84989.57505212496;85034.14965850324;85999;87109.56445217734;12737.936310318575;12866;12896.161749486253;13011.414486348138;13147.53983610569;22934.65598016031;23038.308850734506;23137.652935205984;23168.65247021296;23314.067437302318;8696.13038696138;8703.347248956354;8779.12208779117;8897.421667591585;9269.212116969999;5058.494150584986;5127.589792816566;5192.948070519322;5263.6841789492755;5301.867453313621;3589.4974703541525;3715.5727091384297;3717.20080182762;3720.813959302024;3724.7020238380865;619.5229673151648;622.4553515673786;625.1622825413967;630.4735545819201;636.926753423353;635.2028204603193;635.6345101566618;687.8211664967145;757.8294883651134;761.162721006888;9421.952890235641;10157.542910568895;10324.690259292232;10449.895501044892;10561.894381056245;672.4889084295872;739.3161325773638;750.0998801438296;754.4115589839934;776.7203806629609;5144.4855514449;5213.765380557884;5218.739063046833;5228.08508511009;5257.8159764408065;68835.65582172057;69308.6534567324;104301.47849260857;104962;107597;58619.12071318934;58876;106842;107811.46094269479;112116;56941;57160.141387981654;83361.58319208365;92256;93978;10823.51294191779;11231.71920701989;11310.60412885545;11508.88491115078;11559.190856639954;51286.743566281926;57544.4245557539;57854.421455784905;58337.4166258349;59243.03784810828;49592.256116159006;51613.741931290104;52554.73722631362;63548.364516354246;77920;13625.863741362857;14065.226412547;14581.343839527035;14728.852711472748;14991.475298364941;16171.919140404694;16183.75724364159;16237.431689890796;16654.16729163561;17192.398266060627;14226.928865356022;14848.851511484747;15192.848071519144;15260.847391526388;15428.382864685287;15002.249887505362;15306.311215995529;15585.844141558438;15974.121423321829;16496.505104846874;13259.668508286984;14184;14434.566962991126;14461.566153015427;14916.030458020194;11775.646730598095;12432.81350779721;12712.173708708904;12761.106722529334;13057.34713264352;12090.697732556757;12117.422268542923;12380.380980950737;12423.130380873614;12445.502179912703 11:45:11 INFO - PROCESS | 2184 | __end_tp_report 11:45:11 INFO - PROCESS | 2184 | __start_cc_report 11:45:11 INFO - PROCESS | 2184 | _x_x_mozilla_cycle_collect,992 11:45:11 INFO - PROCESS | 2184 | __end_cc_report 11:45:11 INFO - PROCESS | 2184 | __startTimestamp1479152711014__endTimestamp 11:45:11 INFO - PROCESS | 2184 | 11:45:11 INFO - PROCESS | 2184 | ------- Summary: start ------- 11:45:11 INFO - PROCESS | 2184 | Number of tests: 6 11:45:11 INFO - PROCESS | 2184 | 11:45:11 INFO - PROCESS | 2184 | [#0] dojo.html Cycles:165 Average:5500.91 Median:5740.77 stddev:629.07 (11.0%) stddev-sans-first:602.60 11:45:11 INFO - PROCESS | 2184 | Values: 3118.6 3136.8 3171.9 3193.4 3207.1 5433.2 5512.6 5523.1 5538.4 5541.8 5507.9 5535.9 5539.9 5543.3 5575.0 4990.9 5004.6 5035.2 5069.5 5079.8 4922.4 4926.3 5088.8 5110.2 5123.2 4853.7 4981.4 4987.9 5033.1 5038.4 5014.8 5017.9 5033.3 5107.1 5127.5 4970.0 5044.1 5068.8 5068.9 5069.8 5330.3 5361.9 5367.6 5469.2 5560.3 5888.4 5906.6 5912.3 5915.7 5933.8 5922.7 5933.3 5943.6 5954.3 5956.4 5361.7 5407.1 5469.4 5530.6 5536.7 6026.5 6114.8 6117.7 6119.2 6119.7 5869.7 5872.0 5872.1 5876.8 5898.4 5676.4 5714.6 5817.0 5945.1 5963.6 5731.4 5798.6 5808.7 5810.6 5834.3 5818.5 5823.3 5826.7 5829.7 5872.2 4439.9 4466.9 4532.6 4533.4 4543.7 4024.6 4077.3 4077.4 4096.1 4130.9 5726.6 5740.8 5742.3 5743.5 5751.3 5734.7 5904.1 5971.9 5974.9 6023.2 5555.7 5556.5 5599.2 5606.3 5715.4 5209.3 5211.2 5211.2 5214.3 5234.8 6173.6 6189.9 6190.2 6193.9 6197.0 5632.7 5638.9 5841.2 5911.6 5968.3 6156.3 6202.8 6206.7 6210.2 6210.2 5734.2 5833.2 5852.1 5853.3 5881.5 5661.0 5714.8 5879.6 5989.2 6000.2 5764.5 5835.5 5835.9 5868.5 5872.0 5830.8 5833.3 5838.4 5839.4 5844.2 5574.2 5576.6 5812.7 5827.6 5829.6 5797.7 5853.3 5855.7 5876.9 5982.5 5785.5 5790.9 5795.3 5795.8 5795.8 11:45:11 INFO - PROCESS | 2184 | 11:45:11 INFO - PROCESS | 2184 | [#1] ext.html Cycles:180 Average:65727.31 Median:40677.00 stddev:133016.43 (327.0%) stddev-sans-first:133299.69 11:45:11 INFO - PROCESS | 2184 | Values: 595.6 628.1 652.4 654.6 655.7 34786.5 47403.3 48529.5 48546.0 48596.8 49180.0 49625.0 49777.8 49835.3 49935.8 8416.7 9225.7 9403.2 9404.4 9413.2 8364.6 10842.1 11142.3 11150.6 11456.6 11392.3 11448.6 11457.0 11461.3 11465.5 11130.4 11175.4 11183.8 11193.6 11199.9 11052.4 11134.2 11134.9 11139.6 11143.1 45454.8 45692.8 45724.5 45740.3 45768.5 3816.4 3876.9 3900.8 4183.3 4426.8 34353.7 34421.7 34480.1 34605.5 35246.5 4723.2 4791.3 4803.6 4884.5 4913.0 369981.0 370723.0 371871.0 372116.0 372718.0 67424.0 67814.3 67918.0 68044.3 68087.0 41427.6 42253.6 42387.2 42790.1 72346.6 15014.8 15045.5 15068.3 15081.9 15084.6 12636.3 12840.2 12907.3 13095.5 13233.8 20597.6 20689.3 20737.5 20835.8 20943.6 7355.4 7405.6 7412.7 7507.2 7548.2 1061.8 1066.4 1067.2 1086.7 1153.1 33013.0 34131.5 34272.8 34307.5 34447.5 1142.0 1152.0 1182.0 1185.0 1263.7 17295.7 18312.4 18401.0 18439.4 18530.4 571006.0 573895.0 574405.0 576788.0 577684.0 62458.4 63125.7 63186.0 63241.4 63253.4 1331.5 1366.6 1421.2 1439.5 1459.9 32599.0 34315.3 34741.5 34923.5 34957.5 55532.0 56058.4 56152.7 56243.0 56283.0 430044.0 431418.0 432315.0 433638.0 814258.0 27427.3 31331.5 31462.4 31479.2 33064.0 50272.5 50779.2 54053.5 54230.5 54393.5 43810.8 45244.3 45360.3 46160.0 47037.0 46100.5 46609.3 46867.5 47080.8 47265.3 43889.8 45103.8 45695.8 45763.1 45774.3 37497.4 39997.6 40474.0 40880.0 41009.4 29466.6 30079.8 30442.7 30521.7 30555.4 11:45:11 INFO - PROCESS | 2184 | 11:45:11 INFO - PROCESS | 2184 | [#2] jquery.html Cycles:180 Average:29652.33 Median:2394.73 stddev:83773.95 (3498.3%) stddev-sans-first:83980.22 11:45:11 INFO - PROCESS | 2184 | Values: 428.3 434.7 435.4 438.8 455.1 17960.0 19369.6 20005.1 20031.9 20281.1 19187.5 19298.2 19407.6 19879.4 20113.0 5623.7 5885.6 5898.1 5898.6 5903.4 4354.0 4634.9 5843.2 5928.4 6598.4 6677.3 6690.5 6694.8 6705.3 6705.9 6485.3 6685.8 6713.8 6715.8 6737.8 6006.3 6008.7 6008.7 6009.7 6013.6 23653.9 23680.9 23993.2 24003.5 24195.6 2496.3 2526.6 2601.0 2664.9 2719.5 14821.5 15023.3 15134.7 15192.5 15710.8 2039.5 2041.6 2086.2 2098.4 2117.6 60502.7 82634.2 82943.0 83466.6 83871.2 11315.4 11340.0 11461.4 11752.2 11897.4 16574.8 16648.7 16655.4 16666.3 16667.5 7934.7 8216.3 8273.3 8330.4 8431.7 7982.6 7990.2 8124.0 8179.4 8229.8 6878.3 6945.4 6945.9 7003.2 7072.6 2048.3 2079.8 2112.7 2120.6 2124.7 478.8 980.3 1035.9 1047.2 1104.9 11759.3 12755.3 12782.4 12832.7 13170.9 496.3 511.8 518.5 520.5 533.5 6654.6 6825.7 6833.1 6872.5 6877.4 497978.0 499447.0 500797.0 519147.0 520013.0 96762.0 97691.0 98791.0 101264.0 101289.0 527.6 541.1 541.3 541.3 541.7 11662.4 11777.4 11782.4 12085.4 12162.5 40216.2 42271.6 42357.6 43118.6 43703.0 60636.0 61168.7 61303.0 61508.4 62264.4 5928.2 8506.3 8685.9 8929.1 9132.9 12345.8 13493.0 13497.7 13947.3 14005.8 11871.2 12074.5 12184.1 12285.9 12427.9 11181.5 11394.7 11557.0 11588.2 12050.8 11611.9 11864.6 12340.1 12385.4 12445.8 8674.1 8808.9 8904.7 8924.8 8990.2 8616.0 11108.4 11745.9 12018.3 12035.2 11:45:11 INFO - PROCESS | 2184 | 11:45:11 INFO - PROCESS | 2184 | [#3] mootools.html Cycles:180 Average:5939.71 Median:4762.36 stddev:3318.19 (69.7%) stddev-sans-first:3308.62 11:45:11 INFO - PROCESS | 2184 | Values: 1229.9 1538.6 1572.0 1690.4 1836.2 7914.6 8489.6 8857.2 8870.7 9021.2 8823.6 8878.3 8904.5 8906.2 8941.9 2393.5 2420.9 2423.3 2423.4 2426.2 2396.4 2412.9 2425.2 2429.8 2433.0 2369.6 2377.4 2403.5 2448.1 2448.4 2358.4 2377.1 2383.9 2384.0 2397.6 2353.1 2373.0 2385.0 2386.6 2387.2 8729.8 8809.4 8839.2 8895.6 8948.3 5302.4 5511.6 5514.5 5518.8 5520.8 3438.8 3443.9 3488.8 3545.7 3554.3 1287.2 1288.9 1294.3 1323.2 1336.2 12246.5 12333.8 12354.4 12370.7 12381.4 4444.1 4657.6 4724.2 4800.5 4803.5 9811.2 9832.2 9846.6 9856.4 9856.8 5245.8 5246.9 5250.4 5251.8 5255.4 4438.5 4557.7 4932.7 4944.1 5039.1 3925.5 3929.9 4052.4 4057.2 4088.2 1162.5 1168.6 1169.5 1176.4 1196.9 867.7 880.0 954.9 982.1 1009.8 5989.5 6264.2 6379.1 6461.0 6550.2 1030.9 1046.9 1051.9 1069.9 1098.4 4158.1 4314.7 4458.1 4468.4 4468.8 10764.8 11182.6 11223.0 11231.2 11270.2 11221.5 11221.6 11221.7 11240.8 11241.0 7976.6 8290.3 8297.7 8415.1 8429.1 6182.2 6210.3 6283.8 6358.7 6573.9 10165.0 10187.6 10294.7 10315.1 10387.6 10399.9 10468.1 10548.4 10580.9 10607.3 7913.0 7995.6 8010.6 8073.2 8081.5 8717.8 8803.3 8827.4 8837.7 8845.8 7551.3 7779.6 7861.5 7861.8 7890.3 7969.8 7980.6 7996.4 8057.2 8230.0 7141.8 7170.9 7229.8 7243.8 7244.7 5440.1 5609.2 5627.7 5650.6 5671.2 5516.6 5570.5 5636.0 5657.9 5663.7 11:45:11 INFO - PROCESS | 2184 | 11:45:11 INFO - PROCESS | 2184 | [#4] prototype.html Cycles:180 Average:4860.27 Median:5487.10 stddev:1412.06 (25.7%) stddev-sans-first:1375.05 11:45:11 INFO - PROCESS | 2184 | Values: 361.6 362.6 368.2 371.8 381.3 5273.5 5447.8 5480.0 5529.9 5540.6 5213.6 5366.8 5379.3 5385.5 5405.7 4331.8 4333.8 4338.3 4343.5 4347.0 4216.5 4331.9 4333.8 4351.5 4374.0 4279.8 4296.8 4298.6 4302.9 4309.4 4271.8 4288.3 4293.5 4296.7 4305.1 3704.6 3810.0 3834.3 3842.0 3865.3 5306.4 5312.2 5314.7 5327.8 5340.1 4904.3 4904.8 4917.9 4925.0 4926.7 5326.3 5664.0 5682.2 5705.5 5714.7 4198.2 4204.3 4215.9 4216.3 4216.9 5930.7 5952.3 5957.7 5966.9 5997.3 4080.6 4261.0 4282.7 4299.2 4299.2 4329.8 4337.5 4341.7 4360.4 4361.8 4918.0 4936.6 4940.9 4949.1 4952.2 5494.2 5719.7 5749.8 5758.3 5762.4 1395.9 1427.4 1454.8 1475.4 1488.8 1008.6 1035.6 1042.6 1056.2 1057.2 5514.7 5517.5 5517.6 5520.4 5532.9 5754.5 5773.8 5789.5 5807.9 5828.4 5445.7 5556.1 5570.8 5670.4 5716.3 3077.4 3115.3 3115.7 3138.2 3141.2 5937.5 6108.1 6131.8 6142.8 6153.7 5976.7 5991.5 6006.5 6016.5 6040.0 6155.2 6165.2 6187.5 6198.3 6218.0 5698.5 5915.9 5932.3 5960.9 5987.6 5812.4 5856.2 5856.3 5873.6 5883.4 5936.5 6168.4 6189.8 6203.0 6216.5 4316.3 4332.9 4343.1 4344.6 4352.8 5675.9 5837.9 5981.7 5994.3 5996.7 5624.7 5650.8 5665.5 5776.2 5814.6 5687.2 5719.5 5736.7 5802.3 5807.2 5589.2 5709.4 5711.8 5735.1 5739.7 5750.8 5766.6 5793.8 5799.7 5810.0 5380.0 5655.9 5660.6 5686.5 5697.8 11:45:11 INFO - PROCESS | 2184 | 11:45:11 INFO - PROCESS | 2184 | [#5] yui.html Cycles:180 Average:20325.39 Median:4126.54 stddev:27171.50 (658.5%) stddev-sans-first:27208.33 11:45:11 INFO - PROCESS | 2184 | Values: 840.4 848.7 853.6 869.3 902.2 17212.0 17997.8 18012.3 18027.4 18107.1 16089.6 16290.0 16303.0 16307.4 16320.1 722.9 737.8 869.4 874.3 895.1 908.0 911.2 911.3 911.5 911.9 947.4 948.5 949.3 949.6 949.7 940.2 950.2 950.7 950.8 951.3 689.4 689.6 693.6 694.4 695.1 16302.9 16426.3 16445.0 16466.4 16484.2 15882.8 16384.5 16647.2 16788.2 17692.5 13729.3 13880.7 13966.9 14045.0 14192.7 4106.5 4146.6 4213.0 4222.8 4238.1 84196.0 84989.6 85034.1 85999.0 87109.6 12737.9 12866.0 12896.2 13011.4 13147.5 22934.7 23038.3 23137.7 23168.7 23314.1 8696.1 8703.3 8779.1 8897.4 9269.2 5058.5 5127.6 5192.9 5263.7 5301.9 3589.5 3715.6 3717.2 3720.8 3724.7 619.5 622.5 625.2 630.5 636.9 635.2 635.6 687.8 757.8 761.2 9422.0 10157.5 10324.7 10449.9 10561.9 672.5 739.3 750.1 754.4 776.7 5144.5 5213.8 5218.7 5228.1 5257.8 68835.7 69308.7 104301.5 104962.0 107597.0 58619.1 58876.0 106842.0 107811.5 112116.0 56941.0 57160.1 83361.6 92256.0 93978.0 10823.5 11231.7 11310.6 11508.9 11559.2 51286.7 57544.4 57854.4 58337.4 59243.0 49592.3 51613.7 52554.7 63548.4 77920.0 13625.9 14065.2 14581.3 14728.9 14991.5 16171.9 16183.8 16237.4 16654.2 17192.4 14226.9 14848.9 15192.8 15260.8 15428.4 15002.2 15306.3 15585.8 15974.1 16496.5 13259.7 14184.0 14434.6 14461.6 14916.0 11775.6 12432.8 12712.2 12761.1 13057.3 12090.7 12117.4 12380.4 12423.1 12445.5 11:45:11 INFO - PROCESS | 2184 | -------- Summary: end -------- 11:45:11 INFO - PROCESS | 2184 | 11:45:11 INFO - TEST-INFO | 2184: exit 0 11:45:11 INFO - TEST-OK | dromaeo_css | took 1112761ms 11:45:11 INFO - TEST-START | kraken 11:45:11 INFO - operating with platform_type : linux_ 11:45:11 INFO - Initialising browser for kraken test... 11:45:11 INFO - TEST-INFO | started process 2577 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpprlTdo/profile http://localhost:33492/getInfo.html) 11:45:15 INFO - PROCESS | 2577 | __metrics Screen width/height:1600/1200 11:45:15 INFO - PROCESS | 2577 | colorDepth:24 11:45:15 INFO - PROCESS | 2577 | Browser inner width/height: 1024/697 11:45:15 INFO - PROCESS | 2577 | __metrics 11:45:16 INFO - PROCESS | 2577 | 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" 11:45:16 INFO - PROCESS | 2577 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:45:16 INFO - PROCESS | 2577 | console.error: PushService: 11:45:16 INFO - PROCESS | 2577 | stateChangeProcessEnqueue: Error transitioning state 11:45:16 INFO - PROCESS | 2577 | UnknownError 11:45:17 INFO - TEST-INFO | 2577: exit 0 11:45:17 INFO - Browser initialized. 11:45:17 INFO - Running cycle 1/1 for kraken test... 11:45:17 INFO - TEST-INFO | started process 2670 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpprlTdo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:29 INFO - PROCESS | 2670 | RSS: Main: 142749696 11:45:29 INFO - PROCESS | 2670 | 11:45:31 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33492/tests/kraken/driver.html?testName=audio-beat-detection) 11:45:32 INFO - PROCESS | 2670 | RSS: Main: 186011648 11:45:32 INFO - PROCESS | 2670 | 11:45:34 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33492/tests/kraken/driver.html?testName=audio-dft) 11:45:35 INFO - PROCESS | 2670 | RSS: Main: 173334528 11:45:35 INFO - PROCESS | 2670 | 11:45:38 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33492/tests/kraken/driver.html?testName=audio-fft) 11:45:38 INFO - PROCESS | 2670 | RSS: Main: 264790016 11:45:38 INFO - PROCESS | 2670 | 11:45:41 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33492/tests/kraken/driver.html?testName=audio-oscillator) 11:45:41 INFO - PROCESS | 2670 | RSS: Main: 171524096 11:45:41 INFO - PROCESS | 2670 | 11:45:43 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33492/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:45:44 INFO - PROCESS | 2670 | RSS: Main: 173142016 11:45:44 INFO - PROCESS | 2670 | 11:45:47 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33492/tests/kraken/driver.html?testName=imaging-darkroom) 11:45:47 INFO - PROCESS | 2670 | RSS: Main: 209133568 11:45:47 INFO - PROCESS | 2670 | 11:45:51 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33492/tests/kraken/driver.html?testName=imaging-desaturate) 11:45:51 INFO - PROCESS | 2670 | RSS: Main: 208195584 11:45:51 INFO - PROCESS | 2670 | 11:45:54 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33492/tests/kraken/driver.html?testName=json-parse-financial) 11:45:55 INFO - PROCESS | 2670 | RSS: Main: 207253504 11:45:55 INFO - PROCESS | 2670 | 11:45:56 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33492/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:45:57 INFO - PROCESS | 2670 | RSS: Main: 169328640 11:45:57 INFO - PROCESS | 2670 | 11:45:58 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33492/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:45:59 INFO - PROCESS | 2670 | RSS: Main: 176844800 11:45:59 INFO - PROCESS | 2670 | 11:46:01 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33492/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:46:02 INFO - PROCESS | 2670 | RSS: Main: 186355712 11:46:02 INFO - PROCESS | 2670 | 11:46:04 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33492/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:46:05 INFO - PROCESS | 2670 | RSS: Main: 195297280 11:46:05 INFO - PROCESS | 2670 | 11:46:07 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33492/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:46:08 INFO - PROCESS | 2670 | RSS: Main: 191287296 11:46:08 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | Cycle 1(1): loaded http://localhost:33492/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33492/tests/kraken/driver.html?testName=ai-astar) 11:46:10 INFO - PROCESS | 2670 | RSS: Main: 242667520 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | __start_tp_report 11:46:10 INFO - PROCESS | 2670 | _x_x_mozilla_page_load 11:46:10 INFO - PROCESS | 2670 | _x_x_mozilla_page_load_details 11:46:10 INFO - PROCESS | 2670 | |i|pagename|runs| 11:46:10 INFO - PROCESS | 2670 | |0;ai-astar;112;110;109;109;110;109;110;111;111;110 11:46:10 INFO - PROCESS | 2670 | |1;audio-beat-detection;142;116;116;156;118;120;118;116;140;117 11:46:10 INFO - PROCESS | 2670 | |2;audio-dft;159;161;160;161;160;161;159;160;161;159 11:46:10 INFO - PROCESS | 2670 | |3;audio-fft;74;74;76;115;76;76;75;76;99;75 11:46:10 INFO - PROCESS | 2670 | |4;audio-oscillator;82;81;124;81;82;81;110;81;82;107 11:46:10 INFO - PROCESS | 2670 | |5;imaging-gaussian-blur;117;116;117;116;117;117;117;116;116;117 11:46:10 INFO - PROCESS | 2670 | |6;imaging-darkroom;145;144;145;146;146;145;145;145;146;145 11:46:10 INFO - PROCESS | 2670 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;94;95 11:46:10 INFO - PROCESS | 2670 | |8;json-parse-financial;51;51;51;53;50;50;53;51;50;53 11:46:10 INFO - PROCESS | 2670 | |9;json-stringify-tinderbox;59;60;59;59;59;60;60;59;60;61 11:46:10 INFO - PROCESS | 2670 | |10;stanford-crypto-aes;72;73;73;72;74;73;73;74;93;72 11:46:10 INFO - PROCESS | 2670 | |11;stanford-crypto-ccm;121;118;120;128;123;123;117;117;162;123 11:46:10 INFO - PROCESS | 2670 | |12;stanford-crypto-pbkdf2;157;156;152;152;158;158;156;153;153;151 11:46:10 INFO - PROCESS | 2670 | |13;stanford-crypto-sha256-iterative;66;64;64;61;61;63;64;61;60;60 11:46:10 INFO - PROCESS | 2670 | __end_tp_report 11:46:10 INFO - PROCESS | 2670 | __start_cc_report 11:46:10 INFO - PROCESS | 2670 | _x_x_mozilla_cycle_collect,1576 11:46:10 INFO - PROCESS | 2670 | __end_cc_report 11:46:10 INFO - PROCESS | 2670 | __startTimestamp1479152770319__endTimestamp 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | ------- Summary: start ------- 11:46:10 INFO - PROCESS | 2670 | Number of tests: 14 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.00 stddev:0.99 (0.9%) stddev-sans-first:0.78 11:46:10 INFO - PROCESS | 2670 | Values: 112.0 110.0 109.0 109.0 110.0 109.0 110.0 111.0 111.0 110.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#1] audio-beat-detection Cycles:10 Average:125.90 Median:119.00 stddev:14.52 (12.2%) stddev-sans-first:14.18 11:46:10 INFO - PROCESS | 2670 | Values: 142.0 116.0 116.0 156.0 118.0 120.0 118.0 116.0 140.0 117.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:0.88 (0.5%) stddev-sans-first:0.83 11:46:10 INFO - PROCESS | 2670 | Values: 159.0 161.0 160.0 161.0 160.0 161.0 159.0 160.0 161.0 159.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.00 stddev:13.93 (18.3%) stddev-sans-first:14.50 11:46:10 INFO - PROCESS | 2670 | Values: 74.0 74.0 76.0 115.0 76.0 76.0 75.0 76.0 99.0 75.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:81.00 stddev:16.16 (19.9%) stddev-sans-first:16.80 11:46:10 INFO - PROCESS | 2670 | Values: 82.0 81.0 124.0 81.0 82.0 81.0 110.0 81.0 82.0 107.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 11:46:10 INFO - PROCESS | 2670 | Values: 117.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 11:46:10 INFO - PROCESS | 2670 | Values: 145.0 144.0 145.0 146.0 146.0 145.0 145.0 145.0 146.0 145.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 11:46:10 INFO - PROCESS | 2670 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.00 stddev:1.25 (2.5%) stddev-sans-first:1.32 11:46:10 INFO - PROCESS | 2670 | Values: 51.0 51.0 51.0 53.0 50.0 50.0 53.0 51.0 50.0 53.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.70 (1.2%) stddev-sans-first:0.71 11:46:10 INFO - PROCESS | 2670 | Values: 59.0 60.0 59.0 59.0 59.0 60.0 60.0 59.0 60.0 61.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:73.00 stddev:6.40 (8.8%) stddev-sans-first:6.70 11:46:10 INFO - PROCESS | 2670 | Values: 72.0 73.0 73.0 72.0 74.0 73.0 73.0 74.0 93.0 72.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:123.00 stddev:13.36 (10.9%) stddev-sans-first:14.09 11:46:10 INFO - PROCESS | 2670 | Values: 121.0 118.0 120.0 128.0 123.0 123.0 117.0 117.0 162.0 123.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.60 Median:156.00 stddev:2.67 (1.7%) stddev-sans-first:2.69 11:46:10 INFO - PROCESS | 2670 | Values: 157.0 156.0 152.0 152.0 158.0 158.0 156.0 153.0 153.0 151.0 11:46:10 INFO - PROCESS | 2670 | 11:46:10 INFO - PROCESS | 2670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.50 stddev:2.07 (3.3%) stddev-sans-first:1.73 11:46:10 INFO - PROCESS | 2670 | Values: 66.0 64.0 64.0 61.0 61.0 63.0 64.0 61.0 60.0 60.0 11:46:10 INFO - PROCESS | 2670 | -------- Summary: end -------- 11:46:10 INFO - PROCESS | 2670 | 11:46:11 INFO - TEST-INFO | 2670: exit 0 11:46:11 INFO - TEST-OK | kraken | took 59500ms 11:46:11 INFO - SUITE-END | took 1172s 11:46:11 INFO - Completed test suite (00:19:32) 11:46:11 INFO - javascript benchmark 11:46:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3118.563401123842, 3136.7647426443023, 3171.8889838855644, 3193.441147799135, 3207.118042538302, 5433.239346491489, 5512.5865560083, 5523.061079616465, 5538.363088244843, 5541.778328866841, 5507.862303442416, 5535.944640553594, 5539.9169012464845, 5543.307086614173, 5574.9721251393685, 4990.925136122952, 5004.574611158056, 5035.194368900977, 5069.493050694938, 5079.796808127689, 4922.433920099181, 4926.261060840867, 5088.821891233808, 5110.182370820651, 5123.20590308501, 4853.733044682541, 4981.352424184851, 4987.900241995158, 5033.0688822567945, 5038.420581633122, 5014.799408023675, 5017.899642007158, 5033.270175824485, 5107.080725469414, 5127.53852153308, 4969.975150124263, 5044.092063428581, 5068.797248110071, 5068.949310506885, 5069.847904562869, 5330.307060082203, 5361.892762144736, 5367.597430192712, 5469.179623056533, 5560.277163968658, 5888.352281249059, 5906.586538942276, 5912.290525136968, 5915.704214789243, 5933.821985340447, 5922.674252916088, 5933.3473317934995, 5943.554233432466, 5954.285485741739, 5956.374580669054, 5361.705106219157, 5407.053765591006, 5469.425710300361, 5530.557555395559, 5536.667799932017, 6026.487748541335, 6114.810722568954, 6117.705611546597, 6119.165849018138, 6119.746775990644, 5869.6771677558145, 5871.972404829138, 5872.060470324727, 5876.7649294028615, 5898.351181370046, 5676.375598684142, 5714.628549144292, 5816.970915145482, 5945.108233764883, 5963.5527335450015, 5731.398203188689, 5798.565107616945, 5808.651480911159, 5810.622309549865, 5834.299884013861, 5818.4763371296785, 5823.301203855564, 5826.679532625746, 5829.7376618052285, 5872.17789509469, 4439.889002774957, 4466.9106617867465, 4532.592066714012, 4533.410656614619, 4543.704659197141, 4024.5975402460103, 4077.347624380085, 4077.408775727503, 4096.078382363944, 4130.855420060284, 5726.627769194989, 5740.770369185186, 5742.28221472316, 5743.54051675865, 5751.338596061423, 5734.741936612862, 5904.114382842608, 5971.880562388728, 5974.9402505974385, 6023.186869772525, 5555.72221388937, 5556.499915007668, 5599.244102046172, 5606.271184745957, 5715.428457154335, 5209.348831396075, 5211.166213405837, 5211.2443695663915, 5214.270002199695, 5234.7644356004075, 6173.567850250529, 6189.907151392733, 6190.1952746142715, 6193.876122477525, 6197.03945888388, 5632.7183640816975, 5638.859028524155, 5841.18223448726, 5911.556633252438, 5968.253968253968, 6156.322804491413, 6202.751889924352, 6206.658633775005, 6210.223722034746, 6210.223722034746, 5734.197212390185, 5833.154192641959, 5852.06366981298, 5853.2976042875125, 5881.529477641864, 5661.037623604079, 5714.771409143587, 5879.588428810113, 5989.1914591529585, 6000.249968753906, 5764.510016648632, 5835.4748072673665, 5835.912461313169, 5868.530517558671, 5871.970640146772, 5830.766769329349, 5833.329167145833, 5838.386969368155, 5839.445252700986, 5844.240248767633, 5574.219609254788, 5576.581756368208, 5812.709364531672, 5827.562932779973, 5829.59192856496, 5797.652140871562, 5853.2976042875125, 5855.707214639337, 5876.853078673067, 5982.46157845796, 5785.508231800344, 5790.855228619318, 5795.33353664334, 5795.797147099831, 5795.7971471], "value": 5458.0781707305005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.565237376715, 628.1488582970076, 652.399792191184, 654.5745265577377, 655.6885479397287, 34786.47820282695, 47403.28895066577, 48529.51470485294, 48546.0, 48596.75701621496, 49180.0, 49625.0, 49777.751111244484, 49835.25247121296, 49935.75032124844, 8416.663333466655, 9225.72322830316, 9403.153716165543, 9404.388714733555, 9413.152816246537, 8364.623591938378, 10842.078423334026, 11142.27575207613, 11150.609728659498, 11456.599019034335, 11392.316461012324, 11448.599299024536, 11456.968872801446, 11461.255018423775, 11465.541378344857, 11130.38782866942, 11175.44122793857, 11183.776324473465, 11193.608223712172, 11199.944000279987, 11052.447377631166, 11134.16493762971, 11134.944325278402, 11139.554417823278, 11143.108551315876, 45454.77272613616, 45692.77153614244, 45724.54275457236, 45740.3138952916, 45768.54231457676, 3816.4466152407745, 3876.8836934891997, 3900.843966241347, 4183.309753890604, 4426.825568150728, 34353.6564634353, 34421.6557834421, 34480.13799655004, 34605.480917786255, 35246.47130293048, 4723.173444647173, 4791.257355109963, 4803.615710743132, 4884.4871288514905, 4912.975435122801, 369981.0, 370723.0, 371871.0, 372116.0, 372718.0, 67424.0, 67814.3218567808, 67918.0, 68044.31955680478, 68087.0, 41427.58572414298, 42253.57746422558, 42387.152256955305, 42790.144197116504, 72346.63826680832, 15014.849851501564, 15045.548633541011, 15068.321925513379, 15081.924590377197, 15084.622884427758, 12636.30500322491, 12840.165389249669, 12907.290099044643, 13095.541656041993, 13233.801492977613, 20597.558170927892, 20689.27587534429, 20737.481562961046, 20835.79164208369, 20943.581128377653, 7355.448341374418, 7405.555666660018, 7412.666430010662, 7507.21174276704, 7548.188164976652, 1061.7823346214007, 1066.4294602387686, 1067.1729409707539, 1086.7174534620897, 1153.066016526616, 33013.0, 34131.4880276796, 34272.82863585716, 34307.485387718705, 34447.48328775071, 1141.9771604567861, 1152.0348981311554, 1182.0484509969385, 1184.9822252666218, 1263.7346157307068, 17295.74056389155, 18312.359067432575, 18401.0, 18439.354622588413, 18530.351437699617, 571006.0, 573895.0, 574405.0, 576788.0, 577684.0, 62458.37541624525, 63125.68437157877, 63186.0, 63241.36758632355, 63253.36746632475, 1331.467413034794, 1366.5968539280937, 1421.2112277685983, 1439.4889814116048, 1459.9270036498344, 32599.0, 34315.313693726486, 34741.47887781635, 34923.47614785831, 34957.47563786495, 55532.0, 56058.439415605324, 56152.71923640519, 56243.0, 56283.0, 430044.0, 431418.0, 432315.0, 433638.0, 814258.0, 27427.31431714223, 31331.530027049157, 31462.370752584364, 31479.213019673774, 33064.0, 50272.497275026784, 50779.23831142462, 54053.45946540484, 54230.45769542254, 54393.45606543884, 43810.78094609507, 45244.321335180655, 45360.31959520544, 46160.0, 47037.0, 46100.538994610964, 46609.3008604878, 46867.489025287185, 47080.7645961768, 47265.29102063403, 43889.78055109704, 45103.77448112739, 45695.77152114218, 45763.08473830438, 45774.313385299916, 37497.4375384364, 39997.60002399939, 40474.0, 40880.0, 41009.38485922654, 29466.558001630423, 30079.849600751855, 30442.695573043988, 30521.694783052775, 30555.388892222476], "value": 21865.544561126142, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.3382174540335, 434.68702534175395, 435.42741295196817, 438.76087532294895, 455.11253056539755, 17960.0, 19369.612607747855, 20005.099770510325, 20031.899840500813, 20281.0873510692, 19187.520311992208, 19298.228070877183, 19407.611847763037, 19879.403617891487, 20113.0, 5623.662580245178, 5885.617434866741, 5898.115282707607, 5898.616589921663, 5903.35063143054, 4354.037082229701, 4634.86095417138, 5843.152742852284, 5928.407159284058, 6598.439132673729, 6677.2988836172235, 6690.531662783583, 6694.765683201089, 6705.2624211336715, 6705.932940670579, 6485.254195767477, 6685.799426017203, 6713.765018224339, 6715.832104197385, 6737.831554211135, 6006.309274433453, 6008.669523176223, 6008.699565021775, 6009.699515024275, 6013.609115407485, 23653.881730591238, 23680.881595592084, 23993.160239391713, 24003.51992960131, 24195.637065443858, 2496.2885577610477, 2526.6336381224805, 2601.0246157690867, 2664.893404263827, 2719.4561087782326, 14821.481248156262, 15023.323950422257, 15134.697306053928, 15192.544223673307, 15710.842891571167, 2039.51051747581, 2041.6427125253022, 2086.2280956046206, 2098.3600001999384, 2117.587070521249, 60502.69748651273, 82634.17365826386, 82943.0, 83466.58266708688, 83871.16128838694, 11315.434228288552, 11340.0, 11461.369624670557, 11752.177347585757, 11897.405129743478, 16574.75137872933, 16648.66702665964, 16655.41706040283, 16666.33334666624, 16667.49997500077, 7934.682612695543, 8216.26053655174, 8273.296769774637, 8330.416870818985, 8431.704890328932, 7982.600869956479, 7990.240927111915, 8124.0, 8179.4274400792565, 8229.835403291901, 6878.3465570770695, 6945.374916257561, 6945.89581156283, 7003.194632617212, 7072.575645461289, 2048.3240530624857, 2079.781622929601, 2112.6725357569603, 2120.6288899442534, 2124.702541644171, 478.77258302306126, 980.3333733061587, 1035.9378437293635, 1047.2407504559285, 1104.8619921480727, 11759.294442333487, 12755.29845858468, 12782.424790884432, 12832.679183020313, 13170.868291317156, 496.3001719112027, 511.7901660319326, 518.4530320511897, 520.5497244883148, 533.4691981478454, 6654.633995130217, 6825.726970921207, 6833.077534532775, 6872.518923675295, 6877.449804015667, 497978.0, 499447.0, 500797.0, 519147.0, 520013.0, 96762.0, 97691.0, 98791.0, 101264.0, 101289.0, 527.6438404077232, 541.1341853035112, 541.2990177719862, 541.3179393963555, 541.6912359954866, 11662.416879155839, 11777.352245626573, 11782.351970641697, 12085.395730213279, 12162.452689629165, 40216.1956760869, 42271.57728422676, 42357.5764242366, 43118.56881431145, 43703.0, 60636.0, 61168.69415652893, 61303.0, 61508.384916152056, 62264.37735622586, 5928.199693041384, 8506.319494440306, 8685.869711954452, 8929.10708929102, 9132.908670913206, 12345.753084938431, 13493.0, 13497.730045399234, 13947.302634868012, 14005.789913151515, 11871.228370155912, 12074.517019319128, 12184.14710970259, 12285.938570307091, 12427.875721243034, 11181.496832642713, 11394.658160255205, 11557.017653499546, 11588.246763960316, 12050.75898482043, 11611.88388116108, 11864.584739534075, 12340.074494413135, 12385.380730963596, 12445.813312800134, 8674.132586741276, 8808.91191088081, 8904.73285801427, 8924.821503570021, 8990.190882820578, 8615.956920215358, 11108.444577770917, 11745.882541174478, 12018.33899135556, 12035.157538972539], "value": 9045.292752774987, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1229.8585662648786, 1538.5768913548777, 1572.0489104092023, 1690.3745614122768, 1836.1828986101182, 7914.60426978651, 8489.575521223936, 8857.158569935862, 8870.733877983655, 9021.188093071622, 8823.558822058887, 8878.334124940622, 8904.465732056082, 8906.198442140207, 8941.865872011926, 2393.4734358441115, 2420.9273721788286, 2423.297243799305, 2423.3941514621347, 2426.1508472034825, 2396.4128788446856, 2412.867292298923, 2425.1875621666763, 2429.842060266086, 2433.0, 2369.57347677418, 2377.4413012941945, 2403.531311394279, 2448.1064411489765, 2448.3879030242438, 2358.41039740065, 2377.072941552796, 2383.940401489968, 2384.0344660412475, 2397.580423425911, 2353.1411034972284, 2373.0270589058405, 2385.0459816073535, 2386.618141097416, 2387.2241521505407, 8729.825403491895, 8809.38334316598, 8839.16027977341, 8895.599698013604, 8948.284137269004, 5302.416734159239, 5511.58663100269, 5514.531264842493, 5518.806841760559, 5520.806771762969, 3438.8452519636676, 3443.8622455101768, 3488.773229740084, 3545.716342692604, 3554.289142171576, 1287.2276634019606, 1288.871112888713, 1294.3075454631726, 1323.2060763541895, 1336.2049580499588, 12246.510139594406, 12333.814992775115, 12354.38228088601, 12370.690732731753, 12381.380930953417, 4444.133393988206, 4657.580817726421, 4724.220503616864, 4800.495947925488, 4803.543663351976, 9811.215102791903, 9832.213422926148, 9846.606135754633, 9856.408615483095, 9856.802863942681, 5245.816396426183, 5246.868828279324, 5250.422453530108, 5251.789928402897, 5255.448177941261, 4438.467383913951, 4557.703749256288, 4932.679375840559, 4944.1100601892, 5039.118154322979, 3925.509311336083, 3929.901752456154, 4052.392141178847, 4057.16828050249, 4088.2028004539425, 1162.5296245478357, 1168.6026750904732, 1169.4503583315827, 1176.3765204441659, 1196.946137423818, 867.6919693508839, 880.0, 954.8970938565967, 982.1455333859445, 1009.8182327181178, 5989.4609485145675, 6264.21697287839, 6379.075034120119, 6461.030845373138, 6550.213974323112, 1030.90206430389, 1046.895310468947, 1051.9211059170593, 1069.8816343639483, 1098.4068602954314, 4158.08522125132, 4314.654827613784, 4458.08609235106, 4468.396766436555, 4468.843590474383, 10764.78470430587, 11182.552697891993, 11223.046041086436, 11231.213815033032, 11270.154738394653, 11221.495032723547, 11221.551137954553, 11221.719457013476, 11240.775184496428, 11241.0, 7976.5612891290375, 8290.253877150964, 8297.709580164665, 8415.116412776693, 8429.114942931026, 6182.227221597301, 6210.3168651449205, 6283.7800676976085, 6358.682065896779, 6573.934260657524, 10165.0, 10187.643432479828, 10294.691159265234, 10315.12321452655, 10387.584496620051, 10399.896001039893, 10468.057874791306, 10548.36709797384, 10580.947095264783, 10607.310524815863, 7913.0, 7995.560244186626, 8010.639521221676, 8073.15231900642, 8081.4747041442115, 8717.782055448664, 8803.29573634095, 8827.382083254111, 8837.734867953972, 8845.823083538422, 7551.282628150317, 7779.57212353326, 7861.52830830152, 7861.764147075597, 7890.329322007694, 7969.80075498094, 7980.600969951363, 7996.400269979891, 8057.194280571896, 8229.95885020571, 7141.750038748619, 7170.892436613351, 7229.819254518679, 7243.782686519413, 7244.673989670372, 5440.075187218262, 5609.242752228397, 5627.690477023639, 5650.63270887391, 5671.234383358194, 5516.586256030733, 5570.498655121059, 5636.0, 5657.915131272952, 5663.716814159193], "value": 4776.200882857817, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.6347489036074, 362.5594902193834, 368.1863082587482, 371.8456840411229, 381.3383779143184, 5273.472652734725, 5447.754851031703, 5479.972600136999, 5529.944700552994, 5540.617534460181, 5213.644452442364, 5366.785328586851, 5379.273798037264, 5385.461453854613, 5405.675659460435, 4331.7834108294455, 4333.826646934119, 4338.327559228324, 4343.478782546099, 4346.97826510867, 4216.451861258032, 4331.870043898688, 4333.826646934119, 4351.499577548575, 4374.0, 4279.828806847722, 4296.785160741966, 4298.570142985692, 4302.8709138725735, 4309.37514060461, 4271.765052922088, 4288.31386978082, 4293.5401963332615, 4296.677749168824, 4305.115572566372, 3704.6480584344445, 3810.047488127968, 3834.2523207974455, 3841.9807900960595, 3865.265599536079, 5306.442823503515, 5312.203169524563, 5314.681119132864, 5327.840164795062, 5340.145576707708, 4904.313396124546, 4904.754762261909, 4917.901641967176, 4924.975375123137, 4926.679765815246, 5326.322208504347, 5664.0, 5682.17608446777, 5705.5435565154685, 5714.685692286964, 4198.160367926427, 4204.327307630764, 4215.894602634928, 4216.304309788881, 4216.852410165629, 5930.673812940287, 5952.255968003999, 5957.7319020643745, 5966.850828729273, 5997.340292567814, 4080.6123418275215, 4261.0, 4282.650964946032, 4299.161663475655, 4299.226139294957, 4329.766016685229, 4337.544557821447, 4341.696081274281, 4360.367746676714, 4361.847335343247, 4917.950820491749, 4936.6297527685565, 4940.8764780880765, 4949.133901567283, 4952.2324039773175, 5494.230807686927, 5719.685417302005, 5749.7700091996685, 5758.337791154071, 5762.394948530427, 1395.9162450253018, 1427.4432971141264, 1454.789055586955, 1475.3950880139091, 1488.8238291749396, 1008.5864795433837, 1035.5961175141701, 1042.607216438276, 1056.2236755984345, 1057.180684969155, 5514.696691681996, 5517.475839795213, 5517.586181036439, 5520.3651580068, 5532.889342213133, 5754.482096611325, 5773.766690653248, 5789.45000224978, 5807.912881306784, 5828.358880523139, 5445.673259604437, 5556.110922286442, 5570.805021824217, 5670.419958918682, 5716.314042314865, 3077.38452309539, 3115.2679120406688, 3115.6728543502604, 3138.184072141214, 3141.2303985523695, 5937.526546970071, 6108.1143234230785, 6131.8160455185525, 6142.754289828446, 6153.7230824613, 5976.701164941735, 5991.460768530853, 6006.519478441714, 6016.488598469179, 6039.969800151059, 6155.230596175478, 6165.1985241919165, 6187.474064704551, 6198.318184999736, 6218.0, 5698.487136157766, 5915.852103697442, 5932.2584676915385, 5960.910586341295, 5987.5808693391045, 5812.389699081536, 5856.150858125463, 5856.267966504187, 5873.622666966426, 5883.411658834082, 5936.495397891227, 6168.414000669929, 6189.752409903734, 6202.968985155046, 6216.487684458267, 4316.266234740039, 4332.941852619981, 4343.087951530088, 4344.608985191221, 4352.801835073267, 5675.914861277167, 5837.912431313449, 5981.7308221131025, 5994.291626886316, 5996.68073023934, 5624.6625202487985, 5650.773969041193, 5665.518430933252, 5776.220210271559, 5814.5639077068545, 5687.175359572756, 5719.5138413235345, 5736.684482353344, 5802.332731735735, 5807.216025836458, 5589.189567512606, 5709.3719690834805, 5711.828645140652, 5735.075111692983, 5739.684317362585, 5750.827475175752, 5766.59633825645, 5793.826185214451, 5799.652020878761, 5809.978811018163, 5380.0, 5655.88688226225, 5660.603757736919, 5686.488216060575, 5697.7720891165545], "value": 4419.206498250956, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [840.4453060979753, 848.6902280667558, 853.6244052616852, 869.3349587565513, 902.2195800632446, 17212.0, 17997.820021799795, 18012.279508819633, 18027.36904208353, 18107.094645267734, 16089.597760056004, 16290.0, 16303.0, 16307.429239976573, 16320.102394368305, 722.9457790665695, 737.7786664000805, 869.3523325122777, 874.2961915657893, 895.0646574329809, 907.9773005674845, 911.2209061252632, 911.3256190419075, 911.4850109688039, 911.8677791720197, 947.3510645208055, 948.5352177433043, 949.2548349545608, 949.5964215208543, 949.7293271417648, 940.1773448232797, 950.2208189284789, 950.7147855643314, 950.7908260182784, 951.3055469507264, 689.3692271571556, 689.5793565924782, 693.6358411833828, 694.4062826283505, 695.0720787748362, 16302.918485407616, 16426.342946282133, 16445.0, 16466.42367517131, 16484.175791210393, 15882.76175857363, 16384.50846474596, 16647.173489278775, 16788.160591970474, 17692.469225923112, 13729.278044835813, 13880.722385552333, 13966.86033139676, 14045.0, 14192.716145677132, 4106.486689163855, 4146.5853414658295, 4212.978935105336, 4222.831086756526, 4238.08881090564, 84196.0, 84989.57505212496, 85034.14965850324, 85999.0, 87109.56445217734, 12737.936310318575, 12866.0, 12896.161749486253, 13011.414486348138, 13147.53983610569, 22934.65598016031, 23038.308850734506, 23137.652935205984, 23168.65247021296, 23314.067437302318, 8696.13038696138, 8703.347248956354, 8779.12208779117, 8897.421667591585, 9269.212116969999, 5058.494150584986, 5127.589792816566, 5192.948070519322, 5263.6841789492755, 5301.867453313621, 3589.4974703541525, 3715.5727091384297, 3717.20080182762, 3720.813959302024, 3724.7020238380865, 619.5229673151648, 622.4553515673786, 625.1622825413967, 630.4735545819201, 636.926753423353, 635.2028204603193, 635.6345101566618, 687.8211664967145, 757.8294883651134, 761.162721006888, 9421.952890235641, 10157.542910568895, 10324.690259292232, 10449.895501044892, 10561.894381056245, 672.4889084295872, 739.3161325773638, 750.0998801438296, 754.4115589839934, 776.7203806629609, 5144.4855514449, 5213.765380557884, 5218.739063046833, 5228.08508511009, 5257.8159764408065, 68835.65582172057, 69308.6534567324, 104301.47849260857, 104962.0, 107597.0, 58619.12071318934, 58876.0, 106842.0, 107811.46094269479, 112116.0, 56941.0, 57160.141387981654, 83361.58319208365, 92256.0, 93978.0, 10823.51294191779, 11231.71920701989, 11310.60412885545, 11508.88491115078, 11559.190856639954, 51286.743566281926, 57544.4245557539, 57854.421455784905, 58337.4166258349, 59243.03784810828, 49592.256116159006, 51613.741931290104, 52554.73722631362, 63548.364516354246, 77920.0, 13625.863741362857, 14065.226412547, 14581.343839527035, 14728.852711472748, 14991.475298364941, 16171.919140404694, 16183.75724364159, 16237.431689890796, 16654.16729163561, 17192.398266060627, 14226.928865356022, 14848.851511484747, 15192.848071519144, 15260.847391526388, 15428.382864685287, 15002.249887505362, 15306.311215995529, 15585.844141558438, 15974.121423321829, 16496.505104846874, 13259.668508286984, 14184.0, 14434.566962991126, 14461.566153015427, 14916.030458020194, 11775.646730598095, 12432.81350779721, 12712.173708708904, 12761.106722529334, 13057.34713264352, 12090.697732556757, 12117.422268542923, 12380.380980950737, 12423.130380873614, 12445.502179912703], "value": 8048.591029729778, "unit": "score"}], "name": "dromaeo_css", "value": 7537.64567704077, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 109.0, 109.0, 110.0, 109.0, 110.0, 111.0, 111.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 116.0, 156.0, 118.0, 120.0, 118.0, 116.0, 140.0, 117.0], "value": 125.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 160.0, 161.0, 160.0, 161.0, 159.0, 160.0, 161.0, 159.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 115.0, 76.0, 76.0, 75.0, 76.0, 99.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 124.0, 81.0, 82.0, 81.0, 110.0, 81.0, 82.0, 107.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 53.0, 50.0, 50.0, 53.0, 51.0, 50.0, 53.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 60.0, 61.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 73.0, 72.0, 74.0, 73.0, 73.0, 74.0, 93.0, 72.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 118.0, 120.0, 128.0, 123.0, 123.0, 117.0, 117.0, 162.0, 123.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 152.0, 152.0, 158.0, 158.0, 156.0, 153.0, 153.0, 151.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 61.0, 61.0, 63.0, 64.0, 61.0, 60.0, 60.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1453.4, "alertThreshold": 2.0}]} 11:46:11 INFO - Return code: 0 11:46:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:46:11 INFO - # TBPL SUCCESS # 11:46:11 INFO - Running post-action listener: _resource_record_post_action 11:46:11 INFO - [mozharness: 2016-11-14 19:46:11.623258Z] Finished run-tests step (success) 11:46:11 INFO - Running post-run listener: _resource_record_post_run 11:46:11 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 320372736; Read time: 0; Write time: 213852 11:46:11 INFO - TinderboxPrint: CPU usage
12.9% 11:46:11 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 11:46:11 INFO - TinderboxPrint: I/O write bytes / time
320,372,736 / 213,852 11:46:11 INFO - TinderboxPrint: CPU idle
8,246.8 (87.1%) 11:46:11 INFO - TinderboxPrint: CPU user
1,178.9 (12.5%) 11:46:11 INFO - TinderboxPrint: Swap in / out
0 / 0 11:46:11 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 100573184; Read time: 0; Write time: 81876 11:46:11 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 219799552; Read time: 0; Write time: 131976 11:46:12 INFO - Running post-run listener: _upload_blobber_files 11:46:12 INFO - Blob upload gear active. 11:46:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:46:12 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 11:46:12 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'] 11:46:12 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 11:46:12 INFO - (blobuploader) - INFO - Open directory for files ... 11:46:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:46:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:46:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:13 INFO - (blobuploader) - INFO - Done attempting. 11:46:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:46:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:46:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:14 INFO - (blobuploader) - INFO - Done attempting. 11:46:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:46:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:46:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:19 INFO - (blobuploader) - INFO - Done attempting. 11:46:19 INFO - (blobuploader) - INFO - Iteration through files over. 11:46:19 INFO - Return code: 0 11:46:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:46:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:46:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e0297299fb2be4d561894209a0c7a2c391dbcce76c678350adffd68ffefd97fb2aa10c7ae88b84d17fcd3e2fc865a4f5f08ba65c2c2366edbe2c23ce2b08d102", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/396ba3757df8c91b9fe7ad5cc074d280826cc45cd5f98bf747396ec519d7e8f0cfc8d2e0989373286cada7baedbe7ac243875e333e242fc6b6a189090ddcc4c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/571b52290ac4ef6dca144b7e7f2e6a1098a5b67319fb23697f16ea5de616706209776e6a1e921802d05f18df040467d8e314c7664e0464183c4459360603f899"} 11:46:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:46:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:46:19 INFO - Contents: 11:46:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e0297299fb2be4d561894209a0c7a2c391dbcce76c678350adffd68ffefd97fb2aa10c7ae88b84d17fcd3e2fc865a4f5f08ba65c2c2366edbe2c23ce2b08d102", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/396ba3757df8c91b9fe7ad5cc074d280826cc45cd5f98bf747396ec519d7e8f0cfc8d2e0989373286cada7baedbe7ac243875e333e242fc6b6a189090ddcc4c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/571b52290ac4ef6dca144b7e7f2e6a1098a5b67319fb23697f16ea5de616706209776e6a1e921802d05f18df040467d8e314c7664e0464183c4459360603f899"} 11:46:19 INFO - Running post-run listener: copy_logs_to_upload_dir 11:46:19 INFO - Copying logs to upload dir... 11:46:19 INFO - mkdir: /builds/slave/test/build/upload/logs 11:46:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.033286 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-11-14 11:46:19.302348) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 11:46:19.305857) ========= 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=c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744 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/e0297299fb2be4d561894209a0c7a2c391dbcce76c678350adffd68ffefd97fb2aa10c7ae88b84d17fcd3e2fc865a4f5f08ba65c2c2366edbe2c23ce2b08d102", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/396ba3757df8c91b9fe7ad5cc074d280826cc45cd5f98bf747396ec519d7e8f0cfc8d2e0989373286cada7baedbe7ac243875e333e242fc6b6a189090ddcc4c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/571b52290ac4ef6dca144b7e7f2e6a1098a5b67319fb23697f16ea5de616706209776e6a1e921802d05f18df040467d8e314c7664e0464183c4459360603f899"} build_url:https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011861 build_url: 'https://queue.taskcluster.net/v1/task/Z-RTEPmhTzKvbPk61W61hA/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/e0297299fb2be4d561894209a0c7a2c391dbcce76c678350adffd68ffefd97fb2aa10c7ae88b84d17fcd3e2fc865a4f5f08ba65c2c2366edbe2c23ce2b08d102", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/396ba3757df8c91b9fe7ad5cc074d280826cc45cd5f98bf747396ec519d7e8f0cfc8d2e0989373286cada7baedbe7ac243875e333e242fc6b6a189090ddcc4c6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/571b52290ac4ef6dca144b7e7f2e6a1098a5b67319fb23697f16ea5de616706209776e6a1e921802d05f18df040467d8e314c7664e0464183c4459360603f899"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 11:46:19.330804) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:46:19.331085) ========= 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=c7c495d6f76fb52e1ad9f659000001da-1479151546.883463-1069378744 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:46:19.416010) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 11:46:19.416296) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 11:46:19.416561) ========= ========= Total master_lag: 0.16 =========