builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0323 starttime: 1475782783.81 results: success (0) buildid: 20161006092200 builduid: f105651e5ee840aeb2e9574c3bff9ce7 revision: 7ff5c0b99e1936648eb6209703b8670da757f851 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 12:39:43.806599) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 12:39:43.807024) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 12:39:43.807306) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 12:39:44.020176) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-06 12:39:44.020466) ========= 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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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-10-06 12:39:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.9M=0.001s 2016-10-06 12:39:44 (17.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.290040 ========= master_lag: 5.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-06 12:39:49.358007) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-10-06 12:39:49.358349) ========= 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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.027688 ========= master_lag: 1.62 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-10-06 12:39:51.005006) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-06 12:39:51.005477) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7ff5c0b99e1936648eb6209703b8670da757f851 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 7ff5c0b99e1936648eb6209703b8670da757f851 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ijHgTd43ts/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.Q2kWNOTpMj/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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-10-06 12:39:51,160 truncating revision to first 12 chars 2016-10-06 12:39:51,161 Setting DEBUG logging. 2016-10-06 12:39:51,161 attempt 1/10 2016-10-06 12:39:51,161 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7ff5c0b99e19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-06 12:39:52,450 unpacking tar archive at: fx-team-7ff5c0b99e19/testing/mozharness/ program finished with exit code 0 elapsedTime=1.547942 ========= master_lag: 0.48 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-06 12:39:53.037496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 12:39:53.037900) ========= script_repo_revision: 7ff5c0b99e1936648eb6209703b8670da757f851 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 12:39:53.038349) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 5 secs) (at 2016-10-06 12:39:53.038628) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 5 secs) (at 2016-10-06 12:39:58.772082) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-06 12:39:58.772464) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ijHgTd43ts/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.Q2kWNOTpMj/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:39:58 INFO - MultiFileLogger online at 20161006 12:39:58 in /builds/slave/test 12:39:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:39:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:39:58 INFO - {'append_to_log': False, 12:39:58 INFO - 'base_work_dir': '/builds/slave/test', 12:39:58 INFO - 'blob_upload_branch': 'Fx-Team', 12:39:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:39:58 INFO - 'branch': 'Fx-Team', 12:39:58 INFO - 'buildbot_json_path': 'buildprops.json', 12:39:58 INFO - 'config_files': ('talos/mac_config.py',), 12:39:58 INFO - 'default_actions': ('clobber', 12:39:58 INFO - 'read-buildbot-config', 12:39:58 INFO - 'download-and-extract', 12:39:58 INFO - 'populate-webroot', 12:39:58 INFO - 'create-virtualenv', 12:39:58 INFO - 'install', 12:39:58 INFO - 'run-tests'), 12:39:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:39:58 INFO - 'download_minidump_stackwalk': True, 12:39:58 INFO - 'download_symbols': 'ondemand', 12:39:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:39:58 INFO - 'tooltool.py': '/tools/tooltool.py', 12:39:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:39:58 INFO - '/tools/misc-python/virtualenv.py')}, 12:39:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:39:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:39:58 INFO - 'installer_path': 'installer.exe', 12:39:58 INFO - 'log_level': 'info', 12:39:58 INFO - 'log_name': 'talos', 12:39:58 INFO - 'log_to_console': True, 12:39:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:39:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:39:58 INFO - 'opt_config_files': (), 12:39:58 INFO - 'pip_index': False, 12:39:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:39:58 INFO - 'cmd': ('bash', 12:39:58 INFO - '-c', 12:39:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:39:58 INFO - 'enabled': True, 12:39:58 INFO - 'halt_on_failure': False, 12:39:58 INFO - 'name': 'check_screen_resolution'},), 12:39:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:39:58 INFO - 'cmd': ('bash', 12:39:58 INFO - '-c', 12:39:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:39:58 INFO - 'enabled': True, 12:39:58 INFO - 'halt_on_failure': False, 12:39:58 INFO - 'name': 'check_screen_resolution'},), 12:39:58 INFO - 'run_cmd_checks_enabled': True, 12:39:58 INFO - 'sps_profile': False, 12:39:58 INFO - 'sps_profile_interval': 0, 12:39:58 INFO - 'suite': 'dromaeojs-e10s', 12:39:58 INFO - 'system_bits': '32', 12:39:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:39:58 INFO - 'title': 't-yosemite-r7-0323', 12:39:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:39:58 INFO - 'use_talos_json': True, 12:39:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:39:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:39:58 INFO - 'work_dir': 'build'} 12:39:58 INFO - [mozharness: 2016-10-06 19:39:58.967078Z] Running clobber step. 12:39:58 INFO - Running pre-action listener: _resource_record_pre_action 12:39:58 INFO - Running main action method: clobber 12:39:58 INFO - rmtree: /builds/slave/test/build 12:39:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:40:00 INFO - Running post-action listener: _resource_record_post_action 12:40:00 INFO - [mozharness: 2016-10-06 19:40:00.989774Z] Finished clobber step (success) 12:40:00 INFO - [mozharness: 2016-10-06 19:40:00.989941Z] Running read-buildbot-config step. 12:40:00 INFO - Running pre-action listener: _resource_record_pre_action 12:40:00 INFO - Running main action method: read_buildbot_config 12:40:00 INFO - Using buildbot properties: 12:40:00 INFO - { 12:40:00 INFO - "project": "", 12:40:00 INFO - "product": "firefox", 12:40:00 INFO - "script_repo_revision": "production", 12:40:00 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 12:40:00 INFO - "repository": "", 12:40:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 12:40:00 INFO - "buildid": "20161006092200", 12:40:00 INFO - "pgo_build": "False", 12:40:00 INFO - "basedir": "/builds/slave/test", 12:40:00 INFO - "buildnumber": 38, 12:40:00 INFO - "slavename": "t-yosemite-r7-0323", 12:40:00 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:40:00 INFO - "platform": "yosemite_r7", 12:40:00 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 12:40:00 INFO - "branch": "fx-team", 12:40:00 INFO - "repo_path": "integration/fx-team", 12:40:00 INFO - "revision": "7ff5c0b99e1936648eb6209703b8670da757f851", 12:40:00 INFO - "stage_platform": "macosx64", 12:40:00 INFO - "builduid": "f105651e5ee840aeb2e9574c3bff9ce7", 12:40:00 INFO - "slavebuilddir": "test" 12:40:00 INFO - } 12:40:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 12:40:00 INFO - Running post-action listener: _resource_record_post_action 12:40:00 INFO - [mozharness: 2016-10-06 19:40:00.993315Z] Finished read-buildbot-config step (success) 12:40:00 INFO - [mozharness: 2016-10-06 19:40:00.993445Z] Running download-and-extract step. 12:40:00 INFO - Running pre-action listener: _resource_record_pre_action 12:40:00 INFO - Running main action method: download_and_extract 12:40:00 INFO - mkdir: /builds/slave/test/build/tests 12:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:00 INFO - trying https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 12:40:00 INFO - Downloading https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:40:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 12:40:02 INFO - Downloaded 1199 bytes. 12:40:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:40:02 INFO - Using the following test package requirements: 12:40:02 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:40:02 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:40:02 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 12:40:02 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:40:02 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 12:40:02 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:40:02 INFO - u'jsshell-mac.zip'], 12:40:02 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:40:02 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 12:40:02 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:40:02 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:40:02 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 12:40:02 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:40:02 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 12:40:02 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:40:02 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 12:40:02 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:40:02 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 12:40:02 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:40:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:40:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:40:02 INFO - Fetch https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:40:02 INFO - Http code: 200 12:40:02 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 12:40:02 INFO - x-amz-cf-id: n7YhXAD5y7zmrKzHeMx3RxnY0WQlWlbFZrNcIwZibTTdRqsQRR12NQ== 12:40:02 INFO - x-amz-version-id: FeQtKOd6rSTU9kpeLpIqx5xyPS7oFUp8 12:40:03 INFO - Expected file size: 16478241 12:40:03 INFO - Obtained file size: 16478241 12:40:09 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:40:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:40:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:40:09 INFO - Fetch https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:40:10 INFO - Http code: 200 12:40:10 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 12:40:10 INFO - x-amz-cf-id: iD2qY6MxG3UBkknxqeMhfZ4LZJKPjDVLJydzN1Inl0ZS0SvaPwS5Ng== 12:40:10 INFO - x-amz-version-id: FeQtKOd6rSTU9kpeLpIqx5xyPS7oFUp8 12:40:10 INFO - Expected file size: 16478241 12:40:10 INFO - Obtained file size: 16478241 12:40:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 12:40:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:40:17 INFO - Fetch https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 12:40:17 INFO - Http code: 200 12:40:17 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 12:40:17 INFO - x-amz-cf-id: s2e4PXvOgcA6mPp72ag5U-UmvH_O_jV6g7wvBmPp6MIIMac_UOLYFw== 12:40:17 INFO - x-amz-version-id: RPrkOCZ12nIO2H7kS817HU_KIdyFPKlZ 12:40:18 INFO - Expected file size: 14409006 12:40:18 INFO - Obtained file size: 14409006 12:40: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')]} 12:40:18 INFO - trying https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:40:18 INFO - Downloading https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 12:40:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 12:40:21 INFO - Downloaded 111657469 bytes. 12:40:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:40:21 INFO - mkdir: /builds/slave/test/properties 12:40:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:40:21 INFO - Writing to file /builds/slave/test/properties/build_url 12:40:21 INFO - Contents: 12:40:21 INFO - build_url:https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:40:22 INFO - Running post-action listener: _resource_record_post_action 12:40:22 INFO - Running post-action listener: set_extra_try_arguments 12:40:22 INFO - [mozharness: 2016-10-06 19:40:22.107962Z] Finished download-and-extract step (success) 12:40:22 INFO - [mozharness: 2016-10-06 19:40:22.108133Z] Running populate-webroot step. 12:40:22 INFO - Running pre-action listener: _resource_record_pre_action 12:40:22 INFO - Running main action method: populate_webroot 12:40:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:40:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:40:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:40:22 INFO - '--tppagecycles', 12:40:22 INFO - '7'], 12:40:22 INFO - 'tests': ['tsvgm']}}, 12:40:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:40:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:40:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:40:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:40:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:22 INFO - 'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:40:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:40:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:22 INFO - 'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['damp', 'tps']}, 12:40:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:22 INFO - 'tests': ['damp', 'tps']}, 12:40:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['dromaeo_dom']}, 12:40:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:40:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['basic_compositor_video']}, 12:40:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:40:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['a11yr', 12:40:22 INFO - 'ts_paint', 12:40:22 INFO - 'tpaint', 12:40:22 INFO - 'sessionrestore', 12:40:22 INFO - 'sessionrestore_no_auto_restore', 12:40:22 INFO - 'tabpaint']}, 12:40:22 INFO - 'other-e10s': {'tests': ['a11yr', 12:40:22 INFO - 'ts_paint', 12:40:22 INFO - 'tpaint', 12:40:22 INFO - 'sessionrestore', 12:40:22 INFO - 'sessionrestore_no_auto_restore', 12:40:22 INFO - 'tabpaint']}, 12:40:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:40:22 INFO - 'ts_paint', 12:40:22 INFO - 'tpaint', 12:40:22 INFO - 'sessionrestore', 12:40:22 INFO - 'sessionrestore_no_auto_restore', 12:40:22 INFO - 'tabpaint']}, 12:40:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:40:22 INFO - 'ts_paint', 12:40:22 INFO - 'tpaint', 12:40:22 INFO - 'sessionrestore', 12:40:22 INFO - 'sessionrestore_no_auto_restore', 12:40:22 INFO - 'tabpaint']}, 12:40:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['a11yr', 12:40:22 INFO - 'ts_paint', 12:40:22 INFO - 'tpaint', 12:40:22 INFO - 'sessionrestore', 12:40:22 INFO - 'sessionrestore_no_auto_restore', 12:40:22 INFO - 'tabpaint']}, 12:40:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['a11yr', 12:40:22 INFO - 'ts_paint', 12:40:22 INFO - 'tpaint', 12:40:22 INFO - 'sessionrestore', 12:40:22 INFO - 'sessionrestore_no_auto_restore', 12:40:22 INFO - 'tabpaint']}, 12:40:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['tsvgx', 12:40:22 INFO - 'tsvgr_opacity', 12:40:22 INFO - 'tart', 12:40:22 INFO - 'tscrollx', 12:40:22 INFO - 'cart']}, 12:40:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:40:22 INFO - 'tsvgr_opacity', 12:40:22 INFO - 'tart', 12:40:22 INFO - 'tscrollx', 12:40:22 INFO - 'cart']}, 12:40:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:22 INFO - 'talos_options': ['--disable-e10s'], 12:40:22 INFO - 'tests': ['tp5o']}, 12:40:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:22 INFO - 'tests': ['tp5o']}, 12:40:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:22 INFO - 'talos_options': ['--disable-e10s', 12:40:22 INFO - '--xperf_path', 12:40:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:40:22 INFO - 'tests': ['tp5n']}, 12:40:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:40:22 INFO - 'talos_options': ['--xperf_path', 12:40:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:40:22 INFO - 'tests': ['tp5n']}}, 12:40:22 INFO - 'talos.zip': {'path': '', 12:40:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:40:22 INFO - Running post-action listener: _resource_record_post_action 12:40:22 INFO - [mozharness: 2016-10-06 19:40:22.127134Z] Finished populate-webroot step (success) 12:40:22 INFO - [mozharness: 2016-10-06 19:40:22.127239Z] Running create-virtualenv step. 12:40:22 INFO - Running pre-action listener: _resource_record_pre_action 12:40:22 INFO - Running main action method: create_virtualenv 12:40:22 INFO - Creating virtualenv /builds/slave/test/build/venv 12:40: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 12:40:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:40:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:40:22 INFO - Using real prefix '/tools/python27' 12:40:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:40:22 INFO - Installing distribute.............................................................................................................................................................................................done. 12:40:25 INFO - Installing pip.................done. 12:40:25 INFO - Return code: 0 12:40:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:40:26 INFO - Reading from file tmpfile_stdout 12:40:26 INFO - Output received: 12:40:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:40:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40: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', 'pip>=1.5'] in /builds/slave/test/build 12:40: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 pip>=1.5 12:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:26 INFO - 'HOME': '/Users/cltbld', 12:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:26 INFO - 'LOGNAME': 'cltbld', 12:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:26 INFO - 'MOZ_NO_REMOTE': '1', 12:40:26 INFO - 'NO_EM_RESTART': '1', 12:40:26 INFO - 'PAGER': '/bin/cat', 12:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:26 INFO - 'PWD': '/builds/slave/test', 12:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:26 INFO - 'SHELL': '/bin/bash', 12:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:26 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:26 INFO - 'USER': 'cltbld', 12:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:26 INFO - 'XPC_FLAGS': '0x0', 12:40:26 INFO - 'XPC_SERVICE_NAME': '0', 12:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:27 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 12:40:27 INFO - Cleaning up... 12:40:27 INFO - Return code: 0 12:40:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:40: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')]} 12:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40: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')]} 12:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:27 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 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40:27 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 12:40:27 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 12:40:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:27 INFO - 'HOME': '/Users/cltbld', 12:40:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:27 INFO - 'LOGNAME': 'cltbld', 12:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:27 INFO - 'MOZ_NO_REMOTE': '1', 12:40:27 INFO - 'NO_EM_RESTART': '1', 12:40:27 INFO - 'PAGER': '/bin/cat', 12:40:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:27 INFO - 'PWD': '/builds/slave/test', 12:40:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:27 INFO - 'SHELL': '/bin/bash', 12:40:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:27 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:27 INFO - 'USER': 'cltbld', 12:40:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:27 INFO - 'XPC_FLAGS': '0x0', 12:40:27 INFO - 'XPC_SERVICE_NAME': '0', 12:40:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:27 INFO - Downloading/unpacking psutil>=3.1.1 12:40:27 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 12:40:27 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 12:40:27 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 12:40:27 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 12:40:27 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 12:40:27 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 12:40:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:40:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:31 INFO - Installing collected packages: psutil 12:40:31 INFO - Running setup.py install for psutil 12:40:31 INFO - building 'psutil._psutil_osx' extension 12:40:31 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 12:40:32 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 12:40:32 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 12:40:32 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 12:40:32 INFO - building 'psutil._psutil_posix' extension 12:40:32 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 12:40:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:40:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:40:32 INFO - ^ 12:40:32 INFO - 1 warning generated. 12:40:32 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 12:40:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:40:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:40:32 INFO - Successfully installed psutil 12:40:32 INFO - Cleaning up... 12:40:32 INFO - Return code: 0 12:40:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:32 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 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40:32 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 12:40:32 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 12:40:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:32 INFO - 'HOME': '/Users/cltbld', 12:40:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:32 INFO - 'LOGNAME': 'cltbld', 12:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:32 INFO - 'MOZ_NO_REMOTE': '1', 12:40:32 INFO - 'NO_EM_RESTART': '1', 12:40:32 INFO - 'PAGER': '/bin/cat', 12:40:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:32 INFO - 'PWD': '/builds/slave/test', 12:40:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:32 INFO - 'SHELL': '/bin/bash', 12:40:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:32 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:32 INFO - 'USER': 'cltbld', 12:40:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:32 INFO - 'XPC_FLAGS': '0x0', 12:40:32 INFO - 'XPC_SERVICE_NAME': '0', 12:40:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:40:32 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 12:40:32 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 12:40:32 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 12:40:32 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 12:40:32 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 12:40:32 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 12:40:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:40:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:40:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:40:35 INFO - Installing collected packages: mozsystemmonitor 12:40:35 INFO - Running setup.py install for mozsystemmonitor 12:40:35 INFO - Successfully installed mozsystemmonitor 12:40:35 INFO - Cleaning up... 12:40:35 INFO - Return code: 0 12:40:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:40:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:40:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:35 INFO - 'HOME': '/Users/cltbld', 12:40:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:35 INFO - 'LOGNAME': 'cltbld', 12:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:35 INFO - 'MOZ_NO_REMOTE': '1', 12:40:35 INFO - 'NO_EM_RESTART': '1', 12:40:35 INFO - 'PAGER': '/bin/cat', 12:40:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:35 INFO - 'PWD': '/builds/slave/test', 12:40:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:35 INFO - 'SHELL': '/bin/bash', 12:40:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:35 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:35 INFO - 'USER': 'cltbld', 12:40:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:35 INFO - 'XPC_FLAGS': '0x0', 12:40:35 INFO - 'XPC_SERVICE_NAME': '0', 12:40:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:35 INFO - Downloading/unpacking jsonschema==2.5.1 12:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:40:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40: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 12:40:38 INFO - Downloading functools32-3.2.3-2.tar.gz 12:40:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:40:38 INFO - warning: no files found matching '*.txt' 12:40:38 INFO - no previously-included directories found matching 'build' 12:40:38 INFO - no previously-included directories found matching 'dist' 12:40:38 INFO - no previously-included directories found matching '.git*' 12:40:38 INFO - Installing collected packages: jsonschema, functools32 12:40:38 INFO - Running setup.py install for functools32 12:40:39 INFO - warning: no files found matching '*.txt' 12:40:39 INFO - no previously-included directories found matching 'build' 12:40:39 INFO - no previously-included directories found matching 'dist' 12:40:39 INFO - no previously-included directories found matching '.git*' 12:40:39 INFO - Successfully installed jsonschema functools32 12:40:39 INFO - Cleaning up... 12:40:39 INFO - Return code: 0 12:40:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:39 INFO - 'HOME': '/Users/cltbld', 12:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:39 INFO - 'LOGNAME': 'cltbld', 12:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:39 INFO - 'MOZ_NO_REMOTE': '1', 12:40:39 INFO - 'NO_EM_RESTART': '1', 12:40:39 INFO - 'PAGER': '/bin/cat', 12:40:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:39 INFO - 'PWD': '/builds/slave/test', 12:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:39 INFO - 'SHELL': '/bin/bash', 12:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:39 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:39 INFO - 'USER': 'cltbld', 12:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:39 INFO - 'XPC_FLAGS': '0x0', 12:40:39 INFO - 'XPC_SERVICE_NAME': '0', 12:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:40:39 INFO - Cleaning up... 12:40:39 INFO - Return code: 0 12:40:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:39 INFO - 'HOME': '/Users/cltbld', 12:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:39 INFO - 'LOGNAME': 'cltbld', 12:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:39 INFO - 'MOZ_NO_REMOTE': '1', 12:40:39 INFO - 'NO_EM_RESTART': '1', 12:40:39 INFO - 'PAGER': '/bin/cat', 12:40:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:39 INFO - 'PWD': '/builds/slave/test', 12:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:39 INFO - 'SHELL': '/bin/bash', 12:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:39 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:39 INFO - 'USER': 'cltbld', 12:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:39 INFO - 'XPC_FLAGS': '0x0', 12:40:39 INFO - 'XPC_SERVICE_NAME': '0', 12:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:39 INFO - Downloading/unpacking blobuploader==1.2.4 12:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:42 INFO - Downloading blobuploader-1.2.4.tar.gz 12:40:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:40:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:40:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:40:43 INFO - Downloading docopt-0.6.1.tar.gz 12:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:40:43 INFO - Installing collected packages: blobuploader, requests, docopt 12:40:43 INFO - Running setup.py install for blobuploader 12:40:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:40:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:40:43 INFO - Running setup.py install for requests 12:40:44 INFO - Running setup.py install for docopt 12:40:44 INFO - Successfully installed blobuploader requests docopt 12:40:44 INFO - Cleaning up... 12:40:44 INFO - Return code: 0 12:40:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:44 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 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40:44 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 12:40:44 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 12:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:44 INFO - 'HOME': '/Users/cltbld', 12:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:44 INFO - 'LOGNAME': 'cltbld', 12:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:44 INFO - 'MOZ_NO_REMOTE': '1', 12:40:44 INFO - 'NO_EM_RESTART': '1', 12:40:44 INFO - 'PAGER': '/bin/cat', 12:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:44 INFO - 'PWD': '/builds/slave/test', 12:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:44 INFO - 'SHELL': '/bin/bash', 12:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:44 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:44 INFO - 'USER': 'cltbld', 12:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:44 INFO - 'XPC_FLAGS': '0x0', 12:40:44 INFO - 'XPC_SERVICE_NAME': '0', 12:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:44 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-IFtSKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:44 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-Hrklpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:44 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-8Xve9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:44 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-EGRSbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:45 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-QLMGDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:45 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-Uq4OA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:45 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-yFMJC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:45 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-FgV5Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:45 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-jQDxNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:45 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-BJuY6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:45 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-hYjpHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:45 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-5Rzf0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:45 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-7PIzzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:46 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-KFM3jI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:46 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-iCtSL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:46 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-uhv4Sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:46 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-cgT4ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:40:46 INFO - Running setup.py install for manifestparser 12:40:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:40:46 INFO - Running setup.py install for mozcrash 12:40:46 INFO - Running setup.py install for mozdebug 12:40:46 INFO - Running setup.py install for mozdevice 12:40:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:40:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:40:47 INFO - Running setup.py install for mozfile 12:40:47 INFO - Running setup.py install for mozhttpd 12:40:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:40:47 INFO - Running setup.py install for mozinfo 12:40:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:40:47 INFO - Running setup.py install for mozInstall 12:40:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:40:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:40:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:40:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:40:47 INFO - Running setup.py install for mozleak 12:40:47 INFO - Running setup.py install for mozlog 12:40:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:40:47 INFO - Running setup.py install for moznetwork 12:40:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:40:48 INFO - Running setup.py install for mozprocess 12:40:48 INFO - Running setup.py install for mozprofile 12:40:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:40:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:40:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:40:48 INFO - Running setup.py install for mozrunner 12:40:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:40:48 INFO - Running setup.py install for mozscreenshot 12:40:48 INFO - Running setup.py install for moztest 12:40:48 INFO - Running setup.py install for mozversion 12:40:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:40:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:40:48 INFO - Cleaning up... 12:40:48 INFO - Return code: 0 12:40:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:48 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')]} 12:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:48 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')]} 12:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:48 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 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40:48 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 12:40:48 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 12:40:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:48 INFO - 'HOME': '/Users/cltbld', 12:40:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:48 INFO - 'LOGNAME': 'cltbld', 12:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:48 INFO - 'MOZ_NO_REMOTE': '1', 12:40:48 INFO - 'NO_EM_RESTART': '1', 12:40:48 INFO - 'PAGER': '/bin/cat', 12:40:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:48 INFO - 'PWD': '/builds/slave/test', 12:40:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:48 INFO - 'SHELL': '/bin/bash', 12:40:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:48 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:48 INFO - 'USER': 'cltbld', 12:40:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:48 INFO - 'XPC_FLAGS': '0x0', 12:40:48 INFO - 'XPC_SERVICE_NAME': '0', 12:40:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:40:49 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-kCgoYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:40:49 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)) 12:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:40:49 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-E2C2jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:40:49 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)) 12:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:40:49 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-8GhOJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:40:49 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)) 12:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:40:49 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-JBDS1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:40:49 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)) 12:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:40:49 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-R6SY0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:40:49 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)) 12:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:40:49 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-6QNu71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:40:49 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)) 12:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:40:49 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-i5aEdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:40:49 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)) 12:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:40:49 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-SzIMog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:40:50 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)) 12:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:40:50 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-Fht6tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:40:50 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)) 12:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:40:50 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-FTuEk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:40:50 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-3k0vst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:40:50 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)) 12:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:40:50 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-PZHcgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:40:50 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)) 12:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:40:50 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-CBiqfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:40:50 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)) 12:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:40:50 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-SXStKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:40:50 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)) 12:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:50 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-KwcjXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:40:50 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)) 12:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:40:50 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-F4b8Lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:40:50 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)) 12:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:40:50 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-_FKSAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:40:51 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)) 12:40:51 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)) 12:40:51 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)) 12:40:51 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)) 12:40:51 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)) 12:40:51 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)) 12:40:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:40:51 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 12:40:51 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 12:40:51 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 12:40:51 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 12:40:51 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 12:40:51 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 12:40:53 INFO - Downloading blessings-1.6.tar.gz 12:40:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:40:53 INFO - Installing collected packages: blessings 12:40:53 INFO - Running setup.py install for blessings 12:40:53 INFO - Successfully installed blessings 12:40:53 INFO - Cleaning up... 12:40:53 INFO - Return code: 0 12:40:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:40:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:54 INFO - Reading from file tmpfile_stdout 12:40:54 INFO - Current package versions: 12:40:54 INFO - blessings == 1.6 12:40:54 INFO - blobuploader == 1.2.4 12:40:54 INFO - docopt == 0.6.1 12:40:54 INFO - functools32 == 3.2.3-2 12:40:54 INFO - jsonschema == 2.5.1 12:40:54 INFO - manifestparser == 1.1 12:40:54 INFO - mozInstall == 1.12 12:40:54 INFO - mozcrash == 0.17 12:40:54 INFO - mozdebug == 0.1 12:40:54 INFO - mozdevice == 0.48 12:40:54 INFO - mozfile == 1.2 12:40:54 INFO - mozhttpd == 0.7 12:40:54 INFO - mozinfo == 0.9 12:40:54 INFO - mozleak == 0.1 12:40:54 INFO - mozlog == 3.4 12:40:54 INFO - moznetwork == 0.27 12:40:54 INFO - mozprocess == 0.23 12:40:54 INFO - mozprofile == 0.28 12:40:54 INFO - mozrunner == 6.12 12:40:54 INFO - mozscreenshot == 0.1 12:40:54 INFO - mozsystemmonitor == 0.3 12:40:54 INFO - moztest == 0.7 12:40:54 INFO - mozversion == 1.4 12:40:54 INFO - psutil == 3.1.1 12:40:54 INFO - requests == 1.2.3 12:40:54 INFO - wsgiref == 0.1.2 12:40:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:54 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 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40:54 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 12:40:54 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 12:40:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:54 INFO - 'HOME': '/Users/cltbld', 12:40:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:54 INFO - 'LOGNAME': 'cltbld', 12:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:54 INFO - 'MOZ_NO_REMOTE': '1', 12:40:54 INFO - 'NO_EM_RESTART': '1', 12:40:54 INFO - 'PAGER': '/bin/cat', 12:40:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:54 INFO - 'PWD': '/builds/slave/test', 12:40:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:54 INFO - 'SHELL': '/bin/bash', 12:40:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:54 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:54 INFO - 'USER': 'cltbld', 12:40:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:54 INFO - 'XPC_FLAGS': '0x0', 12:40:54 INFO - 'XPC_SERVICE_NAME': '0', 12:40:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:54 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)) 12:40:54 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)) 12:40:54 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)) 12:40:54 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)) 12:40:54 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)) 12:40:54 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)) 12:40:54 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)) 12:40:54 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)) 12:40:54 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)) 12:40:54 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)) 12:40:54 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)) 12:40:54 INFO - Cleaning up... 12:40:54 INFO - Return code: 0 12:40:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:40:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:40:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:40:54 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 0x10567e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f934345dce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, '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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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 12:40:54 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 12:40:54 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 12:40:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:40:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:54 INFO - 'HOME': '/Users/cltbld', 12:40:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:54 INFO - 'LOGNAME': 'cltbld', 12:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:54 INFO - 'MOZ_NO_REMOTE': '1', 12:40:54 INFO - 'NO_EM_RESTART': '1', 12:40:54 INFO - 'PAGER': '/bin/cat', 12:40:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:54 INFO - 'PWD': '/builds/slave/test', 12:40:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:54 INFO - 'SHELL': '/bin/bash', 12:40:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:40:54 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:40:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:54 INFO - 'USER': 'cltbld', 12:40:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:54 INFO - 'XPC_FLAGS': '0x0', 12:40:54 INFO - 'XPC_SERVICE_NAME': '0', 12:40:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:40:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:40:54 INFO - Cleaning up... 12:40:54 INFO - Return code: 0 12:40:54 INFO - Running post-action listener: _resource_record_post_action 12:40:54 INFO - Running post-action listener: _start_resource_monitoring 12:40:54 INFO - Starting resource monitoring. 12:40:54 INFO - [mozharness: 2016-10-06 19:40:54.782840Z] Finished create-virtualenv step (success) 12:40:54 INFO - [mozharness: 2016-10-06 19:40:54.783805Z] Running install step. 12:40:54 INFO - Running pre-action listener: _resource_record_pre_action 12:40:54 INFO - Running main action method: install 12:40:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:40:55 INFO - Reading from file tmpfile_stdout 12:40:55 INFO - Detecting whether we're running mozinstall >=1.0... 12:40:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:40:55 INFO - Reading from file tmpfile_stdout 12:40:55 INFO - Output received: 12:40:55 INFO - Usage: mozinstall [options] installer 12:40:55 INFO - Options: 12:40:55 INFO - -h, --help show this help message and exit 12:40:55 INFO - -d DEST, --destination=DEST 12:40:55 INFO - Directory to install application into. [default: 12:40:55 INFO - "/builds/slave/test"] 12:40:55 INFO - --app=APP Application being installed. [default: firefox] 12:40:55 INFO - mkdir: /builds/slave/test/build/application 12:40:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:41:29 INFO - Reading from file tmpfile_stdout 12:41:29 INFO - Output received: 12:41:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:41:29 INFO - Running post-action listener: _resource_record_post_action 12:41:29 INFO - [mozharness: 2016-10-06 19:41:29.857888Z] Finished install step (success) 12:41:29 INFO - [mozharness: 2016-10-06 19:41:29.858023Z] Running run-tests step. 12:41:29 INFO - Running pre-action listener: _resource_record_pre_action 12:41:29 INFO - Running main action method: run_tests 12:41:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:41:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:41:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:41:29 INFO - 2016-10-06 12:41:29.872 screenresolution[1639:7485] starting screenresolution argv=screenresolution get 12:41:29 INFO - 2016-10-06 12:41:29.875 screenresolution[1639:7485] Display 0: 1600x1200x32@60 12:41:29 INFO - 2016-10-06 12:41:29.881 screenresolution[1640:7487] starting screenresolution argv=screenresolution list 12:41:29 INFO - Available Modes on Display 0 12:41:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:41:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:41:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:41:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:41:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:41:30 INFO - Intel Iris: 12:41:30 INFO - Chipset Model: Intel Iris 12:41:30 INFO - Type: GPU 12:41:30 INFO - Bus: Built-In 12:41:30 INFO - VRAM (Dynamic, Max): 1536 MB 12:41:30 INFO - Vendor: Intel (0x8086) 12:41:30 INFO - Device ID: 0x0a2e 12:41:30 INFO - Revision ID: 0x0009 12:41:30 INFO - Displays: 12:41:30 INFO - Display: 12:41:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:41:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:41:30 INFO - Display Serial Number: 1600x1200 60 12:41:30 INFO - Main Display: Yes 12:41:30 INFO - Mirror: Off 12:41:30 INFO - Online: Yes 12:41:30 INFO - Rotation: Supported 12:41:30 INFO - Return code: 0 12:41:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:41:30 INFO - Python 2.7.3 12:41:30 INFO - Return code: 0 12:41:30 INFO - grabbing minidump binary from tooltool 12:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:41:30 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 0x105492d30>, '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 0x1052b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10548ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:41:30 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 12:41:30 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 12:41:30 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 12:41:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:41:30 INFO - Return code: 0 12:41:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:41:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:41:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:41:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:41:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:41:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--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 12:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0323 --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 12:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ijHgTd43ts/Render', 12:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:41:30 INFO - 'HOME': '/Users/cltbld', 12:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:41:30 INFO - 'LOGNAME': 'cltbld', 12:41:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:41:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:41:30 INFO - 'MOZ_NO_REMOTE': '1', 12:41:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:41:30 INFO - 'NO_EM_RESTART': '1', 12:41:30 INFO - 'PAGER': '/bin/cat', 12:41:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:41:30 INFO - 'PWD': '/builds/slave/test', 12:41:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:41:30 INFO - 'SHELL': '/bin/bash', 12:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Q2kWNOTpMj/Listeners', 12:41:30 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 12:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:41:30 INFO - 'USER': 'cltbld', 12:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:41:30 INFO - 'XPC_FLAGS': '0x0', 12:41:30 INFO - 'XPC_SERVICE_NAME': '0', 12:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:41:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--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 12:41:30 INFO - mozversion application_buildid: 20161006092200 12:41:30 INFO - mozversion application_changeset: 7ff5c0b99e1936648eb6209703b8670da757f851 12:41:30 INFO - mozversion application_display_name: Nightly 12:41:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:41:30 INFO - mozversion application_name: Firefox 12:41:30 INFO - mozversion application_remotingname: firefox 12:41:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:41:30 INFO - mozversion application_vendor: Mozilla 12:41:30 INFO - mozversion application_version: 52.0a1 12:41:30 INFO - mozversion platform_buildid: 20161006092200 12:41:30 INFO - mozversion platform_changeset: 7ff5c0b99e1936648eb6209703b8670da757f851 12:41:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:41:30 INFO - mozversion platform_version: 52.0a1 12:41:30 INFO - using testdate: 1475782890 12:41:30 INFO - actual date: 1475782890 12:41:30 INFO - starting webserver on 'localhost:49267' 12:41:30 INFO - SUITE-START | Running 2 tests 12:41:30 INFO - TEST-START | dromaeo_css 12:41:30 INFO - operating with platform_type : mac_ 12:41:30 INFO - Initialising browser for dromaeo_css test... 12:41:30 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpjsVMb8/profile http://localhost:49267/getInfo.html) 12:41:33 INFO - PROCESS | 1650 | 2016-10-06 12:41:33.111 plugin-container[1652:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 12:41:33 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:41:33 INFO - PROCESS | 1650 | 2016-10-06 12:41:33.114 plugin-container[1652:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 12:41:33 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:41:33 INFO - PROCESS | 1650 | 2016-10-06 12:41:33.114 plugin-container[1652:7655] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:41:33 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 12:41:33 INFO - PROCESS | 1650 | colorDepth:24 12:41:33 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 12:41:33 INFO - PROCESS | 1650 | __metrics 12:41:33 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 12:41:33 INFO - TEST-INFO | 1650: exit 0 12:41:33 INFO - Browser initialized. 12:41:33 INFO - Running cycle 1/1 for dromaeo_css test... 12:41:33 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpjsVMb8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:36 INFO - PROCESS | 1653 | 2016-10-06 12:41:36.388 plugin-container[1654:7776] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891b, name = 'com.apple.tsm.portname' 12:41:36 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:41:36 INFO - PROCESS | 1653 | 2016-10-06 12:41:36.390 plugin-container[1654:7776] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 12:41:36 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:41:36 INFO - PROCESS | 1653 | 2016-10-06 12:41:36.391 plugin-container[1654:7776] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:41:46 INFO - PROCESS | 1653 | RSS: Main: 240029696 12:41:46 INFO - PROCESS | 1653 | 12:42:35 INFO - PROCESS | 1653 | 1475782955749 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] 12:44:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 12:44:51 INFO - PROCESS | 1653 | RSS: Main: 253603840 12:44:51 INFO - PROCESS | 1653 | 12:47:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 12:47:53 INFO - PROCESS | 1653 | RSS: Main: 267661312 12:47:53 INFO - PROCESS | 1653 | 12:50:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 12:50:56 INFO - PROCESS | 1653 | RSS: Main: 270114816 12:50:56 INFO - PROCESS | 1653 | 12:53:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 12:53:59 INFO - PROCESS | 1653 | RSS: Main: 269967360 12:53:59 INFO - PROCESS | 1653 | 12:57:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 12:57:02 INFO - PROCESS | 1653 | RSS: Main: 275386368 12:57:02 INFO - PROCESS | 1653 | 13:00:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 13:00:05 INFO - PROCESS | 1653 | RSS: Main: 278089728 13:00:05 INFO - PROCESS | 1653 | 13:00:05 INFO - PROCESS | 1653 | __start_tp_report 13:00:05 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 13:00:05 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 13:00:05 INFO - PROCESS | 1653 | |i|pagename|runs| 13:00:05 INFO - PROCESS | 1653 | |0;dojo.html;3327.717144042757;3337.699607035366;3401.234722187508;3429.81136037518;3476.739244556659;5774.769009239636;5888.057910734284;5891.410858914106;5917.112433135027;5927.614705044169;4498.2802751559675;4795.616350691945;5854.619449735765;5902.793402230923;5927.8221653350365;4342.934855977162;5335.1730481775385;5376.542993845528;5378.1663842104535;5440.700761458118;5352.892942141155;5429.158480435519;5458.4814442627885;5460.5085542301185;5462.754176062087;5227.921581176285;5294.52349288562;5370.2750128732905;5399.62202645815;5399.78400863965;5278.551323137538;5302.284191634157;5365.812196573141;5378.784848606051;5436.184572314145;4856.198727210006;5281.683099014072;5321.308229930123;5326.6803991760235;5358.410574836765;5746.9712651436475;5761.308642962829;5927.110933359987;5943.108533719934;5949.643021418686;6032.4570788629235;6109.920916985209;6250.9998400255745;6260;6266.686665666698;5821;6277.591956522857;6310.9684451577905;6320.873582528324;6333.778317758903;6000.069989151689;6018.428249316308;6032.95310519646;6038.215032045894;6087.573869829158;6178.289496707846;6320.71556779946;6352.809415717444;6357.205349331333;6380.330065343165;6234.75060997555;6267.2792628847355;6278.780242691484;6283.937160628427;6287.559870809091;6179.412955769195;6351.5871468355335;6359.109724638554;6369.585976911486;6374.394432528902;5137.820176293886;6143.143438301211;6172.321044685081;6222.346653601307;6310.5898116622275;6228.408301211378;6273.278572964167;6277.591956522811;6285.371462853678;6307.400796924285;5043.87390315245;5051.87370315745;5057.367829021372;5064.696118232918;5102.770375333119;3761.9435708464393;4414.27164517851;4415.094905544356;4455.443069616298;4530.79611417487;5847.795327163529;5930.021546444788;5966;5977.163197152402;6000.33996260411;5460;6227.532935029891;6261.186045814015;6334.5249106317215;6346.650934198571;4960.379952505937;5144.356955380577;5174.456682048331;5648.841987392575;5957.523398128139;5570.220169176318;5582.525485333711;5588.21764952907;5596.076647353222;5616.803411880564;6558.573692710054;6565.507586930904;6578;6613.570117942319;6623.172103487064;5081.364829396325;6181.876362472635;6247.31279559239;6312.589681670676;6397.136386587751;5382.650127741685;6376.808695739135;6474.643894585843;6510.674466276762;6527.934720652923;5261.657992230493;6208.348123446973;6238.407351301619;6288.11966324724;6306.873862522815;6138.725610151984;6154.876902461836;6186.597871138361;6335.746570137328;6342.080398342307;5159.871003224949;6103.779244151098;6147.907781383372;6170.444659980623;6171.691415429301;6155.199824022849;6163.9383606163365;6178.41305076017;6192.349803270592;6201.1628430163155;6005.519558435402;6037.63774173551;6064.636121832704;6151.292601350723;6169.598976066721;6144.047672610753;6158.846028849314;6188.133661287334;6212.06818977157;6230.470410015199;6049;6081.300650425258;6106.187656479816;6106.877862442638;6108.031519550537 13:00:05 INFO - PROCESS | 1653 | |1;ext.html;518.2407772613121;518.7328525809208;530.1120622956547;545.04072831816;560.7728869807728;38701;40340;40418.78743637692;40443.59556404435;40546;33012.83493582529;41234.76295711126;41501;41826.32694692208;41834.58165418345;8311.958440207805;9891.604335826594;9908.405495670248;9912.90087099127;9923.1069203772;9618.038196180396;9705.466199359067;9745.122938935494;9772.071653192936;9783.755406114833;9766.169875560587;9784.755381115458;9805.36265142767;9808;9818;9740.269479789009;9778.315517913748;9786.168175705074;9805.166560842337;9807.754806129833;9776.560054797481;9836.262280328932;9842.11420972116;9853.556589953469;9862.50687465624;40372.394414083814;40453;40590.79704601488;40651.98370040773;40785;3242.5460435539044;3336.1659585103725;3722.9069273268115;4068.898277543085;4147.8963025924595;30084;30966.84516577425;31258.687413125805;31375;31441.05676829699;3234.2237862912966;3324;3359.6808303211155;3395.4906763985355;3396.7961922284744;500394;506668;508544;509745.451272745;510154;58013;58095.419045809846;58295;58321.70839145862;58607.706961465774;43890.53860843664;53895.92208155815;56678.332166782995;60594.69702651459;61720.691396543625;15942.362305507882;16154.434594789362;16165.838341616669;16194.352225911067;16198.757018644494;12642.55751048709;12672.619821405373;12705.491780328683;12707.491700332068;12722.173058751152;11460.656180314603;14030.579082627537;14909.25453727331;15783.605409864846;15962.281697323648;6648.601083934979;6737.9663101685155;6745.696443660047;6755.256921738703;6816.386525212754;836.6360633124489;901.6934242357498;956.8277710012264;1013.6249587652616;1041.3699711674478;25469.61795573068;29064.854675726485;29501.8199272031;29945.850270748942;30527;1026.1329176845702;1045.2630895218851;1053.694428615693;1057;1193.8985186259092;14879.92560037193;15431.768523472158;15499.457518986781;15687.215639217993;15797.605059873595;676081;679706;684943;688283;689476;56720;57200.42799572034;57208;57239;57341;1316.486570237608;1341.3561490484622;1357.3891748713118;1369.9863001370059;1370.9177449353072;26809.463810723286;28304.292392690346;29115.7088429113;29258.561121583618;29401;48541.02917941692;49261.75369123275;49334.75332623314;49336.75331623319;49366.50633493619;615449;616170;617881;617886;619383;25767.484650307262;25977.960881565283;26324;26334.73665263323;26385.73614263833;44647.88380290389;45708;46243.76878115588;46497.53502464932;46950.53049469461;37296.44055339118;38248.80875595716;39162.2167556653;39308.60691393164;40909.79545102255;38538.614613853504;38621;39142.412863807636;39243;39685.60314396935;37370.813145934095;37780;38130.42804357882;38178.42732358961;38391.04022399351;33098.66901330956;33986.980390588324;34661.826690866386;34739;34792.30415391627;25098.37254068663;25339.493210135326;25401.491970160863;26022.609660854723;26445.471090577696 13:00:05 INFO - PROCESS | 1653 | |2;jquery.html;347.2083649279642;362.5124207940319;372.0846717075993;374.75640833458266;392.6681953749083;16648.667026659474;19252;20468.28361007365;21021.369358919237;21301.893490532566;16342.754858677099;20072.89963550184;20266.51499957594;20569.588608227827;20705.482362940933;4829.372181616385;4945.554900058994;5118.206677964921;6032.638041717489;6159.599626024294;5912.674802885839;6134.74237781256;6136.079588061782;6138.631682099066;6146.231721034871;5783.768649254046;5941.554383421239;6013.338532761392;6046.365131661179;6093.482054025413;5940.940590594104;5949.315828679714;6013.067974463965;6014.6391216527145;6014.699265036753;4324;5039.118154322979;5099.107656160157;5147.4080480744815;5181.611379146579;17509.824901751075;18493.630127397377;20489.795102048938;24539.018439262407;24811.883465243925;1990.950226244341;2891.3638999420095;3096.9690303096904;3110.6578276389805;3197.736893926909;8632.956835215846;10105.595776168944;10247.84628230577;10332.380057196517;10396.960303969618;1830.4600142957859;1907.7885592460384;1952.2093552111276;1957.1388589020785;1970.8916009619466;97348.53977190348;121017;121548;122320.0491968028;125404;11365.31808091517;11425.828612570818;11548.76902461946;11617.477213525242;11864.94067529674;13619;14494.05788623736;14504.85495145035;14506.564803055926;14518;6927.792166235021;8254;8467.618957146944;8636.265917397091;8640.395172338001;8379.203975622306;8411.158884111539;8428.157184281523;8573.74278771638;8585.18440748128;4429.579189976947;4525.298578720303;4554.95445045552;4610.815567377267;4624.9768751156025;1877.7934427212995;1906.3232552443683;1923.7595300587427;1935.0711658404045;1937.409090227494;805.9274665280153;816.1878930464226;838.6393850644281;900.7297810656777;905.4341036851968;16241.863069585252;19718.507037324183;20701;20716.896415517826;20737.89631051835;889.7063968890251;916.7295647783919;946.7964387656687;951.971870379988;974.5565767575736;9527.633607463576;9724.805503889882;10700.411477368663;10983.890161098287;11743.88256117445;533969;539277;540831;552477;555097;97159.51420242994;97827;126209;131697;133283;939.9060093990684;998.9150922171697;1018.923580731448;1020.8366661334151;1031.3502493429091;18688.34590789316;18840.623187535897;19033.333833315766;19476.8052319475;19504;58887.99999999828;59574.702126489094;59662;59965.80068398695;60275;87139.1286087131;87176;87447;87904;88624.22751544804;7951.761447156595;7952.244536768998;8025.9197408025175;8138.389620778584;8142.552159631277;9049.819003620023;10242.180625550087;10578.89421105779;12044.638660840188;12555.811662825248;8886.111388861062;9742.879568849674;10102.595896164072;10164.440955747505;10997.670069897917;8973.865392018994;10676.62631807883;10727.463626818784;10814.945925270324;10873.456327183767;9373.484458354584;10754.677359679223;10922.836157457485;11120.666380008613;11187.888121119011;6868.484863635147;7194.100737407824;7236.38490728294;7390.113186417664;7472.103347598323;8179.83640327202;10701.464926753786;10888.67333979982;10961.725956850823;10978.780424391627 13:00:05 INFO - PROCESS | 1653 | |3;mootools.html;1470.3824393754626;1503.346044470655;1688.6622675464903;1768.7789026371704;1793.59644080082;8003;8298.95850520748;8555.31557475402;9170.495622740747;9236.44581325121;8483.957580212105;8770.649174033048;8778.824423511525;8855.291576673866;8882.600282987265;2342.402687314737;2359.634256690216;2376.3346263046374;2378.2389635316617;2380.345405013618;2020.8683137443006;2334.9182778602753;2363.83453158279;2371.537550177716;2389.1996181279205;2278.715160604924;2279.9658005129854;2287.1994801819396;2360.0677732295735;2363.0902102690416;2271.8750468724215;2275.3515248154195;2280.1791355112227;2284.7486776454575;2326.1974618756503;2318.652202169671;2363.7990770784504;2366.8343215974887;2369.289213236022;2376.1683410806254;7654.502457340255;8932.374733768638;8981.14679105484;8982.775430614221;8997.370184087116;5269.6311258211945;5405.729713514309;5428.348598168245;5436.945630543683;5438.755256013489;3506.2987402519598;3556.964430355715;3708.369577171887;3710.8515659373593;3733.5146430964073;1051.1065594244908;1075.784843031389;1088.2382332367295;1111.5776005118084;1113.549012649878;10032.498375081217;11775.411229438494;11831.940840295829;11948;11959.581414650445;3794.658480736747;3813.1125093079104;4809.254565542346;4812.5668689817385;4818.132736107535;10044.899551004397;10141.340812847142;10142.49287535635;10154.136898363722;10206.183505319705;5368.651037682616;5389;5394.62237643369;5418.972905135528;5422.0511410503;4425.446819147606;4589.426321709786;4750.406199225097;4900.26496025599;4972.801087956514;3967.2065586882163;3983.7410568312966;4029.5164580250557;4116.279651061052;4122.752634841859;1278.5780692371495;1287.5815360008035;1288.1112032697408;1297.7599144158362;1299.6425982854792;891.3582220801013;905.3843386497114;920.1120918313726;944.0229362609666;950.557990534391;5880.235569375954;6087.395176233747;6129.785457508966;6276.403741644536;6280.434760871543;425.73604365293716;432.78360819590205;441.30053864624927;447.60387057454386;449.18529142264885;3911.396123455948;4025.496812898388;4065.207284579538;4090.3250963591267;4102.2821006323775;10587.205959553065;11025.558977640805;11035.613753518588;11036.889631103586;11054.723631909266;10958.246680531076;10991.670249892515;10994;10998.505067271992;11009.944950275358;8288.50268983863;8342.249197572248;8363.372747044117;8364.707235246737;8366.121557236402;5723.914141287967;5873.647581145145;5905.173275741314;6037.69811509414;6042.489377655586;9942.703572160053;10217.897821021992;10301.639442619473;10305;10306;10427.270091093855;10487.528061237415;10492.842607360742;10501.369917804956;10521.210909181995;6228.906566401598;6886.449084073362;7146.249643787328;7313.268673132644;7628.275313845176;8372.958135209285;8686.869696954676;8706.085860984758;8717.997430295598;8735.344849136212;7316.195218526064;7418.777436676908;7572.507786993828;7649.808754781175;7679.040119985002;6313.210848643919;7319.670614822239;7387.704491820267;7444;7510.699572017058;6960.23437421873;6979.651017449006;7091.078159839188;7100.005999160018;7104.609246491286;4975.0796102721115;5022.472640372855;5045.268436076675;5083.135866902561;5165.870853228699;5033.723145227048;5037.924431133462;5171.81898633546;5198.350206224222;5264.10510213257 13:00:05 INFO - PROCESS | 1653 | |4;prototype.html;302.08468340926987;303.1889695065699;309.86211136044454;316.8130802826332;317.3035187762861;4409.536998615139;5318.867028324294;5563.36021357544;5641.887162256752;5714.342820594818;4833.419989601252;5095.90438055818;5249.632525723201;5376.677399356041;5507.347795661296;4181.64456021237;4198.501891374627;4210.7473551587;4223;4227.112306415656;3313.519539666747;3656;4004.4771793769883;4249.936250956238;4265.146970605876;3265.8530366133464;3765.359888818907;4189.79051047448;4192.098698779761;4208.263553878074;4182.602652747984;4190.685698572604;4203.3064544350145;4209.705320627558;4218.198542276958;3202.327511222646;3505.5968563615;3644.5626524816926;3653.6529029742132;3669.5779985301765;4562.247229207177;5067.0879241736475;5326.067938110815;5345.198220266952;5368.91946620801;4537.886552836172;4696.624270058387;4729.243321068647;4835.806567737286;4890.3642526471685;4742.478327383985;4843.892906585646;5605.990921634104;5844.824655260349;5873.412658734135;3671.963280367189;3943.802809859524;3946.112124771932;3980.7213495055357;4009.1981603679383;6164.383561643845;6193.411625895533;6219.595726277777;6283.800669765591;6291.370862913718;3686.7833614907017;3746.4005759078964;3992.682414803108;4021.8592349267633;4034.5965403460004;3233.935321293561;3490.2950213584677;3785.905352366158;3935.8819235422984;4066.2477441673386;3972.7156884790784;4239.332976974894;4414.955850441519;4764.6426518011285;4773.37946067009;5443.591730620219;5913.556483263773;5919.194645633123;5978.282606087298;6025.307089684655;1134.9773004539863;1295.7343744532348;1303.5009738800265;1334.5662659635657;1342.3690865293297;793.7539362797436;906.8911730592371;910.6767097680361;927.8144371125802;927.9489628070521;5441.483059109379;5474.452554744494;5478.890422191534;5486.780528778804;5489.1217405214975;5360.847417805113;5833.620814647034;6014.578979471395;6023.578349515492;6042.3655516170165;4112.506499220112;4706.176419126639;4827.493113223131;5473.139132694902;5576.832695019156;2480.5860659423956;2919.554820363891;3161.873525058972;3223.8710451581674;3231.1760501072076;5659.490645841894;5811.709414529257;6371;6408.359164083555;6422.848345804208;5303.7878484860175;5516.200150978086;5616.522595579421;5940.495057920039;6046.213992181077;6277.964135917522;6372.789170057674;6432.4532414744335;6456.451201647818;6462.806115816533;5756.8198519302605;5894.440028197314;5899.46904778572;6016.939830601638;6102.328743838263;5309.601779866448;5933.3769954154195;6017.518598512021;6021.7591296347655;6088.4215999479975;5081.33942587476;5860.706964651836;6296.174109508192;6390.7763228286785;6459.741610335534;3199.4880819068835;3961.771850726284;3980.283548961215;3982.26328129297;4001.959980400275;5138.948610513997;5884.911726324023;6066.696665166813;6095.817125486243;6104.603200791934;5129;5159.974200128975;5367.812126575551;5690.179142674305;5932.7033648318275;5458;5963.254593175853;5974.312954010345;5999.730012149551;6100.6339619621185;5111.565516930954;5302.425514719627;5635.6618602883955;5799.420057994167;5929.792457263975;4398.318260669601;5797.8550536236935;5978.850528736642;6065.7573697053385;6098.32918378969;5557.388687244319;5837.241158649348;5848.619839710405;5908.350081490947;5948.4943779779205 13:00:05 INFO - PROCESS | 1653 | |5;yui.html;753.3107206905685;754.6226886556722;795.1730200591385;797.780610332159;845.4758050008995;15261.465848695298;15830.287637056317;15985.520434386957;16040.83959160408;16051.277692503836;14456.92771536141;14583.92708036461;15271.541853744378;15772.526824195264;15887.44393946212;816.6937398475557;839.9454035487673;849.7875531117221;851.1871163039314;852.9019162796296;674.7469698862926;693.0851276315266;794.7099308752292;819.5205804604284;841.6002398860522;856.1267507142711;865.5585651317841;865.8311629232302;886.0120965123873;887.1926546842375;880.7357792662208;881.1584936385736;882.1046637662778;882.1178821178789;882.7042940614901;647.1554621219307;652.4095693397483;652.8008957268031;652.8204743695475;653.493542504558;13873.653158671013;15270.694586108326;15370.846291537053;15461.994970326892;15480.690386192213;13871;13929.86070139306;14028.228447435387;14230.928845355811;14345.211013394259;11884.227525210834;11926.165168438212;12026.51893924242;12044.39778011096;12104.677281491648;3736.9439458408146;3751.643593858579;3780.810959452044;3794.829232684508;3799.202167544819;86845.69731454033;92852.53573732155;111584;111931.44034279858;113732.43133784278;10739.087177589836;10748.838767418494;10792.406417647106;10805.081568066802;11054.889451105386;21561.460963475725;21878.89060554687;21892.233771818228;21894.78105218959;21910.780892191193;7195.964020179866;7200.963995179991;7265.164506081868;7347.926520734831;7428.294312040348;3452.946851210355;4292.356146577976;4405.295152775605;4445.666575006888;4490.101979604092;3636.127329440915;3702.6852717519314;3732.925341493155;3763.040424691686;3767.227718317793;543.9129739241703;659.5119611487464;675.1358261425286;683.9965800171067;695.3359541637724;624.4411251929498;642.8264368620539;647.2879832184653;650.890232154176;671.768239957214;6864.759733409307;8256.422050456531;8558.786030349167;8577.571121443903;9287.860682089775;548.8957098151338;601.6769511063026;607.8784243151388;668.0947316386324;685.1983179679787;3962.9207415852097;4409.647228221735;4534.88662783433;4592.311153327028;4607.677462577587;154186.2290688562;160957.3904260966;162210.37789622188;164434.3556564443;164964;132439;134495.32752336175;135406.32296838454;136652;138311.30844345715;69884.30115698879;70555.6472217646;100222;101762;103403;9500.144986951207;9807.362521436084;9837.114659680663;10061.647842325483;10086.495675216356;42546;48461;61362.693186535565;63502.36497634964;64428.35571644224;63176;77346.6132669333;88491;92768.72312768939;99817;12723.745525089262;12754;13216.801747973595;13417.798733019208;13553.525626603021;14633.34149963233;14692.926535367254;15074.6985060296;15096.54710358647;15337.079775213522;13625.523106691218;14021.088629239066;14092.788608171091;14224.431022758974;14279.786218171157;13270;13412.865871341552;13771.173729576258;14198.29008549589;14231.786523201954;13177.868221317663;13608.183508989492;13702.38339274755;13877.79183312231;14194;11167.44162791841;11468.4265786712;11500.827487587525;11595.884041159818;11869.287842729464;6770.966145169243;10490.435338094552;10556.630517932143;11093.16801239894;11312.717182070184 13:00:05 INFO - PROCESS | 1653 | __end_tp_report 13:00:05 INFO - PROCESS | 1653 | __start_cc_report 13:00:05 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,364 13:00:05 INFO - PROCESS | 1653 | __end_cc_report 13:00:05 INFO - PROCESS | 1653 | __startTimestamp1475784005238__endTimestamp 13:00:05 INFO - PROCESS | 1653 | 13:00:05 INFO - PROCESS | 1653 | ------- Summary: start ------- 13:00:05 INFO - PROCESS | 1653 | Number of tests: 6 13:00:05 INFO - PROCESS | 1653 | 13:00:05 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5780.91 Median:6037.64 stddev:682.81 (11.3%) stddev-sans-first:657.22 13:00:05 INFO - PROCESS | 1653 | Values: 3327.7 3337.7 3401.2 3429.8 3476.7 5774.8 5888.1 5891.4 5917.1 5927.6 4498.3 4795.6 5854.6 5902.8 5927.8 4342.9 5335.2 5376.5 5378.2 5440.7 5352.9 5429.2 5458.5 5460.5 5462.8 5227.9 5294.5 5370.3 5399.6 5399.8 5278.6 5302.3 5365.8 5378.8 5436.2 4856.2 5281.7 5321.3 5326.7 5358.4 5747.0 5761.3 5927.1 5943.1 5949.6 6032.5 6109.9 6251.0 6260.0 6266.7 5821.0 6277.6 6311.0 6320.9 6333.8 6000.1 6018.4 6033.0 6038.2 6087.6 6178.3 6320.7 6352.8 6357.2 6380.3 6234.8 6267.3 6278.8 6283.9 6287.6 6179.4 6351.6 6359.1 6369.6 6374.4 5137.8 6143.1 6172.3 6222.3 6310.6 6228.4 6273.3 6277.6 6285.4 6307.4 5043.9 5051.9 5057.4 5064.7 5102.8 3761.9 4414.3 4415.1 4455.4 4530.8 5847.8 5930.0 5966.0 5977.2 6000.3 5460.0 6227.5 6261.2 6334.5 6346.7 4960.4 5144.4 5174.5 5648.8 5957.5 5570.2 5582.5 5588.2 5596.1 5616.8 6558.6 6565.5 6578.0 6613.6 6623.2 5081.4 6181.9 6247.3 6312.6 6397.1 5382.7 6376.8 6474.6 6510.7 6527.9 5261.7 6208.3 6238.4 6288.1 6306.9 6138.7 6154.9 6186.6 6335.7 6342.1 5159.9 6103.8 6147.9 6170.4 6171.7 6155.2 6163.9 6178.4 6192.3 6201.2 6005.5 6037.6 6064.6 6151.3 6169.6 6144.0 6158.8 6188.1 6212.1 6230.5 6049.0 6081.3 6106.2 6106.9 6108.0 13:00:05 INFO - PROCESS | 1653 | 13:00:05 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:72509.84 Median:40499.50 stddev:162615.29 (401.5%) stddev-sans-first:162981.63 13:00:05 INFO - PROCESS | 1653 | Values: 518.2 518.7 530.1 545.0 560.8 38701.0 40340.0 40418.8 40443.6 40546.0 33012.8 41234.8 41501.0 41826.3 41834.6 8312.0 9891.6 9908.4 9912.9 9923.1 9618.0 9705.5 9745.1 9772.1 9783.8 9766.2 9784.8 9805.4 9808.0 9818.0 9740.3 9778.3 9786.2 9805.2 9807.8 9776.6 9836.3 9842.1 9853.6 9862.5 40372.4 40453.0 40590.8 40652.0 40785.0 3242.5 3336.2 3722.9 4068.9 4147.9 30084.0 30966.8 31258.7 31375.0 31441.1 3234.2 3324.0 3359.7 3395.5 3396.8 500394.0 506668.0 508544.0 509745.5 510154.0 58013.0 58095.4 58295.0 58321.7 58607.7 43890.5 53895.9 56678.3 60594.7 61720.7 15942.4 16154.4 16165.8 16194.4 16198.8 12642.6 12672.6 12705.5 12707.5 12722.2 11460.7 14030.6 14909.3 15783.6 15962.3 6648.6 6738.0 6745.7 6755.3 6816.4 836.6 901.7 956.8 1013.6 1041.4 25469.6 29064.9 29501.8 29945.9 30527.0 1026.1 1045.3 1053.7 1057.0 1193.9 14879.9 15431.8 15499.5 15687.2 15797.6 676081.0 679706.0 684943.0 688283.0 689476.0 56720.0 57200.4 57208.0 57239.0 57341.0 1316.5 1341.4 1357.4 1370.0 1370.9 26809.5 28304.3 29115.7 29258.6 29401.0 48541.0 49261.8 49334.8 49336.8 49366.5 615449.0 616170.0 617881.0 617886.0 619383.0 25767.5 25978.0 26324.0 26334.7 26385.7 44647.9 45708.0 46243.8 46497.5 46950.5 37296.4 38248.8 39162.2 39308.6 40909.8 38538.6 38621.0 39142.4 39243.0 39685.6 37370.8 37780.0 38130.4 38178.4 38391.0 33098.7 33987.0 34661.8 34739.0 34792.3 25098.4 25339.5 25401.5 26022.6 26445.5 13:00:05 INFO - PROCESS | 1653 | 13:00:05 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:33308.36 Median:2358.33 stddev:91410.80 (3876.1%) stddev-sans-first:91633.72 13:00:05 INFO - PROCESS | 1653 | Values: 347.2 362.5 372.1 374.8 392.7 16648.7 19252.0 20468.3 21021.4 21301.9 16342.8 20072.9 20266.5 20569.6 20705.5 4829.4 4945.6 5118.2 6032.6 6159.6 5912.7 6134.7 6136.1 6138.6 6146.2 5783.8 5941.6 6013.3 6046.4 6093.5 5940.9 5949.3 6013.1 6014.6 6014.7 4324.0 5039.1 5099.1 5147.4 5181.6 17509.8 18493.6 20489.8 24539.0 24811.9 1991.0 2891.4 3097.0 3110.7 3197.7 8633.0 10105.6 10247.8 10332.4 10397.0 1830.5 1907.8 1952.2 1957.1 1970.9 97348.5 121017.0 121548.0 122320.0 125404.0 11365.3 11425.8 11548.8 11617.5 11864.9 13619.0 14494.1 14504.9 14506.6 14518.0 6927.8 8254.0 8467.6 8636.3 8640.4 8379.2 8411.2 8428.2 8573.7 8585.2 4429.6 4525.3 4555.0 4610.8 4625.0 1877.8 1906.3 1923.8 1935.1 1937.4 805.9 816.2 838.6 900.7 905.4 16241.9 19718.5 20701.0 20716.9 20737.9 889.7 916.7 946.8 952.0 974.6 9527.6 9724.8 10700.4 10983.9 11743.9 533969.0 539277.0 540831.0 552477.0 555097.0 97159.5 97827.0 126209.0 131697.0 133283.0 939.9 998.9 1018.9 1020.8 1031.4 18688.3 18840.6 19033.3 19476.8 19504.0 58888.0 59574.7 59662.0 59965.8 60275.0 87139.1 87176.0 87447.0 87904.0 88624.2 7951.8 7952.2 8025.9 8138.4 8142.6 9049.8 10242.2 10578.9 12044.6 12555.8 8886.1 9742.9 10102.6 10164.4 10997.7 8973.9 10676.6 10727.5 10814.9 10873.5 9373.5 10754.7 10922.8 11120.7 11187.9 6868.5 7194.1 7236.4 7390.1 7472.1 8179.8 10701.5 10888.7 10961.7 10978.8 13:00:05 INFO - PROCESS | 1653 | 13:00:05 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5753.21 Median:448.39 stddev:3276.04 (730.6%) stddev-sans-first:3269.42 13:00:05 INFO - PROCESS | 1653 | Values: 1470.4 1503.3 1688.7 1768.8 1793.6 8003.0 8299.0 8555.3 9170.5 9236.4 8484.0 8770.6 8778.8 8855.3 8882.6 2342.4 2359.6 2376.3 2378.2 2380.3 2020.9 2334.9 2363.8 2371.5 2389.2 2278.7 2280.0 2287.2 2360.1 2363.1 2271.9 2275.4 2280.2 2284.7 2326.2 2318.7 2363.8 2366.8 2369.3 2376.2 7654.5 8932.4 8981.1 8982.8 8997.4 5269.6 5405.7 5428.3 5436.9 5438.8 3506.3 3557.0 3708.4 3710.9 3733.5 1051.1 1075.8 1088.2 1111.6 1113.5 10032.5 11775.4 11831.9 11948.0 11959.6 3794.7 3813.1 4809.3 4812.6 4818.1 10044.9 10141.3 10142.5 10154.1 10206.2 5368.7 5389.0 5394.6 5419.0 5422.1 4425.4 4589.4 4750.4 4900.3 4972.8 3967.2 3983.7 4029.5 4116.3 4122.8 1278.6 1287.6 1288.1 1297.8 1299.6 891.4 905.4 920.1 944.0 950.6 5880.2 6087.4 6129.8 6276.4 6280.4 425.7 432.8 441.3 447.6 449.2 3911.4 4025.5 4065.2 4090.3 4102.3 10587.2 11025.6 11035.6 11036.9 11054.7 10958.2 10991.7 10994.0 10998.5 11009.9 8288.5 8342.2 8363.4 8364.7 8366.1 5723.9 5873.6 5905.2 6037.7 6042.5 9942.7 10217.9 10301.6 10305.0 10306.0 10427.3 10487.5 10492.8 10501.4 10521.2 6228.9 6886.4 7146.2 7313.3 7628.3 8373.0 8686.9 8706.1 8718.0 8735.3 7316.2 7418.8 7572.5 7649.8 7679.0 6313.2 7319.7 7387.7 7444.0 7510.7 6960.2 6979.7 7091.1 7100.0 7104.6 4975.1 5022.5 5045.3 5083.1 5165.9 5033.7 5037.9 5171.8 5198.4 5264.1 13:00:05 INFO - PROCESS | 1653 | 13:00:05 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4707.68 Median:5322.47 stddev:1508.22 (28.3%) stddev-sans-first:1475.76 13:00:05 INFO - PROCESS | 1653 | Values: 302.1 303.2 309.9 316.8 317.3 4409.5 5318.9 5563.4 5641.9 5714.3 4833.4 5095.9 5249.6 5376.7 5507.3 4181.6 4198.5 4210.7 4223.0 4227.1 3313.5 3656.0 4004.5 4249.9 4265.1 3265.9 3765.4 4189.8 4192.1 4208.3 4182.6 4190.7 4203.3 4209.7 4218.2 3202.3 3505.6 3644.6 3653.7 3669.6 4562.2 5067.1 5326.1 5345.2 5368.9 4537.9 4696.6 4729.2 4835.8 4890.4 4742.5 4843.9 5606.0 5844.8 5873.4 3672.0 3943.8 3946.1 3980.7 4009.2 6164.4 6193.4 6219.6 6283.8 6291.4 3686.8 3746.4 3992.7 4021.9 4034.6 3233.9 3490.3 3785.9 3935.9 4066.2 3972.7 4239.3 4415.0 4764.6 4773.4 5443.6 5913.6 5919.2 5978.3 6025.3 1135.0 1295.7 1303.5 1334.6 1342.4 793.8 906.9 910.7 927.8 927.9 5441.5 5474.5 5478.9 5486.8 5489.1 5360.8 5833.6 6014.6 6023.6 6042.4 4112.5 4706.2 4827.5 5473.1 5576.8 2480.6 2919.6 3161.9 3223.9 3231.2 5659.5 5811.7 6371.0 6408.4 6422.8 5303.8 5516.2 5616.5 5940.5 6046.2 6278.0 6372.8 6432.5 6456.5 6462.8 5756.8 5894.4 5899.5 6016.9 6102.3 5309.6 5933.4 6017.5 6021.8 6088.4 5081.3 5860.7 6296.2 6390.8 6459.7 3199.5 3961.8 3980.3 3982.3 4002.0 5138.9 5884.9 6066.7 6095.8 6104.6 5129.0 5160.0 5367.8 5690.2 5932.7 5458.0 5963.3 5974.3 5999.7 6100.6 5111.6 5302.4 5635.7 5799.4 5929.8 4398.3 5797.9 5978.9 6065.8 6098.3 5557.4 5837.2 5848.6 5908.4 5948.5 13:00:05 INFO - PROCESS | 1653 | 13:00:05 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:24280.91 Median:3734.93 stddev:39489.24 (1057.3%) stddev-sans-first:39560.51 13:00:05 INFO - PROCESS | 1653 | Values: 753.3 754.6 795.2 797.8 845.5 15261.5 15830.3 15985.5 16040.8 16051.3 14456.9 14583.9 15271.5 15772.5 15887.4 816.7 839.9 849.8 851.2 852.9 674.7 693.1 794.7 819.5 841.6 856.1 865.6 865.8 886.0 887.2 880.7 881.2 882.1 882.1 882.7 647.2 652.4 652.8 652.8 653.5 13873.7 15270.7 15370.8 15462.0 15480.7 13871.0 13929.9 14028.2 14230.9 14345.2 11884.2 11926.2 12026.5 12044.4 12104.7 3736.9 3751.6 3780.8 3794.8 3799.2 86845.7 92852.5 111584.0 111931.4 113732.4 10739.1 10748.8 10792.4 10805.1 11054.9 21561.5 21878.9 21892.2 21894.8 21910.8 7196.0 7201.0 7265.2 7347.9 7428.3 3452.9 4292.4 4405.3 4445.7 4490.1 3636.1 3702.7 3732.9 3763.0 3767.2 543.9 659.5 675.1 684.0 695.3 624.4 642.8 647.3 650.9 671.8 6864.8 8256.4 8558.8 8577.6 9287.9 548.9 601.7 607.9 668.1 685.2 3962.9 4409.6 4534.9 4592.3 4607.7 154186.2 160957.4 162210.4 164434.4 164964.0 132439.0 134495.3 135406.3 136652.0 138311.3 69884.3 70555.6 100222.0 101762.0 103403.0 9500.1 9807.4 9837.1 10061.6 10086.5 42546.0 48461.0 61362.7 63502.4 64428.4 63176.0 77346.6 88491.0 92768.7 99817.0 12723.7 12754.0 13216.8 13417.8 13553.5 14633.3 14692.9 15074.7 15096.5 15337.1 13625.5 14021.1 14092.8 14224.4 14279.8 13270.0 13412.9 13771.2 14198.3 14231.8 13177.9 13608.2 13702.4 13877.8 14194.0 11167.4 11468.4 11500.8 11595.9 11869.3 6771.0 10490.4 10556.6 11093.2 11312.7 13:00:05 INFO - PROCESS | 1653 | -------- Summary: end -------- 13:00:05 INFO - PROCESS | 1653 | 13:00:05 INFO - TEST-INFO | 1653: exit 0 13:00:05 INFO - TEST-OK | dromaeo_css | took 1115449ms 13:00:05 INFO - TEST-START | kraken 13:00:05 INFO - operating with platform_type : mac_ 13:00:05 INFO - Initialising browser for kraken test... 13:00:05 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpu9YlIv/profile http://localhost:49267/getInfo.html) 13:00:08 INFO - PROCESS | 1661 | 2016-10-06 13:00:08.547 plugin-container[1662:9893] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.tsm.portname' 13:00:08 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:08 INFO - PROCESS | 1661 | 2016-10-06 13:00:08.551 plugin-container[1662:9893] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 13:00:08 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:08 INFO - PROCESS | 1661 | 2016-10-06 13:00:08.551 plugin-container[1662:9893] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:00:08 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 13:00:08 INFO - PROCESS | 1661 | colorDepth:24 13:00:08 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 13:00:08 INFO - PROCESS | 1661 | __metrics 13:00:08 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 13:00:09 INFO - TEST-INFO | 1661: exit 0 13:00:09 INFO - Browser initialized. 13:00:09 INFO - Running cycle 1/1 for kraken test... 13:00:09 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpu9YlIv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:00:12 INFO - PROCESS | 1663 | 2016-10-06 13:00:12.121 plugin-container[1664:10004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.tsm.portname' 13:00:12 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:12 INFO - PROCESS | 1663 | 2016-10-06 13:00:12.124 plugin-container[1664:10004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f17, name = 'com.apple.CFPasteboardClient' 13:00:12 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:00:12 INFO - PROCESS | 1663 | 2016-10-06 13:00:12.124 plugin-container[1664:10004] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:00:21 INFO - PROCESS | 1663 | RSS: Main: 240353280 13:00:21 INFO - PROCESS | 1663 | 13:00:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 13:00:25 INFO - PROCESS | 1663 | RSS: Main: 253943808 13:00:25 INFO - PROCESS | 1663 | 13:00:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 13:00:28 INFO - PROCESS | 1663 | RSS: Main: 261525504 13:00:28 INFO - PROCESS | 1663 | 13:00:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 13:00:32 INFO - PROCESS | 1663 | RSS: Main: 262279168 13:00:32 INFO - PROCESS | 1663 | 13:00:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 13:00:35 INFO - PROCESS | 1663 | RSS: Main: 264769536 13:00:35 INFO - PROCESS | 1663 | 13:00:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:00:37 INFO - PROCESS | 1663 | RSS: Main: 265224192 13:00:37 INFO - PROCESS | 1663 | 13:00:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 13:00:41 INFO - PROCESS | 1663 | RSS: Main: 266010624 13:00:41 INFO - PROCESS | 1663 | 13:00:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 13:00:44 INFO - PROCESS | 1663 | RSS: Main: 265805824 13:00:44 INFO - PROCESS | 1663 | 13:00:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 13:00:48 INFO - PROCESS | 1663 | RSS: Main: 266338304 13:00:48 INFO - PROCESS | 1663 | 13:00:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:00:50 INFO - PROCESS | 1663 | RSS: Main: 265977856 13:00:50 INFO - PROCESS | 1663 | 13:00:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:00:53 INFO - PROCESS | 1663 | RSS: Main: 265809920 13:00:53 INFO - PROCESS | 1663 | 13:00:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:00:56 INFO - PROCESS | 1663 | RSS: Main: 265785344 13:00:56 INFO - PROCESS | 1663 | 13:00:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:01:00 INFO - PROCESS | 1663 | RSS: Main: 266190848 13:01:00 INFO - PROCESS | 1663 | 13:01:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:01:03 INFO - PROCESS | 1663 | RSS: Main: 266280960 13:01:03 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 13:01:05 INFO - PROCESS | 1663 | RSS: Main: 268050432 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | __start_tp_report 13:01:05 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 13:01:05 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 13:01:05 INFO - PROCESS | 1663 | |i|pagename|runs| 13:01:05 INFO - PROCESS | 1663 | |0;ai-astar;107;116;119;117;118;118;116;119;116;114 13:01:05 INFO - PROCESS | 1663 | |1;audio-beat-detection;149;115;116;163;143;131;117;115;177;147 13:01:05 INFO - PROCESS | 1663 | |2;audio-dft;196;205;203;239;199;197;194;197;196;201 13:01:05 INFO - PROCESS | 1663 | |3;audio-fft;90;60;60;85;59;59;59;57;79;59 13:01:05 INFO - PROCESS | 1663 | |4;audio-oscillator;90;71;85;105;98;81;90;71;71;86 13:01:05 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;91;88;136;134;91;87;93;88;91;105 13:01:05 INFO - PROCESS | 1663 | |6;imaging-darkroom;96;99;101;97;98;97;97;98;97;100 13:01:05 INFO - PROCESS | 1663 | |7;imaging-desaturate;80;148;93;141;150;115;91;92;96;90 13:01:05 INFO - PROCESS | 1663 | |8;json-parse-financial;55;56;55;57;58;59;57;57;56;58 13:01:05 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;69;70;77;79;77;87;62;61;89;106 13:01:05 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;86;94;90;90;86;87;92;93;88;106 13:01:05 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;151;143;155;158;165;163;148;154;197;171 13:01:05 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;175;177;176;174;177;173;177;176;178;233 13:01:05 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;71;72;72;69;70;71;72;69;71;70 13:01:05 INFO - PROCESS | 1663 | __end_tp_report 13:01:05 INFO - PROCESS | 1663 | __start_cc_report 13:01:05 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,940 13:01:05 INFO - PROCESS | 1663 | __end_cc_report 13:01:05 INFO - PROCESS | 1663 | __startTimestamp1475784065778__endTimestamp 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | ------- Summary: start ------- 13:01:05 INFO - PROCESS | 1663 | Number of tests: 14 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:116.00 Median:117.50 stddev:3.53 (3.0%) stddev-sans-first:1.66 13:01:05 INFO - PROCESS | 1663 | Values: 107.0 116.0 119.0 117.0 118.0 118.0 116.0 119.0 116.0 114.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:145.00 stddev:22.11 (15.2%) stddev-sans-first:23.04 13:01:05 INFO - PROCESS | 1663 | Values: 149.0 115.0 116.0 163.0 143.0 131.0 117.0 115.0 177.0 147.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:202.70 Median:200.00 stddev:13.21 (6.6%) stddev-sans-first:13.79 13:01:05 INFO - PROCESS | 1663 | Values: 196.0 205.0 203.0 239.0 199.0 197.0 194.0 197.0 196.0 201.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:66.70 Median:60.00 stddev:12.69 (21.2%) stddev-sans-first:10.29 13:01:05 INFO - PROCESS | 1663 | Values: 90.0 60.0 60.0 85.0 59.0 59.0 59.0 57.0 79.0 59.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:85.50 stddev:11.66 (13.6%) stddev-sans-first:12.21 13:01:05 INFO - PROCESS | 1663 | Values: 90.0 71.0 85.0 105.0 98.0 81.0 90.0 71.0 71.0 86.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:100.40 Median:89.50 stddev:18.93 (21.1%) stddev-sans-first:19.77 13:01:05 INFO - PROCESS | 1663 | Values: 91.0 88.0 136.0 134.0 91.0 87.0 93.0 88.0 91.0 105.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:97.00 stddev:1.56 (1.6%) stddev-sans-first:1.48 13:01:05 INFO - PROCESS | 1663 | Values: 96.0 99.0 101.0 97.0 98.0 97.0 97.0 98.0 97.0 100.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:109.60 Median:90.50 stddev:26.87 (29.7%) stddev-sans-first:26.28 13:01:05 INFO - PROCESS | 1663 | Values: 80.0 148.0 93.0 141.0 150.0 115.0 91.0 92.0 96.0 90.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.00 stddev:1.32 (2.3%) stddev-sans-first:1.22 13:01:05 INFO - PROCESS | 1663 | Values: 55.0 56.0 55.0 57.0 58.0 59.0 57.0 57.0 56.0 58.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:77.70 Median:77.00 stddev:13.65 (17.7%) stddev-sans-first:14.12 13:01:05 INFO - PROCESS | 1663 | Values: 69.0 70.0 77.0 79.0 77.0 87.0 62.0 61.0 89.0 106.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:90.00 stddev:5.92 (6.6%) stddev-sans-first:5.97 13:01:05 INFO - PROCESS | 1663 | Values: 86.0 94.0 90.0 90.0 86.0 87.0 92.0 93.0 88.0 106.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:160.50 Median:160.50 stddev:15.28 (9.5%) stddev-sans-first:15.81 13:01:05 INFO - PROCESS | 1663 | Values: 151.0 143.0 155.0 158.0 165.0 163.0 148.0 154.0 197.0 171.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.60 Median:177.00 stddev:18.12 (10.2%) stddev-sans-first:19.07 13:01:05 INFO - PROCESS | 1663 | Values: 175.0 177.0 176.0 174.0 177.0 173.0 177.0 176.0 178.0 233.0 13:01:05 INFO - PROCESS | 1663 | 13:01:05 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:71.00 stddev:1.16 (1.6%) stddev-sans-first:1.22 13:01:05 INFO - PROCESS | 1663 | Values: 71.0 72.0 72.0 69.0 70.0 71.0 72.0 69.0 71.0 70.0 13:01:05 INFO - PROCESS | 1663 | -------- Summary: end -------- 13:01:05 INFO - PROCESS | 1663 | 13:01:06 INFO - TEST-INFO | 1663: exit 0 13:01:06 INFO - TEST-OK | kraken | took 60719ms 13:01:06 INFO - SUITE-END | took 1176s 13:01:06 INFO - Completed test suite (00:19:36) 13:01:06 INFO - javascript benchmark 13:01:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7344.008649533684, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.717144042757, 3337.699607035366, 3401.234722187508, 3429.81136037518, 3476.739244556659, 5774.769009239636, 5888.057910734284, 5891.410858914106, 5917.112433135027, 5927.614705044169, 4498.2802751559675, 4795.616350691945, 5854.619449735765, 5902.793402230923, 5927.8221653350365, 4342.934855977162, 5335.1730481775385, 5376.542993845528, 5378.1663842104535, 5440.700761458118, 5352.892942141155, 5429.158480435519, 5458.4814442627885, 5460.5085542301185, 5462.754176062087, 5227.921581176285, 5294.52349288562, 5370.2750128732905, 5399.62202645815, 5399.78400863965, 5278.551323137538, 5302.284191634157, 5365.812196573141, 5378.784848606051, 5436.184572314145, 4856.198727210006, 5281.683099014072, 5321.308229930123, 5326.6803991760235, 5358.410574836765, 5746.9712651436475, 5761.308642962829, 5927.110933359987, 5943.108533719934, 5949.643021418686, 6032.4570788629235, 6109.920916985209, 6250.9998400255745, 6260.0, 6266.686665666698, 5821.0, 6277.591956522857, 6310.9684451577905, 6320.873582528324, 6333.778317758903, 6000.069989151689, 6018.428249316308, 6032.95310519646, 6038.215032045894, 6087.573869829158, 6178.289496707846, 6320.71556779946, 6352.809415717444, 6357.205349331333, 6380.330065343165, 6234.75060997555, 6267.2792628847355, 6278.780242691484, 6283.937160628427, 6287.559870809091, 6179.412955769195, 6351.5871468355335, 6359.109724638554, 6369.585976911486, 6374.394432528902, 5137.820176293886, 6143.143438301211, 6172.321044685081, 6222.346653601307, 6310.5898116622275, 6228.408301211378, 6273.278572964167, 6277.591956522811, 6285.371462853678, 6307.400796924285, 5043.87390315245, 5051.87370315745, 5057.367829021372, 5064.696118232918, 5102.770375333119, 3761.9435708464393, 4414.27164517851, 4415.094905544356, 4455.443069616298, 4530.79611417487, 5847.795327163529, 5930.021546444788, 5966.0, 5977.163197152402, 6000.33996260411, 5460.0, 6227.532935029891, 6261.186045814015, 6334.5249106317215, 6346.650934198571, 4960.379952505937, 5144.356955380577, 5174.456682048331, 5648.841987392575, 5957.523398128139, 5570.220169176318, 5582.525485333711, 5588.21764952907, 5596.076647353222, 5616.803411880564, 6558.573692710054, 6565.507586930904, 6578.0, 6613.570117942319, 6623.172103487064, 5081.364829396325, 6181.876362472635, 6247.31279559239, 6312.589681670676, 6397.136386587751, 5382.650127741685, 6376.808695739135, 6474.643894585843, 6510.674466276762, 6527.934720652923, 5261.657992230493, 6208.348123446973, 6238.407351301619, 6288.11966324724, 6306.873862522815, 6138.725610151984, 6154.876902461836, 6186.597871138361, 6335.746570137328, 6342.080398342307, 5159.871003224949, 6103.779244151098, 6147.907781383372, 6170.444659980623, 6171.691415429301, 6155.199824022849, 6163.9383606163365, 6178.41305076017, 6192.349803270592, 6201.1628430163155, 6005.519558435402, 6037.63774173551, 6064.636121832704, 6151.292601350723, 6169.598976066721, 6144.047672610753, 6158.846028849314, 6188.133661287334, 6212.06818977157, 6230.470410015199, 6049.0, 6081.300650425258, 6106.187656479816, 6106.877862442638, 6108.031519550537], "value": 5739.052645061264, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.2407772613121, 518.7328525809208, 530.1120622956547, 545.04072831816, 560.7728869807728, 38701.0, 40340.0, 40418.78743637692, 40443.59556404435, 40546.0, 33012.83493582529, 41234.76295711126, 41501.0, 41826.32694692208, 41834.58165418345, 8311.958440207805, 9891.604335826594, 9908.405495670248, 9912.90087099127, 9923.1069203772, 9618.038196180396, 9705.466199359067, 9745.122938935494, 9772.071653192936, 9783.755406114833, 9766.169875560587, 9784.755381115458, 9805.36265142767, 9808.0, 9818.0, 9740.269479789009, 9778.315517913748, 9786.168175705074, 9805.166560842337, 9807.754806129833, 9776.560054797481, 9836.262280328932, 9842.11420972116, 9853.556589953469, 9862.50687465624, 40372.394414083814, 40453.0, 40590.79704601488, 40651.98370040773, 40785.0, 3242.5460435539044, 3336.1659585103725, 3722.9069273268115, 4068.898277543085, 4147.8963025924595, 30084.0, 30966.84516577425, 31258.687413125805, 31375.0, 31441.05676829699, 3234.2237862912966, 3324.0, 3359.6808303211155, 3395.4906763985355, 3396.7961922284744, 500394.0, 506668.0, 508544.0, 509745.451272745, 510154.0, 58013.0, 58095.419045809846, 58295.0, 58321.70839145862, 58607.706961465774, 43890.53860843664, 53895.92208155815, 56678.332166782995, 60594.69702651459, 61720.691396543625, 15942.362305507882, 16154.434594789362, 16165.838341616669, 16194.352225911067, 16198.757018644494, 12642.55751048709, 12672.619821405373, 12705.491780328683, 12707.491700332068, 12722.173058751152, 11460.656180314603, 14030.579082627537, 14909.25453727331, 15783.605409864846, 15962.281697323648, 6648.601083934979, 6737.9663101685155, 6745.696443660047, 6755.256921738703, 6816.386525212754, 836.6360633124489, 901.6934242357498, 956.8277710012264, 1013.6249587652616, 1041.3699711674478, 25469.61795573068, 29064.854675726485, 29501.8199272031, 29945.850270748942, 30527.0, 1026.1329176845702, 1045.2630895218851, 1053.694428615693, 1057.0, 1193.8985186259092, 14879.92560037193, 15431.768523472158, 15499.457518986781, 15687.215639217993, 15797.605059873595, 676081.0, 679706.0, 684943.0, 688283.0, 689476.0, 56720.0, 57200.42799572034, 57208.0, 57239.0, 57341.0, 1316.486570237608, 1341.3561490484622, 1357.3891748713118, 1369.9863001370059, 1370.9177449353072, 26809.463810723286, 28304.292392690346, 29115.7088429113, 29258.561121583618, 29401.0, 48541.02917941692, 49261.75369123275, 49334.75332623314, 49336.75331623319, 49366.50633493619, 615449.0, 616170.0, 617881.0, 617886.0, 619383.0, 25767.484650307262, 25977.960881565283, 26324.0, 26334.73665263323, 26385.73614263833, 44647.88380290389, 45708.0, 46243.76878115588, 46497.53502464932, 46950.53049469461, 37296.44055339118, 38248.80875595716, 39162.2167556653, 39308.60691393164, 40909.79545102255, 38538.614613853504, 38621.0, 39142.412863807636, 39243.0, 39685.60314396935, 37370.813145934095, 37780.0, 38130.42804357882, 38178.42732358961, 38391.04022399351, 33098.66901330956, 33986.980390588324, 34661.826690866386, 34739.0, 34792.30415391627, 25098.37254068663, 25339.493210135326, 25401.491970160863, 26022.609660854723, 26445.471090577696], "value": 19812.382584571966, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.2083649279642, 362.5124207940319, 372.0846717075993, 374.75640833458266, 392.6681953749083, 16648.667026659474, 19252.0, 20468.28361007365, 21021.369358919237, 21301.893490532566, 16342.754858677099, 20072.89963550184, 20266.51499957594, 20569.588608227827, 20705.482362940933, 4829.372181616385, 4945.554900058994, 5118.206677964921, 6032.638041717489, 6159.599626024294, 5912.674802885839, 6134.74237781256, 6136.079588061782, 6138.631682099066, 6146.231721034871, 5783.768649254046, 5941.554383421239, 6013.338532761392, 6046.365131661179, 6093.482054025413, 5940.940590594104, 5949.315828679714, 6013.067974463965, 6014.6391216527145, 6014.699265036753, 4324.0, 5039.118154322979, 5099.107656160157, 5147.4080480744815, 5181.611379146579, 17509.824901751075, 18493.630127397377, 20489.795102048938, 24539.018439262407, 24811.883465243925, 1990.950226244341, 2891.3638999420095, 3096.9690303096904, 3110.6578276389805, 3197.736893926909, 8632.956835215846, 10105.595776168944, 10247.84628230577, 10332.380057196517, 10396.960303969618, 1830.4600142957859, 1907.7885592460384, 1952.2093552111276, 1957.1388589020785, 1970.8916009619466, 97348.53977190348, 121017.0, 121548.0, 122320.0491968028, 125404.0, 11365.31808091517, 11425.828612570818, 11548.76902461946, 11617.477213525242, 11864.94067529674, 13619.0, 14494.05788623736, 14504.85495145035, 14506.564803055926, 14518.0, 6927.792166235021, 8254.0, 8467.618957146944, 8636.265917397091, 8640.395172338001, 8379.203975622306, 8411.158884111539, 8428.157184281523, 8573.74278771638, 8585.18440748128, 4429.579189976947, 4525.298578720303, 4554.95445045552, 4610.815567377267, 4624.9768751156025, 1877.7934427212995, 1906.3232552443683, 1923.7595300587427, 1935.0711658404045, 1937.409090227494, 805.9274665280153, 816.1878930464226, 838.6393850644281, 900.7297810656777, 905.4341036851968, 16241.863069585252, 19718.507037324183, 20701.0, 20716.896415517826, 20737.89631051835, 889.7063968890251, 916.7295647783919, 946.7964387656687, 951.971870379988, 974.5565767575736, 9527.633607463576, 9724.805503889882, 10700.411477368663, 10983.890161098287, 11743.88256117445, 533969.0, 539277.0, 540831.0, 552477.0, 555097.0, 97159.51420242994, 97827.0, 126209.0, 131697.0, 133283.0, 939.9060093990684, 998.9150922171697, 1018.923580731448, 1020.8366661334151, 1031.3502493429091, 18688.34590789316, 18840.623187535897, 19033.333833315766, 19476.8052319475, 19504.0, 58887.99999999828, 59574.702126489094, 59662.0, 59965.80068398695, 60275.0, 87139.1286087131, 87176.0, 87447.0, 87904.0, 88624.22751544804, 7951.761447156595, 7952.244536768998, 8025.9197408025175, 8138.389620778584, 8142.552159631277, 9049.819003620023, 10242.180625550087, 10578.89421105779, 12044.638660840188, 12555.811662825248, 8886.111388861062, 9742.879568849674, 10102.595896164072, 10164.440955747505, 10997.670069897917, 8973.865392018994, 10676.62631807883, 10727.463626818784, 10814.945925270324, 10873.456327183767, 9373.484458354584, 10754.677359679223, 10922.836157457485, 11120.666380008613, 11187.888121119011, 6868.484863635147, 7194.100737407824, 7236.38490728294, 7390.113186417664, 7472.103347598323, 8179.83640327202, 10701.464926753786, 10888.67333979982, 10961.725956850823, 10978.780424391627], "value": 9347.342583824226, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1470.3824393754626, 1503.346044470655, 1688.6622675464903, 1768.7789026371704, 1793.59644080082, 8003.0, 8298.95850520748, 8555.31557475402, 9170.495622740747, 9236.44581325121, 8483.957580212105, 8770.649174033048, 8778.824423511525, 8855.291576673866, 8882.600282987265, 2342.402687314737, 2359.634256690216, 2376.3346263046374, 2378.2389635316617, 2380.345405013618, 2020.8683137443006, 2334.9182778602753, 2363.83453158279, 2371.537550177716, 2389.1996181279205, 2278.715160604924, 2279.9658005129854, 2287.1994801819396, 2360.0677732295735, 2363.0902102690416, 2271.8750468724215, 2275.3515248154195, 2280.1791355112227, 2284.7486776454575, 2326.1974618756503, 2318.652202169671, 2363.7990770784504, 2366.8343215974887, 2369.289213236022, 2376.1683410806254, 7654.502457340255, 8932.374733768638, 8981.14679105484, 8982.775430614221, 8997.370184087116, 5269.6311258211945, 5405.729713514309, 5428.348598168245, 5436.945630543683, 5438.755256013489, 3506.2987402519598, 3556.964430355715, 3708.369577171887, 3710.8515659373593, 3733.5146430964073, 1051.1065594244908, 1075.784843031389, 1088.2382332367295, 1111.5776005118084, 1113.549012649878, 10032.498375081217, 11775.411229438494, 11831.940840295829, 11948.0, 11959.581414650445, 3794.658480736747, 3813.1125093079104, 4809.254565542346, 4812.5668689817385, 4818.132736107535, 10044.899551004397, 10141.340812847142, 10142.49287535635, 10154.136898363722, 10206.183505319705, 5368.651037682616, 5389.0, 5394.62237643369, 5418.972905135528, 5422.0511410503, 4425.446819147606, 4589.426321709786, 4750.406199225097, 4900.26496025599, 4972.801087956514, 3967.2065586882163, 3983.7410568312966, 4029.5164580250557, 4116.279651061052, 4122.752634841859, 1278.5780692371495, 1287.5815360008035, 1288.1112032697408, 1297.7599144158362, 1299.6425982854792, 891.3582220801013, 905.3843386497114, 920.1120918313726, 944.0229362609666, 950.557990534391, 5880.235569375954, 6087.395176233747, 6129.785457508966, 6276.403741644536, 6280.434760871543, 425.73604365293716, 432.78360819590205, 441.30053864624927, 447.60387057454386, 449.18529142264885, 3911.396123455948, 4025.496812898388, 4065.207284579538, 4090.3250963591267, 4102.2821006323775, 10587.205959553065, 11025.558977640805, 11035.613753518588, 11036.889631103586, 11054.723631909266, 10958.246680531076, 10991.670249892515, 10994.0, 10998.505067271992, 11009.944950275358, 8288.50268983863, 8342.249197572248, 8363.372747044117, 8364.707235246737, 8366.121557236402, 5723.914141287967, 5873.647581145145, 5905.173275741314, 6037.69811509414, 6042.489377655586, 9942.703572160053, 10217.897821021992, 10301.639442619473, 10305.0, 10306.0, 10427.270091093855, 10487.528061237415, 10492.842607360742, 10501.369917804956, 10521.210909181995, 6228.906566401598, 6886.449084073362, 7146.249643787328, 7313.268673132644, 7628.275313845176, 8372.958135209285, 8686.869696954676, 8706.085860984758, 8717.997430295598, 8735.344849136212, 7316.195218526064, 7418.777436676908, 7572.507786993828, 7649.808754781175, 7679.040119985002, 6313.210848643919, 7319.670614822239, 7387.704491820267, 7444.0, 7510.699572017058, 6960.23437421873, 6979.651017449006, 7091.078159839188, 7100.005999160018, 7104.609246491286, 4975.0796102721115, 5022.472640372855, 5045.268436076675, 5083.135866902561, 5165.870853228699, 5033.723145227048, 5037.924431133462, 5171.81898633546, 5198.350206224222, 5264.10510213257], "value": 4530.460401345397, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.08468340926987, 303.1889695065699, 309.86211136044454, 316.8130802826332, 317.3035187762861, 4409.536998615139, 5318.867028324294, 5563.36021357544, 5641.887162256752, 5714.342820594818, 4833.419989601252, 5095.90438055818, 5249.632525723201, 5376.677399356041, 5507.347795661296, 4181.64456021237, 4198.501891374627, 4210.7473551587, 4223.0, 4227.112306415656, 3313.519539666747, 3656.0, 4004.4771793769883, 4249.936250956238, 4265.146970605876, 3265.8530366133464, 3765.359888818907, 4189.79051047448, 4192.098698779761, 4208.263553878074, 4182.602652747984, 4190.685698572604, 4203.3064544350145, 4209.705320627558, 4218.198542276958, 3202.327511222646, 3505.5968563615, 3644.5626524816926, 3653.6529029742132, 3669.5779985301765, 4562.247229207177, 5067.0879241736475, 5326.067938110815, 5345.198220266952, 5368.91946620801, 4537.886552836172, 4696.624270058387, 4729.243321068647, 4835.806567737286, 4890.3642526471685, 4742.478327383985, 4843.892906585646, 5605.990921634104, 5844.824655260349, 5873.412658734135, 3671.963280367189, 3943.802809859524, 3946.112124771932, 3980.7213495055357, 4009.1981603679383, 6164.383561643845, 6193.411625895533, 6219.595726277777, 6283.800669765591, 6291.370862913718, 3686.7833614907017, 3746.4005759078964, 3992.682414803108, 4021.8592349267633, 4034.5965403460004, 3233.935321293561, 3490.2950213584677, 3785.905352366158, 3935.8819235422984, 4066.2477441673386, 3972.7156884790784, 4239.332976974894, 4414.955850441519, 4764.6426518011285, 4773.37946067009, 5443.591730620219, 5913.556483263773, 5919.194645633123, 5978.282606087298, 6025.307089684655, 1134.9773004539863, 1295.7343744532348, 1303.5009738800265, 1334.5662659635657, 1342.3690865293297, 793.7539362797436, 906.8911730592371, 910.6767097680361, 927.8144371125802, 927.9489628070521, 5441.483059109379, 5474.452554744494, 5478.890422191534, 5486.780528778804, 5489.1217405214975, 5360.847417805113, 5833.620814647034, 6014.578979471395, 6023.578349515492, 6042.3655516170165, 4112.506499220112, 4706.176419126639, 4827.493113223131, 5473.139132694902, 5576.832695019156, 2480.5860659423956, 2919.554820363891, 3161.873525058972, 3223.8710451581674, 3231.1760501072076, 5659.490645841894, 5811.709414529257, 6371.0, 6408.359164083555, 6422.848345804208, 5303.7878484860175, 5516.200150978086, 5616.522595579421, 5940.495057920039, 6046.213992181077, 6277.964135917522, 6372.789170057674, 6432.4532414744335, 6456.451201647818, 6462.806115816533, 5756.8198519302605, 5894.440028197314, 5899.46904778572, 6016.939830601638, 6102.328743838263, 5309.601779866448, 5933.3769954154195, 6017.518598512021, 6021.7591296347655, 6088.4215999479975, 5081.33942587476, 5860.706964651836, 6296.174109508192, 6390.7763228286785, 6459.741610335534, 3199.4880819068835, 3961.771850726284, 3980.283548961215, 3982.26328129297, 4001.959980400275, 5138.948610513997, 5884.911726324023, 6066.696665166813, 6095.817125486243, 6104.603200791934, 5129.0, 5159.974200128975, 5367.812126575551, 5690.179142674305, 5932.7033648318275, 5458.0, 5963.254593175853, 5974.312954010345, 5999.730012149551, 6100.6339619621185, 5111.565516930954, 5302.425514719627, 5635.6618602883955, 5799.420057994167, 5929.792457263975, 4398.318260669601, 5797.8550536236935, 5978.850528736642, 6065.7573697053385, 6098.32918378969, 5557.388687244319, 5837.241158649348, 5848.619839710405, 5908.350081490947, 5948.4943779779205], "value": 4222.827061991843, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.3107206905685, 754.6226886556722, 795.1730200591385, 797.780610332159, 845.4758050008995, 15261.465848695298, 15830.287637056317, 15985.520434386957, 16040.83959160408, 16051.277692503836, 14456.92771536141, 14583.92708036461, 15271.541853744378, 15772.526824195264, 15887.44393946212, 816.6937398475557, 839.9454035487673, 849.7875531117221, 851.1871163039314, 852.9019162796296, 674.7469698862926, 693.0851276315266, 794.7099308752292, 819.5205804604284, 841.6002398860522, 856.1267507142711, 865.5585651317841, 865.8311629232302, 886.0120965123873, 887.1926546842375, 880.7357792662208, 881.1584936385736, 882.1046637662778, 882.1178821178789, 882.7042940614901, 647.1554621219307, 652.4095693397483, 652.8008957268031, 652.8204743695475, 653.493542504558, 13873.653158671013, 15270.694586108326, 15370.846291537053, 15461.994970326892, 15480.690386192213, 13871.0, 13929.86070139306, 14028.228447435387, 14230.928845355811, 14345.211013394259, 11884.227525210834, 11926.165168438212, 12026.51893924242, 12044.39778011096, 12104.677281491648, 3736.9439458408146, 3751.643593858579, 3780.810959452044, 3794.829232684508, 3799.202167544819, 86845.69731454033, 92852.53573732155, 111584.0, 111931.44034279858, 113732.43133784278, 10739.087177589836, 10748.838767418494, 10792.406417647106, 10805.081568066802, 11054.889451105386, 21561.460963475725, 21878.89060554687, 21892.233771818228, 21894.78105218959, 21910.780892191193, 7195.964020179866, 7200.963995179991, 7265.164506081868, 7347.926520734831, 7428.294312040348, 3452.946851210355, 4292.356146577976, 4405.295152775605, 4445.666575006888, 4490.101979604092, 3636.127329440915, 3702.6852717519314, 3732.925341493155, 3763.040424691686, 3767.227718317793, 543.9129739241703, 659.5119611487464, 675.1358261425286, 683.9965800171067, 695.3359541637724, 624.4411251929498, 642.8264368620539, 647.2879832184653, 650.890232154176, 671.768239957214, 6864.759733409307, 8256.422050456531, 8558.786030349167, 8577.571121443903, 9287.860682089775, 548.8957098151338, 601.6769511063026, 607.8784243151388, 668.0947316386324, 685.1983179679787, 3962.9207415852097, 4409.647228221735, 4534.88662783433, 4592.311153327028, 4607.677462577587, 154186.2290688562, 160957.3904260966, 162210.37789622188, 164434.3556564443, 164964.0, 132439.0, 134495.32752336175, 135406.32296838454, 136652.0, 138311.30844345715, 69884.30115698879, 70555.6472217646, 100222.0, 101762.0, 103403.0, 9500.144986951207, 9807.362521436084, 9837.114659680663, 10061.647842325483, 10086.495675216356, 42546.0, 48461.0, 61362.693186535565, 63502.36497634964, 64428.35571644224, 63176.0, 77346.6132669333, 88491.0, 92768.72312768939, 99817.0, 12723.745525089262, 12754.0, 13216.801747973595, 13417.798733019208, 13553.525626603021, 14633.34149963233, 14692.926535367254, 15074.6985060296, 15096.54710358647, 15337.079775213522, 13625.523106691218, 14021.088629239066, 14092.788608171091, 14224.431022758974, 14279.786218171157, 13270.0, 13412.865871341552, 13771.173729576258, 14198.29008549589, 14231.786523201954, 13177.868221317663, 13608.183508989492, 13702.38339274755, 13877.79183312231, 14194.0, 11167.44162791841, 11468.4265786712, 11500.827487587525, 11595.884041159818, 11869.287842729464, 6770.966145169243, 10490.435338094552, 10556.630517932143, 11093.16801239894, 11312.717182070184], "value": 7715.145906179291, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 119.0, 117.0, 118.0, 118.0, 116.0, 119.0, 116.0, 114.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 116.0, 163.0, 143.0, 131.0, 117.0, 115.0, 177.0, 147.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 205.0, 203.0, 239.0, 199.0, 197.0, 194.0, 197.0, 196.0, 201.0], "value": 202.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 60.0, 60.0, 85.0, 59.0, 59.0, 59.0, 57.0, 79.0, 59.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 85.0, 105.0, 98.0, 81.0, 90.0, 71.0, 71.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 136.0, 134.0, 91.0, 87.0, 93.0, 88.0, 91.0, 105.0], "value": 100.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 101.0, 97.0, 98.0, 97.0, 97.0, 98.0, 97.0, 100.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 148.0, 93.0, 141.0, 150.0, 115.0, 91.0, 92.0, 96.0, 90.0], "value": 109.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 57.0, 58.0, 59.0, 57.0, 57.0, 56.0, 58.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 77.0, 79.0, 77.0, 87.0, 62.0, 61.0, 89.0, 106.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 94.0, 90.0, 90.0, 86.0, 87.0, 92.0, 93.0, 88.0, 106.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 143.0, 155.0, 158.0, 165.0, 163.0, 148.0, 154.0, 197.0, 171.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 177.0, 176.0, 174.0, 177.0, 173.0, 177.0, 176.0, 178.0, 233.0], "value": 181.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 72.0, 69.0, 70.0, 71.0, 72.0, 69.0, 71.0, 70.0], "value": 70.7, "unit": "score"}]}]} 13:01:07 INFO - Return code: 0 13:01:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:01:07 ERROR - # TBPL SUCCESS # 13:01:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:01:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:01:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:01:07 INFO - 2016-10-06 13:01:07.117 screenresolution[1668:10390] starting screenresolution argv=screenresolution get 13:01:07 INFO - 2016-10-06 13:01:07.119 screenresolution[1668:10390] Display 0: 1600x1200x32@60 13:01:07 INFO - 2016-10-06 13:01:07.125 screenresolution[1669:10393] starting screenresolution argv=screenresolution list 13:01:07 INFO - Available Modes on Display 0 13:01:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:01:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:01:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:01:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:01:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:01:07 INFO - Intel Iris: 13:01:07 INFO - Chipset Model: Intel Iris 13:01:07 INFO - Type: GPU 13:01:07 INFO - Bus: Built-In 13:01:07 INFO - VRAM (Dynamic, Max): 1536 MB 13:01:07 INFO - Vendor: Intel (0x8086) 13:01:07 INFO - Device ID: 0x0a2e 13:01:07 INFO - Revision ID: 0x0009 13:01:07 INFO - Displays: 13:01:07 INFO - Display: 13:01:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:01:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:01:07 INFO - Display Serial Number: 1600x1200 60 13:01:07 INFO - Main Display: Yes 13:01:07 INFO - Mirror: Off 13:01:07 INFO - Online: Yes 13:01:07 INFO - Rotation: Supported 13:01:07 INFO - Return code: 0 13:01:07 INFO - Running post-action listener: _resource_record_post_action 13:01:07 INFO - [mozharness: 2016-10-06 20:01:07.363521Z] Finished run-tests step (success) 13:01:07 INFO - Running post-run listener: _resource_record_post_run 13:01:07 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38177280; Write bytes: 710286336; Read time: 243; Write time: 2027 13:01:07 INFO - TinderboxPrint: CPU usage
54.4% 13:01:07 INFO - TinderboxPrint: I/O read bytes / time
38,177,280 / 243 13:01:07 INFO - TinderboxPrint: I/O write bytes / time
710,286,336 / 2,027 13:01:07 INFO - TinderboxPrint: CPU idle
2,210.9 (45.6%) 13:01:07 INFO - TinderboxPrint: CPU system
978.2 (20.2%) 13:01:07 INFO - TinderboxPrint: CPU user
1,660.6 (34.2%) 13:01:07 INFO - TinderboxPrint: Swap in / out
446,046,208 / 0 13:01:07 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 414050816; Write bytes: 411932672; Read time: 30811; Write time: 856 13:01:07 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29864448; Write bytes: 296748032; Read time: 175; Write time: 1163 13:01:07 INFO - Running post-run listener: _upload_blobber_files 13:01:07 INFO - Blob upload gear active. 13:01:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:01:07 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 13:01:07 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:01:07 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:01:08 INFO - (blobuploader) - INFO - Open directory for files ... 13:01:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:01:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:01:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:01:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:01:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:01:09 INFO - (blobuploader) - INFO - Done attempting. 13:01:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:01:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:01:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:01:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:01:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:01:10 INFO - (blobuploader) - INFO - Done attempting. 13:01:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:01:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:01:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:01:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:01:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:01:13 INFO - (blobuploader) - INFO - Done attempting. 13:01:13 INFO - (blobuploader) - INFO - Iteration through files over. 13:01:13 INFO - Return code: 0 13:01:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:01:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:01:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32be246695ee67931589de7fa8e0261af1fda26013d70c1cee419322a345c57925aafbbf93270701cf01fa3cf1ae6943f338a52398b074d60adf37d48607e85b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/780f3043ae9d9e59b506547ce5216819238d94e58de35b35d21dc6e01a6904e56f6a94abc122782f55bad3a7ab9daa5424b4f0851e8476b944d1f84dd52b0805", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f490e09c4812e57311d24a465343c0219346d103e97afe6db07f7e025453b88f0583f684bbb0b803c26b8dda4ca021507623b1a45e65798626562685ae42e86"} 13:01:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:01:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:01:13 INFO - Contents: 13:01:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32be246695ee67931589de7fa8e0261af1fda26013d70c1cee419322a345c57925aafbbf93270701cf01fa3cf1ae6943f338a52398b074d60adf37d48607e85b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/780f3043ae9d9e59b506547ce5216819238d94e58de35b35d21dc6e01a6904e56f6a94abc122782f55bad3a7ab9daa5424b4f0851e8476b944d1f84dd52b0805", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f490e09c4812e57311d24a465343c0219346d103e97afe6db07f7e025453b88f0583f684bbb0b803c26b8dda4ca021507623b1a45e65798626562685ae42e86"} 13:01:13 INFO - Running post-run listener: copy_logs_to_upload_dir 13:01:13 INFO - Copying logs to upload dir... 13:01:13 INFO - mkdir: /builds/slave/test/build/upload/logs 13:01:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.927632 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-06 13:01:13.753343) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 13:01:13.760390) ========= 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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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/Fx-Team/sha512/32be246695ee67931589de7fa8e0261af1fda26013d70c1cee419322a345c57925aafbbf93270701cf01fa3cf1ae6943f338a52398b074d60adf37d48607e85b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/780f3043ae9d9e59b506547ce5216819238d94e58de35b35d21dc6e01a6904e56f6a94abc122782f55bad3a7ab9daa5424b4f0851e8476b944d1f84dd52b0805", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f490e09c4812e57311d24a465343c0219346d103e97afe6db07f7e025453b88f0583f684bbb0b803c26b8dda4ca021507623b1a45e65798626562685ae42e86"} build_url:https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011064 build_url: 'https://queue.taskcluster.net/v1/task/HEeQcmDMQ2C7IspfRVN1gA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/32be246695ee67931589de7fa8e0261af1fda26013d70c1cee419322a345c57925aafbbf93270701cf01fa3cf1ae6943f338a52398b074d60adf37d48607e85b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/780f3043ae9d9e59b506547ce5216819238d94e58de35b35d21dc6e01a6904e56f6a94abc122782f55bad3a7ab9daa5424b4f0851e8476b944d1f84dd52b0805", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0f490e09c4812e57311d24a465343c0219346d103e97afe6db07f7e025453b88f0583f684bbb0b803c26b8dda4ca021507623b1a45e65798626562685ae42e86"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 13:01:13.795629) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 13:01:13.795952) ========= 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.ijHgTd43ts/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.Q2kWNOTpMj/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.005020 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 13:01:13.844854) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 13:01:13.848194) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 13:01:13.848524) ========= ========= Total master_lag: 7.27 =========