builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0046 starttime: 1470806641.06 results: success (0) buildid: 20160809204915 builduid: c8ec8e68917f41cc914ddc85631fc167 revision: 7bab284d79100a759b56b98169232d8597cc7add ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:01.062617) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:01.062984) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:01.063286) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:01.154601) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:01.154949) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jF6SJq04c4/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-09 22:24:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.74M=0.001s 2016-08-09 22:24:01 (9.74 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093044 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:01.270941) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:01.271276) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jF6SJq04c4/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.027445 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:01.320198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 22:24:01.320566) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 7bab284d79100a759b56b98169232d8597cc7add --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 7bab284d79100a759b56b98169232d8597cc7add --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jF6SJq04c4/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-09 22:24:01,399 truncating revision to first 12 chars 2016-08-09 22:24:01,399 Setting DEBUG logging. 2016-08-09 22:24:01,399 attempt 1/10 2016-08-09 22:24:01,399 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/7bab284d7910?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 22:24:02,479 unpacking tar archive at: autoland-7bab284d7910/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330257 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 22:24:02.780068) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:02.780405) ========= script_repo_revision: 7bab284d79100a759b56b98169232d8597cc7add ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:24:02.780811) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-08-09 22:24:02.781070) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-08-09 22:24:04.880372) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-09 22:24:04.880772) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jF6SJq04c4/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:24:05 INFO - MultiFileLogger online at 20160809 22:24:05 in /builds/slave/test 22:24:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:24:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:24:05 INFO - {'append_to_log': False, 22:24:05 INFO - 'base_work_dir': '/builds/slave/test', 22:24:05 INFO - 'blob_upload_branch': 'Autoland', 22:24:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:24:05 INFO - 'branch': 'Autoland', 22:24:05 INFO - 'buildbot_json_path': 'buildprops.json', 22:24:05 INFO - 'config_files': ('talos/mac_config.py',), 22:24:05 INFO - 'default_actions': ('clobber', 22:24:05 INFO - 'read-buildbot-config', 22:24:05 INFO - 'download-and-extract', 22:24:05 INFO - 'populate-webroot', 22:24:05 INFO - 'create-virtualenv', 22:24:05 INFO - 'install', 22:24:05 INFO - 'run-tests'), 22:24:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:24:05 INFO - 'download_minidump_stackwalk': True, 22:24:05 INFO - 'download_symbols': 'ondemand', 22:24:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:24:05 INFO - 'tooltool.py': '/tools/tooltool.py', 22:24:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:24:05 INFO - '/tools/misc-python/virtualenv.py')}, 22:24:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:24:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:24:05 INFO - 'installer_path': 'installer.exe', 22:24:05 INFO - 'log_level': 'info', 22:24:05 INFO - 'log_name': 'talos', 22:24:05 INFO - 'log_to_console': True, 22:24:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:24:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:24:05 INFO - 'opt_config_files': (), 22:24:05 INFO - 'pip_index': False, 22:24:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:24:05 INFO - 'cmd': ('bash', 22:24:05 INFO - '-c', 22:24:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:24:05 INFO - 'enabled': True, 22:24:05 INFO - 'halt_on_failure': False, 22:24:05 INFO - 'name': 'check_screen_resolution'},), 22:24:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:24:05 INFO - 'cmd': ('bash', 22:24:05 INFO - '-c', 22:24:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:24:05 INFO - 'enabled': True, 22:24:05 INFO - 'halt_on_failure': False, 22:24:05 INFO - 'name': 'check_screen_resolution'},), 22:24:05 INFO - 'run_cmd_checks_enabled': True, 22:24:05 INFO - 'sps_profile': False, 22:24:05 INFO - 'sps_profile_interval': 0, 22:24:05 INFO - 'suite': 'dromaeojs-e10s', 22:24:05 INFO - 'system_bits': '32', 22:24:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:24:05 INFO - 'title': 't-yosemite-r7-0046', 22:24:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:24:05 INFO - 'use_talos_json': True, 22:24:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:24:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:24:05 INFO - 'work_dir': 'build'} 22:24:05 INFO - [mozharness: 2016-08-10 05:24:05.087906Z] Running clobber step. 22:24:05 INFO - Running pre-action listener: _resource_record_pre_action 22:24:05 INFO - Running main action method: clobber 22:24:05 INFO - rmtree: /builds/slave/test/build 22:24:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:24:07 INFO - Running post-action listener: _resource_record_post_action 22:24:07 INFO - [mozharness: 2016-08-10 05:24:07.194979Z] Finished clobber step (success) 22:24:07 INFO - [mozharness: 2016-08-10 05:24:07.195187Z] Running read-buildbot-config step. 22:24:07 INFO - Running pre-action listener: _resource_record_pre_action 22:24:07 INFO - Running main action method: read_buildbot_config 22:24:07 INFO - Using buildbot properties: 22:24:07 INFO - { 22:24:07 INFO - "project": "", 22:24:07 INFO - "product": "firefox", 22:24:07 INFO - "script_repo_revision": "production", 22:24:07 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 22:24:07 INFO - "repository": "", 22:24:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 22:24:07 INFO - "buildid": "20160809204915", 22:24:07 INFO - "pgo_build": "False", 22:24:07 INFO - "basedir": "/builds/slave/test", 22:24:07 INFO - "buildnumber": 127, 22:24:07 INFO - "slavename": "t-yosemite-r7-0046", 22:24:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:24:07 INFO - "platform": "yosemite_r7", 22:24:07 INFO - "scheduler": "tests-autoland-macosx64-talos", 22:24:07 INFO - "branch": "autoland", 22:24:07 INFO - "repo_path": "integration/autoland", 22:24:07 INFO - "revision": "7bab284d79100a759b56b98169232d8597cc7add", 22:24:07 INFO - "stage_platform": "macosx64", 22:24:07 INFO - "builduid": "c8ec8e68917f41cc914ddc85631fc167", 22:24:07 INFO - "slavebuilddir": "test" 22:24:07 INFO - } 22:24:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 22:24:07 INFO - Running post-action listener: _resource_record_post_action 22:24:07 INFO - [mozharness: 2016-08-10 05:24:07.200316Z] Finished read-buildbot-config step (success) 22:24:07 INFO - [mozharness: 2016-08-10 05:24:07.200438Z] Running download-and-extract step. 22:24:07 INFO - Running pre-action listener: _resource_record_pre_action 22:24:07 INFO - Running main action method: download_and_extract 22:24:07 INFO - mkdir: /builds/slave/test/build/tests 22:24:07 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')]} 22:24:07 INFO - trying https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 22:24:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:24:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 22:24:08 INFO - Downloaded 1199 bytes. 22:24:08 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:24:08 INFO - Using the following test package requirements: 22:24:08 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:24:08 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:24:08 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 22:24:08 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:24:08 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 22:24:08 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:24:08 INFO - u'jsshell-mac.zip'], 22:24:08 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:24:08 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 22:24:08 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:24:08 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:24:08 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 22:24:08 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:24:08 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 22:24:08 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:24:08 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 22:24:08 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:24:08 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 22:24:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:24: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')]} 22:24:08 INFO - trying https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:24:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:24:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:24:09 INFO - Downloaded 16326306 bytes. 22:24:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:24:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:24:12 INFO - Return code: 0 22:24:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:12 INFO - trying https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:24:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:24:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:24:13 INFO - Downloaded 16326306 bytes. 22:24:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:24:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:24:17 INFO - Return code: 0 22:24: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')]} 22:24:17 INFO - trying https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:24:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:24:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:24:18 INFO - Downloaded 14413723 bytes. 22:24:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:24:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:24:18 INFO - Return code: 0 22:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:18 INFO - trying https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:24:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 22:24:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 22:24:21 INFO - Downloaded 110907816 bytes. 22:24:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:24:21 INFO - mkdir: /builds/slave/test/properties 22:24:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:24:21 INFO - Writing to file /builds/slave/test/properties/build_url 22:24:21 INFO - Contents: 22:24:21 INFO - build_url:https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:24:22 INFO - Running post-action listener: _resource_record_post_action 22:24:22 INFO - Running post-action listener: set_extra_try_arguments 22:24:22 INFO - [mozharness: 2016-08-10 05:24:22.215975Z] Finished download-and-extract step (success) 22:24:22 INFO - [mozharness: 2016-08-10 05:24:22.216083Z] Running populate-webroot step. 22:24:22 INFO - Running pre-action listener: _resource_record_pre_action 22:24:22 INFO - Running main action method: populate_webroot 22:24:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:24:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:24:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:24:22 INFO - '--tppagecycles', 22:24:22 INFO - '7'], 22:24:22 INFO - 'tests': ['tsvgm']}}, 22:24:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:24:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:24:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:24:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:24:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:22 INFO - 'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:24:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:24:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:22 INFO - 'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['damp', 'tps']}, 22:24:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:22 INFO - 'tests': ['damp', 'tps']}, 22:24:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['dromaeo_dom']}, 22:24:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:24:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['basic_compositor_video']}, 22:24:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:24:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['a11yr', 22:24:22 INFO - 'ts_paint', 22:24:22 INFO - 'tpaint', 22:24:22 INFO - 'sessionrestore', 22:24:22 INFO - 'sessionrestore_no_auto_restore', 22:24:22 INFO - 'tabpaint']}, 22:24:22 INFO - 'other-e10s': {'tests': ['a11yr', 22:24:22 INFO - 'ts_paint', 22:24:22 INFO - 'tpaint', 22:24:22 INFO - 'sessionrestore', 22:24:22 INFO - 'sessionrestore_no_auto_restore', 22:24:22 INFO - 'tabpaint']}, 22:24:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:24:22 INFO - 'ts_paint', 22:24:22 INFO - 'tpaint', 22:24:22 INFO - 'sessionrestore', 22:24:22 INFO - 'sessionrestore_no_auto_restore', 22:24:22 INFO - 'tabpaint']}, 22:24:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:24:22 INFO - 'ts_paint', 22:24:22 INFO - 'tpaint', 22:24:22 INFO - 'sessionrestore', 22:24:22 INFO - 'sessionrestore_no_auto_restore', 22:24:22 INFO - 'tabpaint']}, 22:24:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['a11yr', 22:24:22 INFO - 'ts_paint', 22:24:22 INFO - 'tpaint', 22:24:22 INFO - 'sessionrestore', 22:24:22 INFO - 'sessionrestore_no_auto_restore', 22:24:22 INFO - 'tabpaint']}, 22:24:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['a11yr', 22:24:22 INFO - 'ts_paint', 22:24:22 INFO - 'tpaint', 22:24:22 INFO - 'sessionrestore', 22:24:22 INFO - 'sessionrestore_no_auto_restore', 22:24:22 INFO - 'tabpaint']}, 22:24:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['tsvgx', 22:24:22 INFO - 'tsvgr_opacity', 22:24:22 INFO - 'tart', 22:24:22 INFO - 'tscrollx', 22:24:22 INFO - 'cart']}, 22:24:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:24:22 INFO - 'tsvgr_opacity', 22:24:22 INFO - 'tart', 22:24:22 INFO - 'tscrollx', 22:24:22 INFO - 'cart']}, 22:24:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:22 INFO - 'talos_options': ['--disable-e10s'], 22:24:22 INFO - 'tests': ['tp5o']}, 22:24:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:22 INFO - 'tests': ['tp5o']}, 22:24:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:22 INFO - 'talos_options': ['--disable-e10s', 22:24:22 INFO - '--xperf_path', 22:24:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:24:22 INFO - 'tests': ['tp5n']}, 22:24:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:22 INFO - 'talos_options': ['--xperf_path', 22:24:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:24:22 INFO - 'tests': ['tp5n']}}, 22:24:22 INFO - 'talos.zip': {'path': '', 22:24:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:24:22 INFO - Running post-action listener: _resource_record_post_action 22:24:22 INFO - [mozharness: 2016-08-10 05:24:22.235284Z] Finished populate-webroot step (success) 22:24:22 INFO - [mozharness: 2016-08-10 05:24:22.235424Z] Running create-virtualenv step. 22:24:22 INFO - Running pre-action listener: _resource_record_pre_action 22:24:22 INFO - Running main action method: create_virtualenv 22:24:22 INFO - Creating virtualenv /builds/slave/test/build/venv 22:24:22 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 22:24:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:24:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:24:22 INFO - Using real prefix '/tools/python27' 22:24:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:24:23 INFO - Installing distribute.............................................................................................................................................................................................done. 22:24:25 INFO - Installing pip.................done. 22:24:25 INFO - Return code: 0 22:24:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:25 INFO - 'HOME': '/Users/cltbld', 22:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:25 INFO - 'LOGNAME': 'cltbld', 22:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:25 INFO - 'MOZ_NO_REMOTE': '1', 22:24:25 INFO - 'NO_EM_RESTART': '1', 22:24:25 INFO - 'PAGER': '/bin/cat', 22:24:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:25 INFO - 'PWD': '/builds/slave/test', 22:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:25 INFO - 'SHELL': '/bin/bash', 22:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:25 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:25 INFO - 'USER': 'cltbld', 22:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:25 INFO - 'XPC_FLAGS': '0x0', 22:24:25 INFO - 'XPC_SERVICE_NAME': '0', 22:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:24:26 INFO - Cleaning up... 22:24:26 INFO - Return code: 0 22:24:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:24:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:26 INFO - 'HOME': '/Users/cltbld', 22:24:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:26 INFO - 'LOGNAME': 'cltbld', 22:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:26 INFO - 'MOZ_NO_REMOTE': '1', 22:24:26 INFO - 'NO_EM_RESTART': '1', 22:24:26 INFO - 'PAGER': '/bin/cat', 22:24:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:26 INFO - 'PWD': '/builds/slave/test', 22:24:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:26 INFO - 'SHELL': '/bin/bash', 22:24:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:26 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:26 INFO - 'USER': 'cltbld', 22:24:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:26 INFO - 'XPC_FLAGS': '0x0', 22:24:26 INFO - 'XPC_SERVICE_NAME': '0', 22:24:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:26 INFO - Downloading/unpacking psutil>=3.1.1 22:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:24:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:24:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:24:29 INFO - Installing collected packages: psutil 22:24:29 INFO - Running setup.py install for psutil 22:24:29 INFO - building 'psutil._psutil_osx' extension 22:24:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:24:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:24:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:24:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:24:30 INFO - building 'psutil._psutil_posix' extension 22:24:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:24:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:24:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:24:30 INFO - ^ 22:24:30 INFO - 1 warning generated. 22:24:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:24:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:24:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:24:31 INFO - Successfully installed psutil 22:24:31 INFO - Cleaning up... 22:24:31 INFO - Return code: 0 22:24:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:31 INFO - 'HOME': '/Users/cltbld', 22:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:31 INFO - 'LOGNAME': 'cltbld', 22:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:31 INFO - 'MOZ_NO_REMOTE': '1', 22:24:31 INFO - 'NO_EM_RESTART': '1', 22:24:31 INFO - 'PAGER': '/bin/cat', 22:24:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:31 INFO - 'PWD': '/builds/slave/test', 22:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:31 INFO - 'SHELL': '/bin/bash', 22:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:31 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:31 INFO - 'USER': 'cltbld', 22:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:31 INFO - 'XPC_FLAGS': '0x0', 22:24:31 INFO - 'XPC_SERVICE_NAME': '0', 22:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:24:33 INFO - Installing collected packages: mozsystemmonitor 22:24:33 INFO - Running setup.py install for mozsystemmonitor 22:24:34 INFO - Successfully installed mozsystemmonitor 22:24:34 INFO - Cleaning up... 22:24:34 INFO - Return code: 0 22:24:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:34 INFO - 'HOME': '/Users/cltbld', 22:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:34 INFO - 'LOGNAME': 'cltbld', 22:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:34 INFO - 'MOZ_NO_REMOTE': '1', 22:24:34 INFO - 'NO_EM_RESTART': '1', 22:24:34 INFO - 'PAGER': '/bin/cat', 22:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:34 INFO - 'PWD': '/builds/slave/test', 22:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:34 INFO - 'SHELL': '/bin/bash', 22:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:34 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:34 INFO - 'USER': 'cltbld', 22:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:34 INFO - 'XPC_FLAGS': '0x0', 22:24:34 INFO - 'XPC_SERVICE_NAME': '0', 22:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:34 INFO - Downloading/unpacking jsonschema==2.5.1 22:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:24:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:37 INFO - Downloading functools32-3.2.3-2.tar.gz 22:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:24:37 INFO - warning: no files found matching '*.txt' 22:24:37 INFO - no previously-included directories found matching 'build' 22:24:37 INFO - no previously-included directories found matching 'dist' 22:24:37 INFO - no previously-included directories found matching '.git*' 22:24:37 INFO - Installing collected packages: jsonschema, functools32 22:24:37 INFO - Running setup.py install for functools32 22:24:37 INFO - warning: no files found matching '*.txt' 22:24:37 INFO - no previously-included directories found matching 'build' 22:24:37 INFO - no previously-included directories found matching 'dist' 22:24:37 INFO - no previously-included directories found matching '.git*' 22:24:37 INFO - Successfully installed jsonschema functools32 22:24:37 INFO - Cleaning up... 22:24:37 INFO - Return code: 0 22:24:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:24:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:37 INFO - 'HOME': '/Users/cltbld', 22:24:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:37 INFO - 'LOGNAME': 'cltbld', 22:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:37 INFO - 'MOZ_NO_REMOTE': '1', 22:24:37 INFO - 'NO_EM_RESTART': '1', 22:24:37 INFO - 'PAGER': '/bin/cat', 22:24:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:37 INFO - 'PWD': '/builds/slave/test', 22:24:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:37 INFO - 'SHELL': '/bin/bash', 22:24:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:37 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:37 INFO - 'USER': 'cltbld', 22:24:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:37 INFO - 'XPC_FLAGS': '0x0', 22:24:37 INFO - 'XPC_SERVICE_NAME': '0', 22:24:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:24:37 INFO - Cleaning up... 22:24:37 INFO - Return code: 0 22:24:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:24:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:37 INFO - 'HOME': '/Users/cltbld', 22:24:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:37 INFO - 'LOGNAME': 'cltbld', 22:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:37 INFO - 'MOZ_NO_REMOTE': '1', 22:24:37 INFO - 'NO_EM_RESTART': '1', 22:24:37 INFO - 'PAGER': '/bin/cat', 22:24:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:37 INFO - 'PWD': '/builds/slave/test', 22:24:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:37 INFO - 'SHELL': '/bin/bash', 22:24:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:37 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:37 INFO - 'USER': 'cltbld', 22:24:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:37 INFO - 'XPC_FLAGS': '0x0', 22:24:37 INFO - 'XPC_SERVICE_NAME': '0', 22:24:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:38 INFO - Downloading/unpacking blobuploader==1.2.4 22:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:40 INFO - Downloading blobuploader-1.2.4.tar.gz 22:24:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:24:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:24:40 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 22:24:40 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 22:24:40 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 22:24:40 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 22:24:40 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 22:24:40 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 22:24:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:24:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:24:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:24:41 INFO - Downloading docopt-0.6.1.tar.gz 22:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:24:41 INFO - Installing collected packages: blobuploader, requests, docopt 22:24:41 INFO - Running setup.py install for blobuploader 22:24:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:24:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:24:41 INFO - Running setup.py install for requests 22:24:42 INFO - Running setup.py install for docopt 22:24:42 INFO - Successfully installed blobuploader requests docopt 22:24:42 INFO - Cleaning up... 22:24:42 INFO - Return code: 0 22:24:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:42 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')]} 22:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:42 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')]} 22:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:42 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 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:42 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 22:24:42 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 22:24:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:42 INFO - 'HOME': '/Users/cltbld', 22:24:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:42 INFO - 'LOGNAME': 'cltbld', 22:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:42 INFO - 'MOZ_NO_REMOTE': '1', 22:24:42 INFO - 'NO_EM_RESTART': '1', 22:24:42 INFO - 'PAGER': '/bin/cat', 22:24:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:42 INFO - 'PWD': '/builds/slave/test', 22:24:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:42 INFO - 'SHELL': '/bin/bash', 22:24:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:42 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:42 INFO - 'USER': 'cltbld', 22:24:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:42 INFO - 'XPC_FLAGS': '0x0', 22:24:42 INFO - 'XPC_SERVICE_NAME': '0', 22:24:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:42 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-Y3NWd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:42 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-E5hgKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-7_xIcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-14UVIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-ItqdSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-hltLzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-2ezQmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-WGrXkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-Lyprog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-hSA7Po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-w8JMf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-MZ5JaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-XmFBjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-ET6PTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-qjHoYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-scTYM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-p3trX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:24:44 INFO - Running setup.py install for manifestparser 22:24:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:24:44 INFO - Running setup.py install for mozcrash 22:24:44 INFO - Running setup.py install for mozdebug 22:24:45 INFO - Running setup.py install for mozdevice 22:24:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:24:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:24:45 INFO - Running setup.py install for mozfile 22:24:45 INFO - Running setup.py install for mozhttpd 22:24:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:24:45 INFO - Running setup.py install for mozinfo 22:24:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:24:45 INFO - Running setup.py install for mozInstall 22:24:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:24:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:24:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:24:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:24:45 INFO - Running setup.py install for mozleak 22:24:45 INFO - Running setup.py install for mozlog 22:24:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:24:46 INFO - Running setup.py install for moznetwork 22:24:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:24:46 INFO - Running setup.py install for mozprocess 22:24:46 INFO - Running setup.py install for mozprofile 22:24:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:24:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:24:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:24:46 INFO - Running setup.py install for mozrunner 22:24:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:24:46 INFO - Running setup.py install for mozscreenshot 22:24:46 INFO - Running setup.py install for moztest 22:24:47 INFO - Running setup.py install for mozversion 22:24:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:24:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:24:47 INFO - Cleaning up... 22:24:47 INFO - Return code: 0 22:24:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:47 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 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:47 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 22:24:47 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 22:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:47 INFO - 'HOME': '/Users/cltbld', 22:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:47 INFO - 'LOGNAME': 'cltbld', 22:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:47 INFO - 'MOZ_NO_REMOTE': '1', 22:24:47 INFO - 'NO_EM_RESTART': '1', 22:24:47 INFO - 'PAGER': '/bin/cat', 22:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:47 INFO - 'PWD': '/builds/slave/test', 22:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:47 INFO - 'SHELL': '/bin/bash', 22:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:47 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:47 INFO - 'USER': 'cltbld', 22:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:47 INFO - 'XPC_FLAGS': '0x0', 22:24:47 INFO - 'XPC_SERVICE_NAME': '0', 22:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:24:47 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-vLegxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:24:47 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)) 22:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:24:47 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-bexCl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:24:47 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)) 22:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:24:47 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-AxAkak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:24:47 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)) 22:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:24:47 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-9PjgVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:24:48 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-8K4SG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:24:48 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-GEU_qX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:24:48 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-nyjSY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:24:48 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-4EjL1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:24:48 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-CHLtz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:24:48 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-vLLEOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:24:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-giQsHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:24:48 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-e_4nyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:24:48 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-tZwihR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:24:48 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)) 22:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:24:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-mlR7DM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:24:49 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)) 22:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-Isny6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:24:49 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)) 22:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:24:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-o6AS4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:24:49 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)) 22:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:24:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-DhLnlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:24:49 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)) 22:24:49 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)) 22:24:49 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)) 22:24:49 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)) 22:24:49 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)) 22:24:49 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)) 22:24:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:24:49 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 22:24:49 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 22:24:49 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 22:24:49 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 22:24:49 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 22:24:49 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 22:24:51 INFO - Downloading blessings-1.6.tar.gz 22:24:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:24:51 INFO - Installing collected packages: blessings 22:24:51 INFO - Running setup.py install for blessings 22:24:52 INFO - Successfully installed blessings 22:24:52 INFO - Cleaning up... 22:24:52 INFO - Return code: 0 22:24:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:24:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:52 INFO - Reading from file tmpfile_stdout 22:24:52 INFO - Current package versions: 22:24:52 INFO - blessings == 1.6 22:24:52 INFO - blobuploader == 1.2.4 22:24:52 INFO - docopt == 0.6.1 22:24:52 INFO - functools32 == 3.2.3-2 22:24:52 INFO - jsonschema == 2.5.1 22:24:52 INFO - manifestparser == 1.1 22:24:52 INFO - mozInstall == 1.12 22:24:52 INFO - mozcrash == 0.17 22:24:52 INFO - mozdebug == 0.1 22:24:52 INFO - mozdevice == 0.48 22:24:52 INFO - mozfile == 1.2 22:24:52 INFO - mozhttpd == 0.7 22:24:52 INFO - mozinfo == 0.9 22:24:52 INFO - mozleak == 0.1 22:24:52 INFO - mozlog == 3.3 22:24:52 INFO - moznetwork == 0.27 22:24:52 INFO - mozprocess == 0.23 22:24:52 INFO - mozprofile == 0.28 22:24:52 INFO - mozrunner == 6.12 22:24:52 INFO - mozscreenshot == 0.1 22:24:52 INFO - mozsystemmonitor == 0.3 22:24:52 INFO - moztest == 0.7 22:24:52 INFO - mozversion == 1.4 22:24:52 INFO - psutil == 3.1.1 22:24:52 INFO - requests == 1.2.3 22:24:52 INFO - wsgiref == 0.1.2 22:24:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:24:52 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')]} 22:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:52 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')]} 22:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:52 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 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:52 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 22:24:52 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 22:24:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:52 INFO - 'HOME': '/Users/cltbld', 22:24:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:52 INFO - 'LOGNAME': 'cltbld', 22:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:52 INFO - 'MOZ_NO_REMOTE': '1', 22:24:52 INFO - 'NO_EM_RESTART': '1', 22:24:52 INFO - 'PAGER': '/bin/cat', 22:24:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:52 INFO - 'PWD': '/builds/slave/test', 22:24:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:52 INFO - 'SHELL': '/bin/bash', 22:24:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:52 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:52 INFO - 'USER': 'cltbld', 22:24:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:52 INFO - 'XPC_FLAGS': '0x0', 22:24:52 INFO - 'XPC_SERVICE_NAME': '0', 22:24:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:52 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)) 22:24:52 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)) 22:24:52 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)) 22:24:52 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)) 22:24:52 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)) 22:24:52 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)) 22:24:52 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)) 22:24:52 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)) 22:24:52 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)) 22:24:52 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)) 22:24:52 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)) 22:24:52 INFO - Cleaning up... 22:24:52 INFO - Return code: 0 22:24:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:24:52 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')]} 22:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:52 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')]} 22:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:24:52 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 0x10def7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fea2856b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:24:52 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 22:24:52 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 22:24:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:24:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:52 INFO - 'HOME': '/Users/cltbld', 22:24:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:52 INFO - 'LOGNAME': 'cltbld', 22:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:52 INFO - 'MOZ_NO_REMOTE': '1', 22:24:52 INFO - 'NO_EM_RESTART': '1', 22:24:52 INFO - 'PAGER': '/bin/cat', 22:24:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:52 INFO - 'PWD': '/builds/slave/test', 22:24:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:52 INFO - 'SHELL': '/bin/bash', 22:24:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:24:52 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:24:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:52 INFO - 'USER': 'cltbld', 22:24:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:52 INFO - 'XPC_FLAGS': '0x0', 22:24:52 INFO - 'XPC_SERVICE_NAME': '0', 22:24:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:24:53 INFO - Cleaning up... 22:24:53 INFO - Return code: 0 22:24:53 INFO - Running post-action listener: _resource_record_post_action 22:24:53 INFO - Running post-action listener: _start_resource_monitoring 22:24:53 INFO - Starting resource monitoring. 22:24:53 INFO - [mozharness: 2016-08-10 05:24:53.086818Z] Finished create-virtualenv step (success) 22:24:53 INFO - [mozharness: 2016-08-10 05:24:53.087753Z] Running install step. 22:24:53 INFO - Running pre-action listener: _resource_record_pre_action 22:24:53 INFO - Running main action method: install 22:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:24:53 INFO - Reading from file tmpfile_stdout 22:24:53 INFO - Detecting whether we're running mozinstall >=1.0... 22:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:24:53 INFO - Reading from file tmpfile_stdout 22:24:53 INFO - Output received: 22:24:53 INFO - Usage: mozinstall [options] installer 22:24:53 INFO - Options: 22:24:53 INFO - -h, --help show this help message and exit 22:24:53 INFO - -d DEST, --destination=DEST 22:24:53 INFO - Directory to install application into. [default: 22:24:53 INFO - "/builds/slave/test"] 22:24:53 INFO - --app=APP Application being installed. [default: firefox] 22:24:53 INFO - mkdir: /builds/slave/test/build/application 22:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:25:27 INFO - Reading from file tmpfile_stdout 22:25:27 INFO - Output received: 22:25:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:25:27 INFO - Running post-action listener: _resource_record_post_action 22:25:27 INFO - [mozharness: 2016-08-10 05:25:27.446886Z] Finished install step (success) 22:25:27 INFO - [mozharness: 2016-08-10 05:25:27.447020Z] Running run-tests step. 22:25:27 INFO - Running pre-action listener: _resource_record_pre_action 22:25:27 INFO - Running main action method: run_tests 22:25:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:25:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:25:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:25:27 INFO - 2016-08-09 22:25:27.462 screenresolution[1641:7430] starting screenresolution argv=screenresolution get 22:25:27 INFO - 2016-08-09 22:25:27.464 screenresolution[1641:7430] Display 0: 1600x1200x32@60 22:25:27 INFO - 2016-08-09 22:25:27.470 screenresolution[1642:7434] starting screenresolution argv=screenresolution list 22:25:27 INFO - Available Modes on Display 0 22:25:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:25:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:25:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:25:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:25:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:25:27 INFO - Intel Iris: 22:25:27 INFO - Chipset Model: Intel Iris 22:25:27 INFO - Type: GPU 22:25:27 INFO - Bus: Built-In 22:25:27 INFO - VRAM (Dynamic, Max): 1536 MB 22:25:27 INFO - Vendor: Intel (0x8086) 22:25:27 INFO - Device ID: 0x0a2e 22:25:27 INFO - Revision ID: 0x0009 22:25:27 INFO - Displays: 22:25:27 INFO - Display: 22:25:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:25:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:25:27 INFO - Display Serial Number: 1600x1200 60 22:25:27 INFO - Main Display: Yes 22:25:27 INFO - Mirror: Off 22:25:27 INFO - Online: Yes 22:25:27 INFO - Rotation: Supported 22:25:27 INFO - Return code: 0 22:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:25:27 INFO - Python 2.7.3 22:25:27 INFO - Return code: 0 22:25:27 INFO - grabbing minidump binary from tooltool 22:25:27 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')]} 22:25:27 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/macosx64/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 0x10de5bd30>, '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 0x10dbc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de53b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:25:27 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:25:27 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:25:27 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:25:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:25:27 INFO - Return code: 0 22:25:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:25:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:25:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:25:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:25:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0046 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:25:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jF6SJq04c4/Render', 22:25:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:25:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:25:27 INFO - 'HOME': '/Users/cltbld', 22:25:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:25:27 INFO - 'LOGNAME': 'cltbld', 22:25:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:25:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:25:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:27 INFO - 'MOZ_NO_REMOTE': '1', 22:25:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:25:27 INFO - 'NO_EM_RESTART': '1', 22:25:27 INFO - 'PAGER': '/bin/cat', 22:25:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:25:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:27 INFO - 'PWD': '/builds/slave/test', 22:25:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:25:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:25:27 INFO - 'SHELL': '/bin/bash', 22:25:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners', 22:25:27 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 22:25:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:25:27 INFO - 'USER': 'cltbld', 22:25:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:25:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:25:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:27 INFO - 'XPC_FLAGS': '0x0', 22:25:27 INFO - 'XPC_SERVICE_NAME': '0', 22:25:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:25:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:25:28 INFO - mozversion application_buildid: 20160809204915 22:25:28 INFO - mozversion application_changeset: 7bab284d79100a759b56b98169232d8597cc7add 22:25:28 INFO - mozversion application_display_name: Nightly 22:25:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:25:28 INFO - mozversion application_name: Firefox 22:25:28 INFO - mozversion application_remotingname: firefox 22:25:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:25:28 INFO - mozversion application_vendor: Mozilla 22:25:28 INFO - mozversion application_version: 51.0a1 22:25:28 INFO - mozversion platform_buildid: 20160809204915 22:25:28 INFO - mozversion platform_changeset: 7bab284d79100a759b56b98169232d8597cc7add 22:25:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:25:28 INFO - mozversion platform_version: 51.0a1 22:25:28 INFO - using testdate: 1470806728 22:25:28 INFO - actual date: 1470806728 22:25:28 INFO - starting webserver on 'localhost:49271' 22:25:28 INFO - SUITE-START | Running 2 tests 22:25:28 INFO - TEST-START | dromaeo_css 22:25:28 INFO - operating with platform_type : mac_ 22:25:28 INFO - Initialising browser for dromaeo_css test... 22:25:28 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpiArMLc/profile http://localhost:49271/getInfo.html) 22:25:31 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 22:25:31 INFO - PROCESS | 1652 | colorDepth:24 22:25:31 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 22:25:31 INFO - PROCESS | 1652 | __metrics 22:25:31 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:25:32 INFO - TEST-INFO | 1652: exit 0 22:25:32 INFO - Browser initialized. 22:25:32 INFO - Running cycle 1/1 for dromaeo_css test... 22:25:32 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpiArMLc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:44 INFO - PROCESS | 1658 | RSS: Main: 254681088 22:25:44 INFO - PROCESS | 1658 | 22:26:33 INFO - PROCESS | 1658 | 1470806793675 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] 22:28:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 22:28:47 INFO - PROCESS | 1658 | RSS: Main: 267448320 22:28:47 INFO - PROCESS | 1658 | 22:31:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 22:31:50 INFO - PROCESS | 1658 | RSS: Main: 279805952 22:31:50 INFO - PROCESS | 1658 | 22:34:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 22:34:52 INFO - PROCESS | 1658 | RSS: Main: 287596544 22:34:52 INFO - PROCESS | 1658 | 22:37:54 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 22:37:55 INFO - PROCESS | 1658 | RSS: Main: 285728768 22:37:55 INFO - PROCESS | 1658 | 22:40:57 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 22:40:58 INFO - PROCESS | 1658 | RSS: Main: 291147776 22:40:58 INFO - PROCESS | 1658 | 22:44:01 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 22:44:01 INFO - PROCESS | 1658 | RSS: Main: 291246080 22:44:01 INFO - PROCESS | 1658 | 22:44:01 INFO - PROCESS | 1658 | __start_tp_report 22:44:01 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 22:44:01 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 22:44:01 INFO - PROCESS | 1658 | |i|pagename|runs| 22:44:01 INFO - PROCESS | 1658 | |0;dojo.html;3394.6944774970257;3398.5751781027357;3429.5027221052933;3434.467657513086;3436.707879830215;5613.46672066154;5815.4475324844125;5852.736626851786;5915.497182739472;5933.406659334064;5789.131630255474;5790.015697331453;5815;5854.619449735765;5918;5189.040027594888;5286.3392075990505;5293.57651387888;5362.731863406833;5396.217548455472;5411.18832175173;5413.079776438015;5421;5455.481729235717;5470.671759694411;4536.228841097021;5133.666311689765;5206.947930520684;5248.3701955765555;5329.946700532983;5195.766190521435;5232.8691782705355;5251.501107394792;5268.5521730652945;5322.840314790563;5261.210818377274;5283.181106928394;5308.389535203424;5323.600729945269;5336.092864213054;5712.200291959129;5719.113537401709;5811.941880581182;5836.007878660638;5885.058390657517;6254.424359651827;6297.086922396273;6326.430621244064;6342.175517182783;6348.968255158741;6306.085617585471;6311.053341998691;6321.241451025906;6330.27201871786;6331.525135614801;5749.9425005750245;5901.17383566301;5940.138679891392;5951.166836642873;6055.364186760415;6314.336994615591;6337.873242535124;6338.714757835908;6343.809685709436;6353.872922541524;6290.465310448663;6294;6302.495800336053;6309.526785491106;6334.5249106317215;6129.9701281150365;6161.568690191643;6216.160818289563;6240.375962403723;6383.585066970632;6185.319614842364;6210.484590228971;6227.314995350508;6253;6255.937440625536;6240.781572644935;6265.8746825063245;6323.588966717149;6335.714892829834;6341.239051313872;4950.603951683856;5007.949920500749;5027.547520723152;5067.037262920034;5112.258722485293;4444.755538445352;4512.842050528216;4553.817847286137;4561.954380456219;4572.017016341436;5790.565707571949;5800.564957628195;5866.472017518444;5870.26621672291;5883.382244864313;6170.81487555374;6238.75044998195;6255.155554000152;6324.747010119636;6326.082718005863;5776.68228247442;5788.942110578924;5799.9130013049835;5801.216835727208;5872.911906321408;5466.6993315367245;5483.725813709299;5495.560355171577;5498.285222920995;5498.81775418287;6239.96880015597;6296.842578935563;6313.40022697843;6349.9682501587195;6364.1408409865;6244.063390491463;6271.78048768291;6281.15204447412;6286.339934306832;6299.244090709144;6336.493080553637;6412.551121421456;6419.133416988647;6424.421802037839;6429.196350456193;6110.862540392742;6183.536234782499;6212.937870621236;6239.313675495782;6288.937110628835;6121.663308518075;6213.844653883508;6273.341299163704;6288.339724329063;6303.527235457267;6264.937350626617;6270.247570291412;6271.059341098871;6277.1525844010475;6312.14786003902;5985.581009329305;6115.179495343419;6150.292716337678;6275.058741188858;6283.717232724447;5938;6100.145979562776;6118.296395914527;6130.8873626509085;6133.172021777003;6181.898154627448;6200.937990620036;6255.186825712628;6263.123162757309;6273.560850740404;5909.793157239476;5978;6057.212562366864;6104.664243466652;6117.541184411098 22:44:01 INFO - PROCESS | 1658 | |1;ext.html;480.9563247752377;492.7413108118237;541.136886665768;554.1881144123855;567.1747607231479;36658.26683466332;38073.619263807355;40946;41163.176736465255;41795.16409671805;41777.791111044404;42245;42268.57731422685;42282.577174228245;42418.36372454416;9476.526173691305;9667.468289244089;9673.56468958895;9700.029997000278;9706.320557560974;9571.617135314615;9684.709458716214;9685.564149613285;9689.418634881895;9700.417974921493;9682.273829462783;9693;9710.465924374157;9723.12491875731;9738.464384458888;9636.132748052674;9677.274204434625;9693.369930954465;9695.563699633463;9728.90271097287;8034.1564135766075;8268.421210515266;9671;9692.030796920322;9705.369151005161;39374.80312598447;39459.01352466182;39592.60407395918;39902;39932.60067399347;3052.4810782167083;3282.5404443377947;3329.933401331984;3444.121748954026;3488.2674638325984;29893.551596726065;30061.398772024437;30914.381712365852;30959.916402925788;30960.38079238425;3139.5133754268127;3144.591203143577;3147.35479226758;3173.9682603173906;3240.270939038721;404073;406263.99999999703;407750;409544;410528.9473552643;33151.66848331486;33265.66734332674;33391.83304083464;33400.1649958753;33402.832985835405;34468.82765586156;34667.13332166665;34699.30601388009;35784.46323305152;35954.28091438157;16531.91734041322;16705.83294167067;16745.748813767805;16752.329906803592;16947.406840760515;12697.682557935941;12735.872641273654;12747.42636581356;12749;12751.61745147647;16666.583335416468;19340.06599340055;19466.825177200448;19632.723872948212;19794.307199247956;8143.4575959457825;8266.793330166794;8273.46222495536;8285;8307.792305192297;896.8385690575759;918.609590923868;1016.694991502561;1036.8341065429495;1090.705509512443;30758.481639334143;30934.917277895165;31012.84493577563;31368.686313137034;31653.52519712206;1052.55792567122;1066.7919755647574;1068.8076146293743;1085.5494969587692;1110.9834501431262;15528.5341439757;15613.60965975837;15712.528624141294;15929.203539822962;16069.839301607068;569987.1500642471;673147;674603;676366.6181669158;677229.2277077264;60118;60140.09789853156;60665;60954.39045609487;63537.23470739713;1110.5002748763088;1334.3194970565082;1370.171046516843;1370.7327071221216;1376.029898921258;29512.557311639914;30274.84862575761;30461.23846903845;30833;30934.845325773225;47053.29420058634;47336.289955650005;47507.28739068848;47589.52410475851;47660;573607;578715;580257;580306;581075;24175.51648967046;26308.8684556576;27001.729982699922;27252.454950900476;27325.1802445919;45829.54170458386;47132.7643361781;48699.269510956656;50255.74872125616;50525.73685657741;40899.38650920179;40977.59022409857;41057.589424105376;42235.7888210557;42628.93427664209;34002.319953600294;35515.82242088773;39524.604753953245;39535;40553.59446405498;36919.44620830636;38718.80640596779;39434.2113157741;40723;41271.58728412678;34416.451259692876;35168.12079698028;35729.82135089308;35818;35830.82084589561;26050;26510.337241568344;26711.599326010513;26880.865595671898;27105 22:44:01 INFO - PROCESS | 1658 | |2;jquery.html;371.7286380941914;375.07169754856716;377.6487866284356;383.9980800096;402.2819267607322;17416.084230734625;20627.865467399286;20946.162153513862;21412.357629271108;21604.459888502795;20078;20643.864587447686;20716.964151792425;20760.584788304262;20963.685544716842;5748.655080695151;5924.377940316259;6064.696765161746;6116.877662446748;6151.7231724572275;6050.848728781772;6085.969570152143;6091.208142941434;6218.22272215973;6485.3514648535;6454.290028096929;6514.153160089182;6540.051692504584;6548.770793022245;6572.967135164317;6169.413905678954;6422.26143993442;6495.188101487314;6554.737810487575;6559.376859198393;5684.943150568524;5831.679257640828;5884.440978107073;5887.911681324784;5888.263967004124;12908.54820081302;12946.158499697583;22645.660315095287;25499.107531236503;25810.612840807404;2116.2804646420136;2126.26643807886;2160.5786871560053;2164.426426996847;2340.3564019894493;8242.793930151794;9868.753281167956;9874.111329980264;10046.792602312382;12072.21530600517;1805.4493379519242;1834.5321942904475;1852.8980906050163;1872.6535590915723;1882.830545250934;118057.81942180554;120098;122132;122637.77362226442;124421;11221.158413119048;11293.604723834625;11454.713632159262;11457.484413201426;11684.532618695326;14340.713185736435;14356.712865742835;14382;14402.351894164787;14411.42354305837;8205.302549283258;8389.496630202208;8469.237768600855;8571.914280857112;8614;8394.496330220207;8401;8425.4944703318;8509.97880254361;8558;3808.771473711586;4168.103857670615;4376.846810361622;4491.326301054868;4557.609928971629;1878.2393130782054;1937.9224831006884;1939.951501212481;1943.1158822735904;1949.756280464942;827.98344033119;879.3932186791094;959.2901253072675;969.6412327438893;988.8912219655832;19774.01129943497;20456.386308410772;20511.4872128198;20518.179272829217;20646.27738029192;846.5597889097635;880.6345366672887;891.3270480786964;927.596495524449;995.5271246158162;8804.47173169612;10797.190210734227;10889.455527223607;11033.282836615594;11109.611163609236;487607;495718;575471;585070;599974;98205;120346.3982680081;120981.39509302573;123809;123932.76067239392;986.4919566423298;1006.7785087280787;1017.8524114003428;1023.181454836143;1029.8867124616286;17999.820001800337;18484.81515184831;18533.629327413644;18642.813571864106;18648.533786655444;54099.72950135224;55093;58141.4185858136;58208.4179158203;58353;76496;82839;84448;85243.5737821307;85693.57153214194;6252.937470625236;7461.701531938662;7806.258405451473;8156.877646835183;8330.958345208235;11370.69237037718;11679.240849344766;13137;13466.922646188086;13625.727485450434;11369.658910232707;11402.48688808989;11621.535138594361;11668.474918628512;11743.647690569296;10814;11384.203105782515;11475.827862582235;11553.653390398302;11644.88355116438;11131.944340278247;11699.707507312045;11912.285262884254;11949.283043017447;11980.520779168735;7380.741674041383;7384.667689954073;7536.924630753622;7539.208383119693;7552.244775522404;9991.700248992542;11426.771464570827;11655.53377864876;11814.763704726029;11916 22:44:01 INFO - PROCESS | 1658 | |3;mootools.html;1492.5820770184346;1532.8160620725512;1718.5102245859932;1727.093276030084;1870.158428707082;8003.1596682348245;8494.235518803314;8665.133486651332;8984.550772461374;8988.41575297606;8694.347923905701;8760.737177884657;8898.243649289818;8901.376903616734;8909.509976951282;2333.8366314358045;2351.2946116165167;2368.7275963264187;2372.9406764830933;2377.215518878766;2326.581215381231;2362.6574146748712;2372.0037584214583;2376.0733314007553;2377.5958087125146;2349.5066036132434;2353.5528249632603;2367.3963139399516;2370.573296806574;2412.155745489082;2051.569170474202;2248.932532024042;2343.496148328109;2343.6953196084487;2346.847454915442;2345.882705864717;2347.2019513365367;2349.3539276698875;2350.9647355289685;2359.504504054151;8845;8877.245434138105;8923.910760892373;8940.240079593243;8981.506017169053;4409.581089796464;5315.309009828737;5327.254184414185;5369.060414427459;5452.263944367499;3529.9763068710045;3639.344917914774;3658.9451158232646;3734.3838266685966;3741.2330472253125;914.3371055984425;1081.3674000709593;1085.2403317677579;1093.2456604942565;1112;11560.595379161688;11638.06895448362;11703.473343699554;11736.882631173665;11779.411029448578;4543;4628.60656844172;4821.276808478756;4828.106800241966;4841.806327746921;8880.422772519765;9655.324127311022;9898.604055837686;9984.400935943866;10015.39907605546;5386.8383948481605;5397.892042159135;5400.324959380077;5412.91880621791;5422.267993820863;4455.086707225011;4777.570018698334;4825.758712064383;4905.337779399807;4946.3817022872145;3867.2652196082654;3953.743006704555;3980.7213495055066;3993.8003099845473;4042.575529569353;1282.51264519482;1285.562908611058;1290.8515520715239;1294.9935250323688;1300.14840279617;717.7703134996856;747.8579069976687;951.743029382063;958.434523631061;962.2013728605241;5876.382979787147;5973.223480947449;5985.311689155803;6114.6636934969;6138.877222455526;417.638742487752;423.8368228232153;445.2030864752122;446.7453551475693;446.8994476242884;3908.710384692013;4006.419069235003;4124.092699606082;4149.25313443577;4171.186618609342;10447.477626118663;10659.520321585547;10890.945545272223;10915.508802103925;10929.617463388902;10613.469326533797;10810.135189184846;10877.83683244752;10881.12950963921;10925.562977480811;6562.606243625398;8224.095349511548;8238.588070596446;8251.8349633007;8307.667693292322;6079.483243924139;6150.477209437248;6168.876622467616;6169.321374648695;6298.08677741734;10050.396976181453;10263.58945642166;10291.022352876465;10295.485225738832;10342.431166285927;8645.919260092489;8723.432976856484;9755.268354873271;10318.277720559488;10354.1198998086;6691.732330706912;6996.650167491707;7430.368418684472;7445.4415918805225;7663.693452261847;8432.578371081545;8482.109378515413;8489.787755305919;8491.15088491146;8508.787280318042;7357.742479013209;7419.961205431136;7554.848903022019;7644.426668000034;7673.731419400294;7324.597147156957;7472.731724134581;7483.738069167553;7569.826676865095;7640.083190017233;6803.25164231944;6929.44564434834;6941.34057264559;6951.9652401739695;6979.930200697928;4068.3694027425795;4967.875803104806;5108.514691104339;5149.819756308511;5219.19102539107;5025.812404918132;5144.485551444825;5151.793928242828;5276.736163191748;5295.364556253275 22:44:01 INFO - PROCESS | 1658 | |4;prototype.html;255.86311323441956;301.4363140926468;304.8704300672214;305.21864028088095;309.5233340655391;4764.976175119124;5672.517835983941;5756.7409466574;5773.740181691829;5838.365257727833;4392.692511524195;5634.12671035989;5656.2929633795775;5657.2928383952;5698.857528561778;4045.4336392904943;4153.439285696438;4245.172191422674;4258.233517966764;4292.6780491463105;3429.4341433663417;3800.3159431302356;4249.745015299092;4259.914801703964;4276;3152.7162555370014;3984.641382275609;4233.767142807144;4238.237117318881;4248.25655510286;4092.6876314882293;4209.873703788891;4246.723962942398;4266.658667306608;4290.056187638715;3685;3686.502322186497;3691.9261614767556;3692.1877187018813;3712.4988126603103;5398.541124004425;5482.479164479368;5483.464629903633;5505.642133261325;5509.586780991402;3882.6311500407414;4822.9984556409045;4934.481879402683;4939.703617782944;4944.258361245806;5025.522575355335;5084.262781896641;5966;5967.762089216544;5979.312379076418;3777.697784177259;4101.097758493127;4108.67130629549;4111.958880411187;4118.423420721101;4897.730624815598;5297.337832770903;5616.691081990489;5908.409159084057;6238.625682459067;3642.18050938542;4139.234241665302;4141.772202528829;4143.523494798076;4167.854125105607;4216.64158546527;4221.387898754663;4222.387753775747;4228.492580890313;4236.830526778895;4166.958260434892;4556;4781.784819683123;4856.17445034345;4933.531314525115;5250.868728281824;5895.587308888336;5933.762649494059;5989.670568118708;5991.460768530853;1388.9513867014607;1444.5810714892773;1447.5657302809116;1449.8405175430905;1454.6508837879044;748.8951546783454;807.7536351412758;872.8952525696957;877.9341549383822;886.1094599927161;4949.257611358325;5046.142155833444;5201.011807756514;5465.371482279589;5534.667919924817;5141.717205553656;5696.943030569724;6131.570790044654;6174.9073763893575;6209.844753881099;5200.699825043739;5251.947480525222;5318.840434786963;5427.620066595379;5430.755615997289;3195.5845740053646;3211.341674956628;3215.5498230247786;3218.742500599946;3235.0589646212557;5822.388649191774;6109.7861574844665;6329.873402531924;6347.365263473615;6375.023742639796;5440.42875498067;5777.786664800355;5788.652680839163;6130.448259656652;6310.716017779211;5380.704061276668;5796.1305804129715;5846.941530584725;6015.901810567649;6391.96804015977;4664.39362882823;5109.540141387293;5410.945890541044;6137.907931380943;6158.353372895782;5540.418256083053;6076.361981991828;6083.080121541596;6104.680221515796;6147.415995480422;5584.664920104807;6257.311695713377;6321.431071203429;6332.810015699352;6404.647744374104;3683.8027641016242;3924.313245182139;4012.177503611694;4029.133736246721;4042.6053011710915;5355.892882142413;6135.325114237519;6150.169727086786;6153.661548614869;6184.350643182401;4593.6554758392585;5651.124075768263;5713.714314284186;6001.519878409804;6022.488088512526;5192.714400707846;5197.778522047392;5953.690188158598;5972.02923035406;5972.20833749882;5112.906691451618;5209.072643121917;5445.156000819879;5560.443955604569;5885.764569417175;5100.438951715234;5701.346609483369;5913.970430147821;5955.583109182316;5993;5069.391672999263;5474.972625136849;5838.423625621143;5850.619709718854;5854.594897224721 22:44:01 INFO - PROCESS | 1658 | |5;yui.html;692.4910191009609;713.2047766740086;743.8103283662665;783.1581050370853;788.546585713215;15009.174495402749;15460.149691766948;15793.368265269404;15806.13066281354;15843.524694259162;11684.240524365932;11957.342346170957;14398.928005359958;14428.639284017905;14434.85565144351;668.6188872342767;828.5857071464268;835.456952980563;846.4371193156544;848.0544193224572;860.5482121885997;866.3588944181323;867.2584939876409;872.6422166911567;874.5408660453281;873.1617647058831;890.3678388344251;915.8488849339851;915.9175674189322;919.4667093086027;883.8497455432592;896.8654701794718;906.7642412972608;907.795745957161;913.0595486648763;676.2020815437781;676.9593824370504;678.13199105145;678.7646483400214;678.9660516974131;14689.045212061288;14696.632584185374;14736.778948315783;14741.926290368587;14769.261536923219;14500.129992200396;14532.782008269884;14648.926755366261;14757.704845903128;14774.926125369411;12011.57959471423;12267.325297108655;12512.436940337706;12532.436040378205;12620.17968832023;3728.4780130781714;3757.079515518688;3971.5035620547433;3971.702122340836;3973.602639736032;118818;119763;120035.3998230012;120230;124723.3763831184;11144.777104457866;11173.553057877756;11306.886931130583;11341.773164536664;11664.6500604982;22817.543649126925;23055.884720576625;23072;23076.53846923052;23122.76877231206;8084.312833409109;8157.592120393957;8162.265396114378;8231.218034286732;8278.710245141512;3681.7975011374097;4421.872422532234;4539.750313732777;4557.361969324297;4601.470830854428;3143.937121257562;3600.225951420457;3724.366857634209;3728.291624591319;3876.341022026262;642.9260635026939;732.487258918759;734.4528326396869;744.739341230565;748.3190296829858;560.3751816724405;600.7807150390164;630.196499463179;659.7525927777084;682.4199430484049;9512;9663.758406039906;9695;9715.27135464843;9950.850737238947;603.2128072864954;639.1339734659563;683.1017212365723;693.0905355744991;698.2040473859804;4643.257078867365;4812.975935120372;4825.708006964746;4856.927146092811;4875.634327425457;107207.92792072135;148719.2564037173;155951.22024390032;158205;160726;118358;145785;146249.26875365767;149571;150163;68831;71208;75238.62380687952;102022;122753;9506.382085164441;9787.608495660379;9996.200303975518;10109.848352274868;10143;60655.48361291003;63632.3636763645;64774.676126619066;64975.67512162409;66710.33289667235;90350.54824725834;96175;100063.49968250403;100158.4992075035;100559.49160762448;13112.278824664354;13216.933915330363;13299.135556189203;13432.059755817;13837.30813459303;15063.623409414853;15066.54800355991;15416.383344666088;15494.767578486557;15666.921665391601;13670.93164534171;13737.107088039245;13871.930640346734;13895.791563126359;14236.217008064656;13419.932900335827;13642.658933526742;14083.084599501;14099.436022558983;14153.716925661634;13328.33358332099;13625.93187034098;13666.590002299947;13757.862421375658;14130.434782608581;11048.462347872648;11471.885281147082;11480.368579727861;11509;11707.473163707824;9501.477418742035;11343.829842552203;11613.941930290295;11788.941055294668;12276.202046866925 22:44:01 INFO - PROCESS | 1658 | __end_tp_report 22:44:01 INFO - PROCESS | 1658 | __start_cc_report 22:44:01 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,419 22:44:01 INFO - PROCESS | 1658 | __end_cc_report 22:44:01 INFO - PROCESS | 1658 | __startTimestamp1470807841861__endTimestamp 22:44:01 INFO - PROCESS | 1658 | 22:44:01 INFO - PROCESS | 1658 | ------- Summary: start ------- 22:44:01 INFO - PROCESS | 1658 | Number of tests: 6 22:44:01 INFO - PROCESS | 1658 | 22:44:01 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5830.57 Median:6110.86 stddev:638.51 (10.4%) stddev-sans-first:611.20 22:44:01 INFO - PROCESS | 1658 | Values: 3394.7 3398.6 3429.5 3434.5 3436.7 5613.5 5815.4 5852.7 5915.5 5933.4 5789.1 5790.0 5815.0 5854.6 5918.0 5189.0 5286.3 5293.6 5362.7 5396.2 5411.2 5413.1 5421.0 5455.5 5470.7 4536.2 5133.7 5206.9 5248.4 5329.9 5195.8 5232.9 5251.5 5268.6 5322.8 5261.2 5283.2 5308.4 5323.6 5336.1 5712.2 5719.1 5811.9 5836.0 5885.1 6254.4 6297.1 6326.4 6342.2 6349.0 6306.1 6311.1 6321.2 6330.3 6331.5 5749.9 5901.2 5940.1 5951.2 6055.4 6314.3 6337.9 6338.7 6343.8 6353.9 6290.5 6294.0 6302.5 6309.5 6334.5 6130.0 6161.6 6216.2 6240.4 6383.6 6185.3 6210.5 6227.3 6253.0 6255.9 6240.8 6265.9 6323.6 6335.7 6341.2 4950.6 5007.9 5027.5 5067.0 5112.3 4444.8 4512.8 4553.8 4562.0 4572.0 5790.6 5800.6 5866.5 5870.3 5883.4 6170.8 6238.8 6255.2 6324.7 6326.1 5776.7 5788.9 5799.9 5801.2 5872.9 5466.7 5483.7 5495.6 5498.3 5498.8 6240.0 6296.8 6313.4 6350.0 6364.1 6244.1 6271.8 6281.2 6286.3 6299.2 6336.5 6412.6 6419.1 6424.4 6429.2 6110.9 6183.5 6212.9 6239.3 6288.9 6121.7 6213.8 6273.3 6288.3 6303.5 6264.9 6270.2 6271.1 6277.2 6312.1 5985.6 6115.2 6150.3 6275.1 6283.7 5938.0 6100.1 6118.3 6130.9 6133.2 6181.9 6200.9 6255.2 6263.1 6273.6 5909.8 5978.0 6057.2 6104.7 6117.5 22:44:01 INFO - PROCESS | 1658 | 22:44:01 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:67159.09 Median:39446.61 stddev:149120.18 (378.0%) stddev-sans-first:149454.47 22:44:01 INFO - PROCESS | 1658 | Values: 481.0 492.7 541.1 554.2 567.2 36658.3 38073.6 40946.0 41163.2 41795.2 41777.8 42245.0 42268.6 42282.6 42418.4 9476.5 9667.5 9673.6 9700.0 9706.3 9571.6 9684.7 9685.6 9689.4 9700.4 9682.3 9693.0 9710.5 9723.1 9738.5 9636.1 9677.3 9693.4 9695.6 9728.9 8034.2 8268.4 9671.0 9692.0 9705.4 39374.8 39459.0 39592.6 39902.0 39932.6 3052.5 3282.5 3329.9 3444.1 3488.3 29893.6 30061.4 30914.4 30959.9 30960.4 3139.5 3144.6 3147.4 3174.0 3240.3 404073.0 406264.0 407750.0 409544.0 410528.9 33151.7 33265.7 33391.8 33400.2 33402.8 34468.8 34667.1 34699.3 35784.5 35954.3 16531.9 16705.8 16745.7 16752.3 16947.4 12697.7 12735.9 12747.4 12749.0 12751.6 16666.6 19340.1 19466.8 19632.7 19794.3 8143.5 8266.8 8273.5 8285.0 8307.8 896.8 918.6 1016.7 1036.8 1090.7 30758.5 30934.9 31012.8 31368.7 31653.5 1052.6 1066.8 1068.8 1085.5 1111.0 15528.5 15613.6 15712.5 15929.2 16069.8 569987.2 673147.0 674603.0 676366.6 677229.2 60118.0 60140.1 60665.0 60954.4 63537.2 1110.5 1334.3 1370.2 1370.7 1376.0 29512.6 30274.8 30461.2 30833.0 30934.8 47053.3 47336.3 47507.3 47589.5 47660.0 573607.0 578715.0 580257.0 580306.0 581075.0 24175.5 26308.9 27001.7 27252.5 27325.2 45829.5 47132.8 48699.3 50255.7 50525.7 40899.4 40977.6 41057.6 42235.8 42628.9 34002.3 35515.8 39524.6 39535.0 40553.6 36919.4 38718.8 39434.2 40723.0 41271.6 34416.5 35168.1 35729.8 35818.0 35830.8 26050.0 26510.3 26711.6 26880.9 27105.0 22:44:01 INFO - PROCESS | 1658 | 22:44:01 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:33576.06 Median:13919.73 stddev:92351.35 (663.5%) stddev-sans-first:92576.77 22:44:01 INFO - PROCESS | 1658 | Values: 371.7 375.1 377.6 384.0 402.3 17416.1 20627.9 20946.2 21412.4 21604.5 20078.0 20643.9 20717.0 20760.6 20963.7 5748.7 5924.4 6064.7 6116.9 6151.7 6050.8 6086.0 6091.2 6218.2 6485.4 6454.3 6514.2 6540.1 6548.8 6573.0 6169.4 6422.3 6495.2 6554.7 6559.4 5684.9 5831.7 5884.4 5887.9 5888.3 12908.5 12946.2 22645.7 25499.1 25810.6 2116.3 2126.3 2160.6 2164.4 2340.4 8242.8 9868.8 9874.1 10046.8 12072.2 1805.4 1834.5 1852.9 1872.7 1882.8 118057.8 120098.0 122132.0 122637.8 124421.0 11221.2 11293.6 11454.7 11457.5 11684.5 14340.7 14356.7 14382.0 14402.4 14411.4 8205.3 8389.5 8469.2 8571.9 8614.0 8394.5 8401.0 8425.5 8510.0 8558.0 3808.8 4168.1 4376.8 4491.3 4557.6 1878.2 1937.9 1940.0 1943.1 1949.8 828.0 879.4 959.3 969.6 988.9 19774.0 20456.4 20511.5 20518.2 20646.3 846.6 880.6 891.3 927.6 995.5 8804.5 10797.2 10889.5 11033.3 11109.6 487607.0 495718.0 575471.0 585070.0 599974.0 98205.0 120346.4 120981.4 123809.0 123932.8 986.5 1006.8 1017.9 1023.2 1029.9 17999.8 18484.8 18533.6 18642.8 18648.5 54099.7 55093.0 58141.4 58208.4 58353.0 76496.0 82839.0 84448.0 85243.6 85693.6 6252.9 7461.7 7806.3 8156.9 8331.0 11370.7 11679.2 13137.0 13466.9 13625.7 11369.7 11402.5 11621.5 11668.5 11743.6 10814.0 11384.2 11475.8 11553.7 11644.9 11131.9 11699.7 11912.3 11949.3 11980.5 7380.7 7384.7 7536.9 7539.2 7552.2 9991.7 11426.8 11655.5 11814.8 11916.0 22:44:01 INFO - PROCESS | 1658 | 22:44:01 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5717.59 Median:4834.96 stddev:3218.80 (66.6%) stddev-sans-first:3212.17 22:44:01 INFO - PROCESS | 1658 | Values: 1492.6 1532.8 1718.5 1727.1 1870.2 8003.2 8494.2 8665.1 8984.6 8988.4 8694.3 8760.7 8898.2 8901.4 8909.5 2333.8 2351.3 2368.7 2372.9 2377.2 2326.6 2362.7 2372.0 2376.1 2377.6 2349.5 2353.6 2367.4 2370.6 2412.2 2051.6 2248.9 2343.5 2343.7 2346.8 2345.9 2347.2 2349.4 2351.0 2359.5 8845.0 8877.2 8923.9 8940.2 8981.5 4409.6 5315.3 5327.3 5369.1 5452.3 3530.0 3639.3 3658.9 3734.4 3741.2 914.3 1081.4 1085.2 1093.2 1112.0 11560.6 11638.1 11703.5 11736.9 11779.4 4543.0 4628.6 4821.3 4828.1 4841.8 8880.4 9655.3 9898.6 9984.4 10015.4 5386.8 5397.9 5400.3 5412.9 5422.3 4455.1 4777.6 4825.8 4905.3 4946.4 3867.3 3953.7 3980.7 3993.8 4042.6 1282.5 1285.6 1290.9 1295.0 1300.1 717.8 747.9 951.7 958.4 962.2 5876.4 5973.2 5985.3 6114.7 6138.9 417.6 423.8 445.2 446.7 446.9 3908.7 4006.4 4124.1 4149.3 4171.2 10447.5 10659.5 10890.9 10915.5 10929.6 10613.5 10810.1 10877.8 10881.1 10925.6 6562.6 8224.1 8238.6 8251.8 8307.7 6079.5 6150.5 6168.9 6169.3 6298.1 10050.4 10263.6 10291.0 10295.5 10342.4 8645.9 8723.4 9755.3 10318.3 10354.1 6691.7 6996.7 7430.4 7445.4 7663.7 8432.6 8482.1 8489.8 8491.2 8508.8 7357.7 7420.0 7554.8 7644.4 7673.7 7324.6 7472.7 7483.7 7569.8 7640.1 6803.3 6929.4 6941.3 6952.0 6979.9 4068.4 4967.9 5108.5 5149.8 5219.2 5025.8 5144.5 5151.8 5276.7 5295.4 22:44:01 INFO - PROCESS | 1658 | 22:44:01 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4751.82 Median:5274.64 stddev:1463.79 (27.8%) stddev-sans-first:1428.47 22:44:01 INFO - PROCESS | 1658 | Values: 255.9 301.4 304.9 305.2 309.5 4765.0 5672.5 5756.7 5773.7 5838.4 4392.7 5634.1 5656.3 5657.3 5698.9 4045.4 4153.4 4245.2 4258.2 4292.7 3429.4 3800.3 4249.7 4259.9 4276.0 3152.7 3984.6 4233.8 4238.2 4248.3 4092.7 4209.9 4246.7 4266.7 4290.1 3685.0 3686.5 3691.9 3692.2 3712.5 5398.5 5482.5 5483.5 5505.6 5509.6 3882.6 4823.0 4934.5 4939.7 4944.3 5025.5 5084.3 5966.0 5967.8 5979.3 3777.7 4101.1 4108.7 4112.0 4118.4 4897.7 5297.3 5616.7 5908.4 6238.6 3642.2 4139.2 4141.8 4143.5 4167.9 4216.6 4221.4 4222.4 4228.5 4236.8 4167.0 4556.0 4781.8 4856.2 4933.5 5250.9 5895.6 5933.8 5989.7 5991.5 1389.0 1444.6 1447.6 1449.8 1454.7 748.9 807.8 872.9 877.9 886.1 4949.3 5046.1 5201.0 5465.4 5534.7 5141.7 5696.9 6131.6 6174.9 6209.8 5200.7 5251.9 5318.8 5427.6 5430.8 3195.6 3211.3 3215.5 3218.7 3235.1 5822.4 6109.8 6329.9 6347.4 6375.0 5440.4 5777.8 5788.7 6130.4 6310.7 5380.7 5796.1 5846.9 6015.9 6392.0 4664.4 5109.5 5410.9 6137.9 6158.4 5540.4 6076.4 6083.1 6104.7 6147.4 5584.7 6257.3 6321.4 6332.8 6404.6 3683.8 3924.3 4012.2 4029.1 4042.6 5355.9 6135.3 6150.2 6153.7 6184.4 4593.7 5651.1 5713.7 6001.5 6022.5 5192.7 5197.8 5953.7 5972.0 5972.2 5112.9 5209.1 5445.2 5560.4 5885.8 5100.4 5701.3 5914.0 5955.6 5993.0 5069.4 5475.0 5838.4 5850.6 5854.6 22:44:01 INFO - PROCESS | 1658 | 22:44:01 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:25219.48 Median:13133.35 stddev:40535.65 (308.6%) stddev-sans-first:40607.53 22:44:01 INFO - PROCESS | 1658 | Values: 692.5 713.2 743.8 783.2 788.5 15009.2 15460.1 15793.4 15806.1 15843.5 11684.2 11957.3 14398.9 14428.6 14434.9 668.6 828.6 835.5 846.4 848.1 860.5 866.4 867.3 872.6 874.5 873.2 890.4 915.8 915.9 919.5 883.8 896.9 906.8 907.8 913.1 676.2 677.0 678.1 678.8 679.0 14689.0 14696.6 14736.8 14741.9 14769.3 14500.1 14532.8 14648.9 14757.7 14774.9 12011.6 12267.3 12512.4 12532.4 12620.2 3728.5 3757.1 3971.5 3971.7 3973.6 118818.0 119763.0 120035.4 120230.0 124723.4 11144.8 11173.6 11306.9 11341.8 11664.7 22817.5 23055.9 23072.0 23076.5 23122.8 8084.3 8157.6 8162.3 8231.2 8278.7 3681.8 4421.9 4539.8 4557.4 4601.5 3143.9 3600.2 3724.4 3728.3 3876.3 642.9 732.5 734.5 744.7 748.3 560.4 600.8 630.2 659.8 682.4 9512.0 9663.8 9695.0 9715.3 9950.9 603.2 639.1 683.1 693.1 698.2 4643.3 4813.0 4825.7 4856.9 4875.6 107207.9 148719.3 155951.2 158205.0 160726.0 118358.0 145785.0 146249.3 149571.0 150163.0 68831.0 71208.0 75238.6 102022.0 122753.0 9506.4 9787.6 9996.2 10109.8 10143.0 60655.5 63632.4 64774.7 64975.7 66710.3 90350.5 96175.0 100063.5 100158.5 100559.5 13112.3 13216.9 13299.1 13432.1 13837.3 15063.6 15066.5 15416.4 15494.8 15666.9 13670.9 13737.1 13871.9 13895.8 14236.2 13419.9 13642.7 14083.1 14099.4 14153.7 13328.3 13625.9 13666.6 13757.9 14130.4 11048.5 11471.9 11480.4 11509.0 11707.5 9501.5 11343.8 11613.9 11788.9 12276.2 22:44:01 INFO - PROCESS | 1658 | -------- Summary: end -------- 22:44:01 INFO - PROCESS | 1658 | 22:44:02 INFO - TEST-INFO | 1658: exit 0 22:44:02 INFO - TEST-OK | dromaeo_css | took 1114575ms 22:44:02 INFO - TEST-START | kraken 22:44:02 INFO - operating with platform_type : mac_ 22:44:02 INFO - Initialising browser for kraken test... 22:44:02 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmp29q9Tv/profile http://localhost:49271/getInfo.html) 22:44:05 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 22:44:05 INFO - PROCESS | 1668 | colorDepth:24 22:44:05 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 22:44:05 INFO - PROCESS | 1668 | __metrics 22:44:05 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:44:06 INFO - TEST-INFO | 1668: exit 0 22:44:06 INFO - Browser initialized. 22:44:06 INFO - Running cycle 1/1 for kraken test... 22:44:06 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmp29q9Tv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:44:18 INFO - PROCESS | 1670 | RSS: Main: 254545920 22:44:18 INFO - PROCESS | 1670 | 22:44:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 22:44:22 INFO - PROCESS | 1670 | RSS: Main: 267345920 22:44:22 INFO - PROCESS | 1670 | 22:44:24 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 22:44:25 INFO - PROCESS | 1670 | RSS: Main: 275976192 22:44:25 INFO - PROCESS | 1670 | 22:44:29 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 22:44:29 INFO - PROCESS | 1670 | RSS: Main: 279142400 22:44:29 INFO - PROCESS | 1670 | 22:44:31 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 22:44:32 INFO - PROCESS | 1670 | RSS: Main: 281444352 22:44:32 INFO - PROCESS | 1670 | 22:44:34 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:44:35 INFO - PROCESS | 1670 | RSS: Main: 281886720 22:44:35 INFO - PROCESS | 1670 | 22:44:37 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 22:44:38 INFO - PROCESS | 1670 | RSS: Main: 282222592 22:44:38 INFO - PROCESS | 1670 | 22:44:41 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 22:44:42 INFO - PROCESS | 1670 | RSS: Main: 281899008 22:44:42 INFO - PROCESS | 1670 | 22:44:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 22:44:46 INFO - PROCESS | 1670 | RSS: Main: 282681344 22:44:46 INFO - PROCESS | 1670 | 22:44:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:44:48 INFO - PROCESS | 1670 | RSS: Main: 282402816 22:44:48 INFO - PROCESS | 1670 | 22:44:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:44:50 INFO - PROCESS | 1670 | RSS: Main: 282562560 22:44:50 INFO - PROCESS | 1670 | 22:44:53 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:44:53 INFO - PROCESS | 1670 | RSS: Main: 282828800 22:44:53 INFO - PROCESS | 1670 | 22:44:56 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:44:57 INFO - PROCESS | 1670 | RSS: Main: 283033600 22:44:57 INFO - PROCESS | 1670 | 22:45:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:45:00 INFO - PROCESS | 1670 | RSS: Main: 282857472 22:45:00 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 22:45:02 INFO - PROCESS | 1670 | RSS: Main: 283840512 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | __start_tp_report 22:45:02 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 22:45:02 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 22:45:02 INFO - PROCESS | 1670 | |i|pagename|runs| 22:45:02 INFO - PROCESS | 1670 | |0;ai-astar;125;171;146;117;118;117;116;121;126;116 22:45:02 INFO - PROCESS | 1670 | |1;audio-beat-detection;137;127;166;159;107;130;129;115;159;122 22:45:02 INFO - PROCESS | 1670 | |2;audio-dft;202;219;258;244;198;199;197;199;197;201 22:45:02 INFO - PROCESS | 1670 | |3;audio-fft;69;82;75;148;63;85;83;60;132;60 22:45:02 INFO - PROCESS | 1670 | |4;audio-oscillator;68;73;126;101;103;96;118;71;74;116 22:45:02 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;91;89;99;88;92;88;93;88;90;88 22:45:02 INFO - PROCESS | 1670 | |6;imaging-darkroom;96;100;96;95;98;96;95;96;120;95 22:45:02 INFO - PROCESS | 1670 | |7;imaging-desaturate;146;142;92;97;111;139;151;128;136;145 22:45:02 INFO - PROCESS | 1670 | |8;json-parse-financial;55;59;68;78;87;83;56;54;55;56 22:45:02 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;67;70;74;71;70;78;74;71;71;70 22:45:02 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;95;107;106;83;82;86;84;97;75;74 22:45:02 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;124;168;149;161;146;154;131;161;221;165 22:45:02 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;168;171;168;166;168;168;168;168;166;164 22:45:02 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;66;75;90;86;69;67;67;65;67;67 22:45:02 INFO - PROCESS | 1670 | __end_tp_report 22:45:02 INFO - PROCESS | 1670 | __start_cc_report 22:45:02 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1046 22:45:02 INFO - PROCESS | 1670 | __end_cc_report 22:45:02 INFO - PROCESS | 1670 | __startTimestamp1470807902930__endTimestamp 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | ------- Summary: start ------- 22:45:02 INFO - PROCESS | 1670 | Number of tests: 14 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:127.30 Median:123.00 stddev:17.83 (14.5%) stddev-sans-first:18.89 22:45:02 INFO - PROCESS | 1670 | Values: 125.0 171.0 146.0 117.0 118.0 117.0 116.0 121.0 126.0 116.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:133.50 stddev:19.99 (15.0%) stddev-sans-first:21.19 22:45:02 INFO - PROCESS | 1670 | Values: 137.0 127.0 166.0 159.0 107.0 130.0 129.0 115.0 159.0 122.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:211.40 Median:201.50 stddev:22.09 (11.0%) stddev-sans-first:23.16 22:45:02 INFO - PROCESS | 1670 | Values: 202.0 219.0 258.0 244.0 198.0 199.0 197.0 199.0 197.0 201.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:85.70 Median:72.00 stddev:30.32 (42.1%) stddev-sans-first:31.56 22:45:02 INFO - PROCESS | 1670 | Values: 69.0 82.0 75.0 148.0 63.0 85.0 83.0 60.0 132.0 60.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:69.50 stddev:21.76 (31.3%) stddev-sans-first:20.84 22:45:02 INFO - PROCESS | 1670 | Values: 68.0 73.0 126.0 101.0 103.0 96.0 118.0 71.0 74.0 116.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:90.50 stddev:3.47 (3.8%) stddev-sans-first:3.68 22:45:02 INFO - PROCESS | 1670 | Values: 91.0 89.0 99.0 88.0 92.0 88.0 93.0 88.0 90.0 88.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:96.00 stddev:7.65 (8.0%) stddev-sans-first:8.05 22:45:02 INFO - PROCESS | 1670 | Values: 96.0 100.0 96.0 95.0 98.0 96.0 95.0 96.0 120.0 95.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:128.70 Median:145.50 stddev:21.25 (14.6%) stddev-sans-first:21.60 22:45:02 INFO - PROCESS | 1670 | Values: 146.0 142.0 92.0 97.0 111.0 139.0 151.0 128.0 136.0 145.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:63.50 stddev:12.93 (20.4%) stddev-sans-first:13.19 22:45:02 INFO - PROCESS | 1670 | Values: 55.0 59.0 68.0 78.0 87.0 83.0 56.0 54.0 55.0 56.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.00 stddev:3.03 (4.3%) stddev-sans-first:2.71 22:45:02 INFO - PROCESS | 1670 | Values: 67.0 70.0 74.0 71.0 70.0 78.0 74.0 71.0 71.0 70.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:83.50 stddev:11.80 (14.1%) stddev-sans-first:12.31 22:45:02 INFO - PROCESS | 1670 | Values: 95.0 107.0 106.0 83.0 82.0 86.0 84.0 97.0 75.0 74.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:158.00 Median:161.00 stddev:26.38 (16.4%) stddev-sans-first:24.94 22:45:02 INFO - PROCESS | 1670 | Values: 124.0 168.0 149.0 161.0 146.0 154.0 131.0 161.0 221.0 165.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:168.00 stddev:1.84 (1.1%) stddev-sans-first:1.94 22:45:02 INFO - PROCESS | 1670 | Values: 168.0 171.0 168.0 166.0 168.0 168.0 168.0 168.0 166.0 164.0 22:45:02 INFO - PROCESS | 1670 | 22:45:02 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:68.00 stddev:8.96 (13.2%) stddev-sans-first:9.25 22:45:02 INFO - PROCESS | 1670 | Values: 66.0 75.0 90.0 86.0 69.0 67.0 67.0 65.0 67.0 67.0 22:45:02 INFO - PROCESS | 1670 | -------- Summary: end -------- 22:45:02 INFO - PROCESS | 1670 | 22:45:03 INFO - TEST-INFO | 1670: exit 0 22:45:03 INFO - TEST-OK | kraken | took 61337ms 22:45:03 INFO - SUITE-END | took 1175s 22:45:04 INFO - Completed test suite (00:19:36) 22:45:04 INFO - javascript benchmark 22:45:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7405.144393185878, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3394.6944774970257, 3398.5751781027357, 3429.5027221052933, 3434.467657513086, 3436.707879830215, 5613.46672066154, 5815.4475324844125, 5852.736626851786, 5915.497182739472, 5933.406659334064, 5789.131630255474, 5790.015697331453, 5815.0, 5854.619449735765, 5918.0, 5189.040027594888, 5286.3392075990505, 5293.57651387888, 5362.731863406833, 5396.217548455472, 5411.18832175173, 5413.079776438015, 5421.0, 5455.481729235717, 5470.671759694411, 4536.228841097021, 5133.666311689765, 5206.947930520684, 5248.3701955765555, 5329.946700532983, 5195.766190521435, 5232.8691782705355, 5251.501107394792, 5268.5521730652945, 5322.840314790563, 5261.210818377274, 5283.181106928394, 5308.389535203424, 5323.600729945269, 5336.092864213054, 5712.200291959129, 5719.113537401709, 5811.941880581182, 5836.007878660638, 5885.058390657517, 6254.424359651827, 6297.086922396273, 6326.430621244064, 6342.175517182783, 6348.968255158741, 6306.085617585471, 6311.053341998691, 6321.241451025906, 6330.27201871786, 6331.525135614801, 5749.9425005750245, 5901.17383566301, 5940.138679891392, 5951.166836642873, 6055.364186760415, 6314.336994615591, 6337.873242535124, 6338.714757835908, 6343.809685709436, 6353.872922541524, 6290.465310448663, 6294.0, 6302.495800336053, 6309.526785491106, 6334.5249106317215, 6129.9701281150365, 6161.568690191643, 6216.160818289563, 6240.375962403723, 6383.585066970632, 6185.319614842364, 6210.484590228971, 6227.314995350508, 6253.0, 6255.937440625536, 6240.781572644935, 6265.8746825063245, 6323.588966717149, 6335.714892829834, 6341.239051313872, 4950.603951683856, 5007.949920500749, 5027.547520723152, 5067.037262920034, 5112.258722485293, 4444.755538445352, 4512.842050528216, 4553.817847286137, 4561.954380456219, 4572.017016341436, 5790.565707571949, 5800.564957628195, 5866.472017518444, 5870.26621672291, 5883.382244864313, 6170.81487555374, 6238.75044998195, 6255.155554000152, 6324.747010119636, 6326.082718005863, 5776.68228247442, 5788.942110578924, 5799.9130013049835, 5801.216835727208, 5872.911906321408, 5466.6993315367245, 5483.725813709299, 5495.560355171577, 5498.285222920995, 5498.81775418287, 6239.96880015597, 6296.842578935563, 6313.40022697843, 6349.9682501587195, 6364.1408409865, 6244.063390491463, 6271.78048768291, 6281.15204447412, 6286.339934306832, 6299.244090709144, 6336.493080553637, 6412.551121421456, 6419.133416988647, 6424.421802037839, 6429.196350456193, 6110.862540392742, 6183.536234782499, 6212.937870621236, 6239.313675495782, 6288.937110628835, 6121.663308518075, 6213.844653883508, 6273.341299163704, 6288.339724329063, 6303.527235457267, 6264.937350626617, 6270.247570291412, 6271.059341098871, 6277.1525844010475, 6312.14786003902, 5985.581009329305, 6115.179495343419, 6150.292716337678, 6275.058741188858, 6283.717232724447, 5938.0, 6100.145979562776, 6118.296395914527, 6130.8873626509085, 6133.172021777003, 6181.898154627448, 6200.937990620036, 6255.186825712628, 6263.123162757309, 6273.560850740404, 5909.793157239476, 5978.0, 6057.212562366864, 6104.664243466652, 6117.541184411098], "value": 5789.47282321346, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.9563247752377, 492.7413108118237, 541.136886665768, 554.1881144123855, 567.1747607231479, 36658.26683466332, 38073.619263807355, 40946.0, 41163.176736465255, 41795.16409671805, 41777.791111044404, 42245.0, 42268.57731422685, 42282.577174228245, 42418.36372454416, 9476.526173691305, 9667.468289244089, 9673.56468958895, 9700.029997000278, 9706.320557560974, 9571.617135314615, 9684.709458716214, 9685.564149613285, 9689.418634881895, 9700.417974921493, 9682.273829462783, 9693.0, 9710.465924374157, 9723.12491875731, 9738.464384458888, 9636.132748052674, 9677.274204434625, 9693.369930954465, 9695.563699633463, 9728.90271097287, 8034.1564135766075, 8268.421210515266, 9671.0, 9692.030796920322, 9705.369151005161, 39374.80312598447, 39459.01352466182, 39592.60407395918, 39902.0, 39932.60067399347, 3052.4810782167083, 3282.5404443377947, 3329.933401331984, 3444.121748954026, 3488.2674638325984, 29893.551596726065, 30061.398772024437, 30914.381712365852, 30959.916402925788, 30960.38079238425, 3139.5133754268127, 3144.591203143577, 3147.35479226758, 3173.9682603173906, 3240.270939038721, 404073.0, 406263.99999999703, 407750.0, 409544.0, 410528.9473552643, 33151.66848331486, 33265.66734332674, 33391.83304083464, 33400.1649958753, 33402.832985835405, 34468.82765586156, 34667.13332166665, 34699.30601388009, 35784.46323305152, 35954.28091438157, 16531.91734041322, 16705.83294167067, 16745.748813767805, 16752.329906803592, 16947.406840760515, 12697.682557935941, 12735.872641273654, 12747.42636581356, 12749.0, 12751.61745147647, 16666.583335416468, 19340.06599340055, 19466.825177200448, 19632.723872948212, 19794.307199247956, 8143.4575959457825, 8266.793330166794, 8273.46222495536, 8285.0, 8307.792305192297, 896.8385690575759, 918.609590923868, 1016.694991502561, 1036.8341065429495, 1090.705509512443, 30758.481639334143, 30934.917277895165, 31012.84493577563, 31368.686313137034, 31653.52519712206, 1052.55792567122, 1066.7919755647574, 1068.8076146293743, 1085.5494969587692, 1110.9834501431262, 15528.5341439757, 15613.60965975837, 15712.528624141294, 15929.203539822962, 16069.839301607068, 569987.1500642471, 673147.0, 674603.0, 676366.6181669158, 677229.2277077264, 60118.0, 60140.09789853156, 60665.0, 60954.39045609487, 63537.23470739713, 1110.5002748763088, 1334.3194970565082, 1370.171046516843, 1370.7327071221216, 1376.029898921258, 29512.557311639914, 30274.84862575761, 30461.23846903845, 30833.0, 30934.845325773225, 47053.29420058634, 47336.289955650005, 47507.28739068848, 47589.52410475851, 47660.0, 573607.0, 578715.0, 580257.0, 580306.0, 581075.0, 24175.51648967046, 26308.8684556576, 27001.729982699922, 27252.454950900476, 27325.1802445919, 45829.54170458386, 47132.7643361781, 48699.269510956656, 50255.74872125616, 50525.73685657741, 40899.38650920179, 40977.59022409857, 41057.589424105376, 42235.7888210557, 42628.93427664209, 34002.319953600294, 35515.82242088773, 39524.604753953245, 39535.0, 40553.59446405498, 36919.44620830636, 38718.80640596779, 39434.2113157741, 40723.0, 41271.58728412678, 34416.451259692876, 35168.12079698028, 35729.82135089308, 35818.0, 35830.82084589561, 26050.0, 26510.337241568344, 26711.599326010513, 26880.865595671898, 27105.0], "value": 19438.85044825999, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.7286380941914, 375.07169754856716, 377.6487866284356, 383.9980800096, 402.2819267607322, 17416.084230734625, 20627.865467399286, 20946.162153513862, 21412.357629271108, 21604.459888502795, 20078.0, 20643.864587447686, 20716.964151792425, 20760.584788304262, 20963.685544716842, 5748.655080695151, 5924.377940316259, 6064.696765161746, 6116.877662446748, 6151.7231724572275, 6050.848728781772, 6085.969570152143, 6091.208142941434, 6218.22272215973, 6485.3514648535, 6454.290028096929, 6514.153160089182, 6540.051692504584, 6548.770793022245, 6572.967135164317, 6169.413905678954, 6422.26143993442, 6495.188101487314, 6554.737810487575, 6559.376859198393, 5684.943150568524, 5831.679257640828, 5884.440978107073, 5887.911681324784, 5888.263967004124, 12908.54820081302, 12946.158499697583, 22645.660315095287, 25499.107531236503, 25810.612840807404, 2116.2804646420136, 2126.26643807886, 2160.5786871560053, 2164.426426996847, 2340.3564019894493, 8242.793930151794, 9868.753281167956, 9874.111329980264, 10046.792602312382, 12072.21530600517, 1805.4493379519242, 1834.5321942904475, 1852.8980906050163, 1872.6535590915723, 1882.830545250934, 118057.81942180554, 120098.0, 122132.0, 122637.77362226442, 124421.0, 11221.158413119048, 11293.604723834625, 11454.713632159262, 11457.484413201426, 11684.532618695326, 14340.713185736435, 14356.712865742835, 14382.0, 14402.351894164787, 14411.42354305837, 8205.302549283258, 8389.496630202208, 8469.237768600855, 8571.914280857112, 8614.0, 8394.496330220207, 8401.0, 8425.4944703318, 8509.97880254361, 8558.0, 3808.771473711586, 4168.103857670615, 4376.846810361622, 4491.326301054868, 4557.609928971629, 1878.2393130782054, 1937.9224831006884, 1939.951501212481, 1943.1158822735904, 1949.756280464942, 827.98344033119, 879.3932186791094, 959.2901253072675, 969.6412327438893, 988.8912219655832, 19774.01129943497, 20456.386308410772, 20511.4872128198, 20518.179272829217, 20646.27738029192, 846.5597889097635, 880.6345366672887, 891.3270480786964, 927.596495524449, 995.5271246158162, 8804.47173169612, 10797.190210734227, 10889.455527223607, 11033.282836615594, 11109.611163609236, 487607.0, 495718.0, 575471.0, 585070.0, 599974.0, 98205.0, 120346.3982680081, 120981.39509302573, 123809.0, 123932.76067239392, 986.4919566423298, 1006.7785087280787, 1017.8524114003428, 1023.181454836143, 1029.8867124616286, 17999.820001800337, 18484.81515184831, 18533.629327413644, 18642.813571864106, 18648.533786655444, 54099.72950135224, 55093.0, 58141.4185858136, 58208.4179158203, 58353.0, 76496.0, 82839.0, 84448.0, 85243.5737821307, 85693.57153214194, 6252.937470625236, 7461.701531938662, 7806.258405451473, 8156.877646835183, 8330.958345208235, 11370.69237037718, 11679.240849344766, 13137.0, 13466.922646188086, 13625.727485450434, 11369.658910232707, 11402.48688808989, 11621.535138594361, 11668.474918628512, 11743.647690569296, 10814.0, 11384.203105782515, 11475.827862582235, 11553.653390398302, 11644.88355116438, 11131.944340278247, 11699.707507312045, 11912.285262884254, 11949.283043017447, 11980.520779168735, 7380.741674041383, 7384.667689954073, 7536.924630753622, 7539.208383119693, 7552.244775522404, 9991.700248992542, 11426.771464570827, 11655.53377864876, 11814.763704726029, 11916.0], "value": 9536.06480062592, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1492.5820770184346, 1532.8160620725512, 1718.5102245859932, 1727.093276030084, 1870.158428707082, 8003.1596682348245, 8494.235518803314, 8665.133486651332, 8984.550772461374, 8988.41575297606, 8694.347923905701, 8760.737177884657, 8898.243649289818, 8901.376903616734, 8909.509976951282, 2333.8366314358045, 2351.2946116165167, 2368.7275963264187, 2372.9406764830933, 2377.215518878766, 2326.581215381231, 2362.6574146748712, 2372.0037584214583, 2376.0733314007553, 2377.5958087125146, 2349.5066036132434, 2353.5528249632603, 2367.3963139399516, 2370.573296806574, 2412.155745489082, 2051.569170474202, 2248.932532024042, 2343.496148328109, 2343.6953196084487, 2346.847454915442, 2345.882705864717, 2347.2019513365367, 2349.3539276698875, 2350.9647355289685, 2359.504504054151, 8845.0, 8877.245434138105, 8923.910760892373, 8940.240079593243, 8981.506017169053, 4409.581089796464, 5315.309009828737, 5327.254184414185, 5369.060414427459, 5452.263944367499, 3529.9763068710045, 3639.344917914774, 3658.9451158232646, 3734.3838266685966, 3741.2330472253125, 914.3371055984425, 1081.3674000709593, 1085.2403317677579, 1093.2456604942565, 1112.0, 11560.595379161688, 11638.06895448362, 11703.473343699554, 11736.882631173665, 11779.411029448578, 4543.0, 4628.60656844172, 4821.276808478756, 4828.106800241966, 4841.806327746921, 8880.422772519765, 9655.324127311022, 9898.604055837686, 9984.400935943866, 10015.39907605546, 5386.8383948481605, 5397.892042159135, 5400.324959380077, 5412.91880621791, 5422.267993820863, 4455.086707225011, 4777.570018698334, 4825.758712064383, 4905.337779399807, 4946.3817022872145, 3867.2652196082654, 3953.743006704555, 3980.7213495055066, 3993.8003099845473, 4042.575529569353, 1282.51264519482, 1285.562908611058, 1290.8515520715239, 1294.9935250323688, 1300.14840279617, 717.7703134996856, 747.8579069976687, 951.743029382063, 958.434523631061, 962.2013728605241, 5876.382979787147, 5973.223480947449, 5985.311689155803, 6114.6636934969, 6138.877222455526, 417.638742487752, 423.8368228232153, 445.2030864752122, 446.7453551475693, 446.8994476242884, 3908.710384692013, 4006.419069235003, 4124.092699606082, 4149.25313443577, 4171.186618609342, 10447.477626118663, 10659.520321585547, 10890.945545272223, 10915.508802103925, 10929.617463388902, 10613.469326533797, 10810.135189184846, 10877.83683244752, 10881.12950963921, 10925.562977480811, 6562.606243625398, 8224.095349511548, 8238.588070596446, 8251.8349633007, 8307.667693292322, 6079.483243924139, 6150.477209437248, 6168.876622467616, 6169.321374648695, 6298.08677741734, 10050.396976181453, 10263.58945642166, 10291.022352876465, 10295.485225738832, 10342.431166285927, 8645.919260092489, 8723.432976856484, 9755.268354873271, 10318.277720559488, 10354.1198998086, 6691.732330706912, 6996.650167491707, 7430.368418684472, 7445.4415918805225, 7663.693452261847, 8432.578371081545, 8482.109378515413, 8489.787755305919, 8491.15088491146, 8508.787280318042, 7357.742479013209, 7419.961205431136, 7554.848903022019, 7644.426668000034, 7673.731419400294, 7324.597147156957, 7472.731724134581, 7483.738069167553, 7569.826676865095, 7640.083190017233, 6803.25164231944, 6929.44564434834, 6941.34057264559, 6951.9652401739695, 6979.930200697928, 4068.3694027425795, 4967.875803104806, 5108.514691104339, 5149.819756308511, 5219.19102539107, 5025.812404918132, 5144.485551444825, 5151.793928242828, 5276.736163191748, 5295.364556253275], "value": 4511.349528309606, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.86311323441956, 301.4363140926468, 304.8704300672214, 305.21864028088095, 309.5233340655391, 4764.976175119124, 5672.517835983941, 5756.7409466574, 5773.740181691829, 5838.365257727833, 4392.692511524195, 5634.12671035989, 5656.2929633795775, 5657.2928383952, 5698.857528561778, 4045.4336392904943, 4153.439285696438, 4245.172191422674, 4258.233517966764, 4292.6780491463105, 3429.4341433663417, 3800.3159431302356, 4249.745015299092, 4259.914801703964, 4276.0, 3152.7162555370014, 3984.641382275609, 4233.767142807144, 4238.237117318881, 4248.25655510286, 4092.6876314882293, 4209.873703788891, 4246.723962942398, 4266.658667306608, 4290.056187638715, 3685.0, 3686.502322186497, 3691.9261614767556, 3692.1877187018813, 3712.4988126603103, 5398.541124004425, 5482.479164479368, 5483.464629903633, 5505.642133261325, 5509.586780991402, 3882.6311500407414, 4822.9984556409045, 4934.481879402683, 4939.703617782944, 4944.258361245806, 5025.522575355335, 5084.262781896641, 5966.0, 5967.762089216544, 5979.312379076418, 3777.697784177259, 4101.097758493127, 4108.67130629549, 4111.958880411187, 4118.423420721101, 4897.730624815598, 5297.337832770903, 5616.691081990489, 5908.409159084057, 6238.625682459067, 3642.18050938542, 4139.234241665302, 4141.772202528829, 4143.523494798076, 4167.854125105607, 4216.64158546527, 4221.387898754663, 4222.387753775747, 4228.492580890313, 4236.830526778895, 4166.958260434892, 4556.0, 4781.784819683123, 4856.17445034345, 4933.531314525115, 5250.868728281824, 5895.587308888336, 5933.762649494059, 5989.670568118708, 5991.460768530853, 1388.9513867014607, 1444.5810714892773, 1447.5657302809116, 1449.8405175430905, 1454.6508837879044, 748.8951546783454, 807.7536351412758, 872.8952525696957, 877.9341549383822, 886.1094599927161, 4949.257611358325, 5046.142155833444, 5201.011807756514, 5465.371482279589, 5534.667919924817, 5141.717205553656, 5696.943030569724, 6131.570790044654, 6174.9073763893575, 6209.844753881099, 5200.699825043739, 5251.947480525222, 5318.840434786963, 5427.620066595379, 5430.755615997289, 3195.5845740053646, 3211.341674956628, 3215.5498230247786, 3218.742500599946, 3235.0589646212557, 5822.388649191774, 6109.7861574844665, 6329.873402531924, 6347.365263473615, 6375.023742639796, 5440.42875498067, 5777.786664800355, 5788.652680839163, 6130.448259656652, 6310.716017779211, 5380.704061276668, 5796.1305804129715, 5846.941530584725, 6015.901810567649, 6391.96804015977, 4664.39362882823, 5109.540141387293, 5410.945890541044, 6137.907931380943, 6158.353372895782, 5540.418256083053, 6076.361981991828, 6083.080121541596, 6104.680221515796, 6147.415995480422, 5584.664920104807, 6257.311695713377, 6321.431071203429, 6332.810015699352, 6404.647744374104, 3683.8027641016242, 3924.313245182139, 4012.177503611694, 4029.133736246721, 4042.6053011710915, 5355.892882142413, 6135.325114237519, 6150.169727086786, 6153.661548614869, 6184.350643182401, 4593.6554758392585, 5651.124075768263, 5713.714314284186, 6001.519878409804, 6022.488088512526, 5192.714400707846, 5197.778522047392, 5953.690188158598, 5972.02923035406, 5972.20833749882, 5112.906691451618, 5209.072643121917, 5445.156000819879, 5560.443955604569, 5885.764569417175, 5100.438951715234, 5701.346609483369, 5913.970430147821, 5955.583109182316, 5993.0, 5069.391672999263, 5474.972625136849, 5838.423625621143, 5850.619709718854, 5854.594897224721], "value": 4273.370281886064, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.4910191009609, 713.2047766740086, 743.8103283662665, 783.1581050370853, 788.546585713215, 15009.174495402749, 15460.149691766948, 15793.368265269404, 15806.13066281354, 15843.524694259162, 11684.240524365932, 11957.342346170957, 14398.928005359958, 14428.639284017905, 14434.85565144351, 668.6188872342767, 828.5857071464268, 835.456952980563, 846.4371193156544, 848.0544193224572, 860.5482121885997, 866.3588944181323, 867.2584939876409, 872.6422166911567, 874.5408660453281, 873.1617647058831, 890.3678388344251, 915.8488849339851, 915.9175674189322, 919.4667093086027, 883.8497455432592, 896.8654701794718, 906.7642412972608, 907.795745957161, 913.0595486648763, 676.2020815437781, 676.9593824370504, 678.13199105145, 678.7646483400214, 678.9660516974131, 14689.045212061288, 14696.632584185374, 14736.778948315783, 14741.926290368587, 14769.261536923219, 14500.129992200396, 14532.782008269884, 14648.926755366261, 14757.704845903128, 14774.926125369411, 12011.57959471423, 12267.325297108655, 12512.436940337706, 12532.436040378205, 12620.17968832023, 3728.4780130781714, 3757.079515518688, 3971.5035620547433, 3971.702122340836, 3973.602639736032, 118818.0, 119763.0, 120035.3998230012, 120230.0, 124723.3763831184, 11144.777104457866, 11173.553057877756, 11306.886931130583, 11341.773164536664, 11664.6500604982, 22817.543649126925, 23055.884720576625, 23072.0, 23076.53846923052, 23122.76877231206, 8084.312833409109, 8157.592120393957, 8162.265396114378, 8231.218034286732, 8278.710245141512, 3681.7975011374097, 4421.872422532234, 4539.750313732777, 4557.361969324297, 4601.470830854428, 3143.937121257562, 3600.225951420457, 3724.366857634209, 3728.291624591319, 3876.341022026262, 642.9260635026939, 732.487258918759, 734.4528326396869, 744.739341230565, 748.3190296829858, 560.3751816724405, 600.7807150390164, 630.196499463179, 659.7525927777084, 682.4199430484049, 9512.0, 9663.758406039906, 9695.0, 9715.27135464843, 9950.850737238947, 603.2128072864954, 639.1339734659563, 683.1017212365723, 693.0905355744991, 698.2040473859804, 4643.257078867365, 4812.975935120372, 4825.708006964746, 4856.927146092811, 4875.634327425457, 107207.92792072135, 148719.2564037173, 155951.22024390032, 158205.0, 160726.0, 118358.0, 145785.0, 146249.26875365767, 149571.0, 150163.0, 68831.0, 71208.0, 75238.62380687952, 102022.0, 122753.0, 9506.382085164441, 9787.608495660379, 9996.200303975518, 10109.848352274868, 10143.0, 60655.48361291003, 63632.3636763645, 64774.676126619066, 64975.67512162409, 66710.33289667235, 90350.54824725834, 96175.0, 100063.49968250403, 100158.4992075035, 100559.49160762448, 13112.278824664354, 13216.933915330363, 13299.135556189203, 13432.059755817, 13837.30813459303, 15063.623409414853, 15066.54800355991, 15416.383344666088, 15494.767578486557, 15666.921665391601, 13670.93164534171, 13737.107088039245, 13871.930640346734, 13895.791563126359, 14236.217008064656, 13419.932900335827, 13642.658933526742, 14083.084599501, 14099.436022558983, 14153.716925661634, 13328.33358332099, 13625.93187034098, 13666.590002299947, 13757.862421375658, 14130.434782608581, 11048.462347872648, 11471.885281147082, 11480.368579727861, 11509.0, 11707.473163707824, 9501.477418742035, 11343.829842552203, 11613.941930290295, 11788.941055294668, 12276.202046866925], "value": 7968.966474657433, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 171.0, 146.0, 117.0, 118.0, 117.0, 116.0, 121.0, 126.0, 116.0], "value": 127.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 127.0, 166.0, 159.0, 107.0, 130.0, 129.0, 115.0, 159.0, 122.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 219.0, 258.0, 244.0, 198.0, 199.0, 197.0, 199.0, 197.0, 201.0], "value": 211.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 82.0, 75.0, 148.0, 63.0, 85.0, 83.0, 60.0, 132.0, 60.0], "value": 85.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 126.0, 101.0, 103.0, 96.0, 118.0, 71.0, 74.0, 116.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 99.0, 88.0, 92.0, 88.0, 93.0, 88.0, 90.0, 88.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 100.0, 96.0, 95.0, 98.0, 96.0, 95.0, 96.0, 120.0, 95.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 142.0, 92.0, 97.0, 111.0, 139.0, 151.0, 128.0, 136.0, 145.0], "value": 128.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 68.0, 78.0, 87.0, 83.0, 56.0, 54.0, 55.0, 56.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 74.0, 71.0, 70.0, 78.0, 74.0, 71.0, 71.0, 70.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 107.0, 106.0, 83.0, 82.0, 86.0, 84.0, 97.0, 75.0, 74.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 168.0, 149.0, 161.0, 146.0, 154.0, 131.0, 161.0, 221.0, 165.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 171.0, 168.0, 166.0, 168.0, 168.0, 168.0, 168.0, 166.0, 164.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 90.0, 86.0, 69.0, 67.0, 67.0, 65.0, 67.0, 67.0], "value": 71.9, "unit": "score"}]}]} 22:45:04 INFO - Return code: 0 22:45:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:45:04 ERROR - # TBPL SUCCESS # 22:45:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:45:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:45:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:45:04 INFO - 2016-08-09 22:45:04.271 screenresolution[1675:10361] starting screenresolution argv=screenresolution get 22:45:04 INFO - 2016-08-09 22:45:04.274 screenresolution[1675:10361] Display 0: 1600x1200x32@60 22:45:04 INFO - 2016-08-09 22:45:04.280 screenresolution[1676:10364] starting screenresolution argv=screenresolution list 22:45:04 INFO - Available Modes on Display 0 22:45:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:45:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:45:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:45:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:45:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:45:04 INFO - Intel Iris: 22:45:04 INFO - Chipset Model: Intel Iris 22:45:04 INFO - Type: GPU 22:45:04 INFO - Bus: Built-In 22:45:04 INFO - VRAM (Dynamic, Max): 1536 MB 22:45:04 INFO - Vendor: Intel (0x8086) 22:45:04 INFO - Device ID: 0x0a2e 22:45:04 INFO - Revision ID: 0x0009 22:45:04 INFO - Displays: 22:45:04 INFO - Display: 22:45:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:45:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:45:04 INFO - Display Serial Number: 1600x1200 60 22:45:04 INFO - Main Display: Yes 22:45:04 INFO - Mirror: Off 22:45:04 INFO - Online: Yes 22:45:04 INFO - Rotation: Supported 22:45:04 INFO - Return code: 0 22:45:04 INFO - Running post-action listener: _resource_record_post_action 22:45:04 INFO - [mozharness: 2016-08-10 05:45:04.525429Z] Finished run-tests step (success) 22:45:04 INFO - Running post-run listener: _resource_record_post_run 22:45:04 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38384640; Write bytes: 701722112; Read time: 377; Write time: 1948 22:45:04 INFO - TinderboxPrint: CPU usage
54.3% 22:45:04 INFO - TinderboxPrint: I/O read bytes / time
38,384,640 / 377 22:45:04 INFO - TinderboxPrint: I/O write bytes / time
701,722,112 / 1,948 22:45:04 INFO - TinderboxPrint: CPU idle
2,211.5 (45.7%) 22:45:04 INFO - TinderboxPrint: CPU system
977.5 (20.2%) 22:45:04 INFO - TinderboxPrint: CPU user
1,652.6 (34.1%) 22:45:04 INFO - TinderboxPrint: Swap in / out
441,704,448 / 0 22:45:04 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399045120; Write bytes: 392840704; Read time: 29669; Write time: 705 22:45:04 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36283392; Write bytes: 295020544; Read time: 353; Write time: 1211 22:45:04 INFO - Running post-run listener: _upload_blobber_files 22:45:04 INFO - Blob upload gear active. 22:45:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:45:04 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 22:45:04 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:45:04 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:45:05 INFO - (blobuploader) - INFO - Open directory for files ... 22:45:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:45:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:45:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:45:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:45:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:45:06 INFO - (blobuploader) - INFO - Done attempting. 22:45:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:45:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:45:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:45:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:45:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:45:07 INFO - (blobuploader) - INFO - Done attempting. 22:45:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:45:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:45:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:45:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:45:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:45:10 INFO - (blobuploader) - INFO - Done attempting. 22:45:10 INFO - (blobuploader) - INFO - Iteration through files over. 22:45:10 INFO - Return code: 0 22:45:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:45:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:45:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cea71648121b57f267737dd6971e248677c03695f8fe64a7bac4af96ba722addefe16ee0f52894398312c09aa6f872d8e0e2fdc6081ff5b45c1ce3fe345176a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb8a3c6dccbe985263e81ac97d65dca27f08d2c1da2a2767a7e15bc57a83c7827e789a05ad77da7426420addad75fe0817204b809cdf4ee77687c786c6d0cc12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0926d0136b663cedaaf14079dbb9792a10249215e17f78da953d0707d7ce1e9d6190c3ed13bf1517dee330f1f895e96176c248b45e0385bbb96c94c6df71ac2a"} 22:45:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:45:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:45:10 INFO - Contents: 22:45:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cea71648121b57f267737dd6971e248677c03695f8fe64a7bac4af96ba722addefe16ee0f52894398312c09aa6f872d8e0e2fdc6081ff5b45c1ce3fe345176a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb8a3c6dccbe985263e81ac97d65dca27f08d2c1da2a2767a7e15bc57a83c7827e789a05ad77da7426420addad75fe0817204b809cdf4ee77687c786c6d0cc12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0926d0136b663cedaaf14079dbb9792a10249215e17f78da953d0707d7ce1e9d6190c3ed13bf1517dee330f1f895e96176c248b45e0385bbb96c94c6df71ac2a"} 22:45:10 INFO - Running post-run listener: copy_logs_to_upload_dir 22:45:10 INFO - Copying logs to upload dir... 22:45:10 INFO - mkdir: /builds/slave/test/build/upload/logs 22:45:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.281531 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-09 22:45:10.290808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:10.295699) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jF6SJq04c4/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cea71648121b57f267737dd6971e248677c03695f8fe64a7bac4af96ba722addefe16ee0f52894398312c09aa6f872d8e0e2fdc6081ff5b45c1ce3fe345176a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb8a3c6dccbe985263e81ac97d65dca27f08d2c1da2a2767a7e15bc57a83c7827e789a05ad77da7426420addad75fe0817204b809cdf4ee77687c786c6d0cc12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0926d0136b663cedaaf14079dbb9792a10249215e17f78da953d0707d7ce1e9d6190c3ed13bf1517dee330f1f895e96176c248b45e0385bbb96c94c6df71ac2a"} build_url:https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/Af2QorArRnSkdLMlU0uzWA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cea71648121b57f267737dd6971e248677c03695f8fe64a7bac4af96ba722addefe16ee0f52894398312c09aa6f872d8e0e2fdc6081ff5b45c1ce3fe345176a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb8a3c6dccbe985263e81ac97d65dca27f08d2c1da2a2767a7e15bc57a83c7827e789a05ad77da7426420addad75fe0817204b809cdf4ee77687c786c6d0cc12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0926d0136b663cedaaf14079dbb9792a10249215e17f78da953d0707d7ce1e9d6190c3ed13bf1517dee330f1f895e96176c248b45e0385bbb96c94c6df71ac2a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:10.328470) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:10.328786) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jF6SJq04c4/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.gWJHQCtJZK/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005168 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:10.376749) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 22:45:10.377022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 22:45:10.377325) ========= ========= Total master_lag: 0.37 =========